[19:27:26.199] New invocation is queued and will start shortly
[19:27:27.469] Starting the invocation (attempt 1)
[19:27:27.502] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:27:27.502] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:27:27.502] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9068740163438451232",
"parameters_json": "{\"builder_name\":\"Site Isolation Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"3b0f7b92d3fafd46eec40316eb9bf26f36ad3a4c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Site Isolation Android",
"scheduler_invocation_id:9068740163438451232",
"scheduler_job_id:chromium/Site Isolation Android",
"user_agent:luci-scheduler",
"buildset:commit/git/3b0f7b92d3fafd46eec40316eb9bf26f36ad3a4c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3b0f7b92d3fafd46eec40316eb9bf26f36ad3a4c",
"gitiles_ref:refs/heads/master"
]
}
[19:27:29.420] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1567538847940687",
"id": "8903310100941056224",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3b0f7b92d3fafd46eec40316eb9bf26f36ad3a4c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1567538849003360",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/36684",
"builder:Site Isolation Android",
"buildset:commit/git/3b0f7b92d3fafd46eec40316eb9bf26f36ad3a4c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3b0f7b92d3fafd46eec40316eb9bf26f36ad3a4c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9068740163438451232",
"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/8903310100941056224/+/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": "1567538849003539",
"url": "https://ci.chromium.org/b/8903310100941056224",
"utcnow_ts": "1567538849376928"
}
}
[19:27:29.420] Task URL: https://ci.chromium.org/b/8903310100941056224
[19:27:29.420] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:2:0) after 1m0s
[19:28:15.812] Received PubSub notification, asking Buildbucket for the build status
[19:28:17.441] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:29.676] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:2:0)
[19:28:29.676] Timer tick, asking Buildbucket for the build status
[19:28:29.899] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:29.899] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:4:0) after 1m0s
[19:29:30.147] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:4:0)
[19:29:30.147] Timer tick, asking Buildbucket for the build status
[19:29:31.234] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:31.234] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:5:0) after 1m0s
[19:30:31.347] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:5:0)
[19:30:31.347] Timer tick, asking Buildbucket for the build status
[19:30:31.741] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:31.741] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:6:0) after 1m0s
[19:31:31.775] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:6:0)
[19:31:31.775] Timer tick, asking Buildbucket for the build status
[19:31:33.799] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:33.799] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:7:0) after 1m0s
[19:32:33.858] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:7:0)
[19:32:33.858] Timer tick, asking Buildbucket for the build status
[19:32:34.379] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:34.379] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:8:0) after 1m0s
[19:33:34.381] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:8:0)
[19:33:34.381] Timer tick, asking Buildbucket for the build status
[19:33:34.620] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:34.620] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:9:0) after 1m0s
[19:34:34.716] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:9:0)
[19:34:34.716] Timer tick, asking Buildbucket for the build status
[19:34:35.058] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:35.058] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:10:0) after 1m0s
[19:35:35.166] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:10:0)
[19:35:35.166] Timer tick, asking Buildbucket for the build status
[19:35:35.437] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:35.437] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:11:0) after 1m0s
[19:36:35.568] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:11:0)
[19:36:35.568] Timer tick, asking Buildbucket for the build status
[19:36:35.926] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:35.926] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:12:0) after 1m0s
[19:37:36.005] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:12:0)
[19:37:36.005] Timer tick, asking Buildbucket for the build status
[19:37:36.231] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:36.231] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:13:0) after 1m0s
[19:38:36.266] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:13:0)
[19:38:36.266] Timer tick, asking Buildbucket for the build status
[19:38:37.134] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:37.134] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:14:0) after 1m0s
[19:39:37.168] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:14:0)
[19:39:37.168] Timer tick, asking Buildbucket for the build status
[19:39:37.348] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:37.348] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:15:0) after 1m0s
[19:40:37.416] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:15:0)
[19:40:37.416] Timer tick, asking Buildbucket for the build status
[19:40:38.421] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:38.421] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:16:0) after 1m0s
[19:41:38.435] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:16:0)
[19:41:38.435] Timer tick, asking Buildbucket for the build status
[19:41:38.704] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:38.704] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:17:0) after 1m0s
[19:42:39.046] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:17:0)
[19:42:39.046] Timer tick, asking Buildbucket for the build status
[19:42:39.428] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:39.428] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:18:0) after 1m0s
[19:43:39.484] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:18:0)
[19:43:39.484] Timer tick, asking Buildbucket for the build status
[19:43:39.744] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:39.744] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:19:0) after 1m0s
[19:44:39.902] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:19:0)
[19:44:39.902] Timer tick, asking Buildbucket for the build status
[19:44:40.505] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:40.505] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:20:0) after 1m0s
[19:45:40.652] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:20:0)
[19:45:40.652] Timer tick, asking Buildbucket for the build status
[19:45:40.846] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:40.846] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:21:0) after 1m0s
[19:46:41.257] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:21:0)
[19:46:41.257] Timer tick, asking Buildbucket for the build status
[19:46:41.574] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:41.575] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:22:0) after 1m0s
[19:47:41.699] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:22:0)
[19:47:41.699] Timer tick, asking Buildbucket for the build status
[19:47:42.385] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:42.385] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:23:0) after 1m0s
[19:48:42.732] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:23:0)
[19:48:42.732] Timer tick, asking Buildbucket for the build status
[19:48:43.353] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:43.353] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:24:0) after 1m0s
[19:49:43.503] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:24:0)
[19:49:43.503] Timer tick, asking Buildbucket for the build status
[19:49:44.321] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:44.321] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:25:0) after 1m0s
[19:50:44.310] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:25:0)
[19:50:44.310] Timer tick, asking Buildbucket for the build status
[19:50:44.485] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:44.485] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:26:0) after 1m0s
[19:51:44.506] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:26:0)
[19:51:44.506] Timer tick, asking Buildbucket for the build status
[19:51:45.182] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:45.182] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:27:0) after 1m0s
[19:52:45.211] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:27:0)
[19:52:45.211] Timer tick, asking Buildbucket for the build status
[19:52:45.694] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:45.694] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:28:0) after 1m0s
[19:53:45.783] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:28:0)
[19:53:45.783] Timer tick, asking Buildbucket for the build status
[19:53:47.635] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:47.635] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:29:0) after 1m0s
[19:54:47.807] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:29:0)
[19:54:47.807] Timer tick, asking Buildbucket for the build status
[19:54:48.595] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:48.595] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:30:0) after 1m0s
[19:55:48.602] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:30:0)
[19:55:48.602] Timer tick, asking Buildbucket for the build status
[19:55:48.969] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:48.969] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:31:0) after 1m0s
[19:56:48.992] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:31:0)
[19:56:48.992] Timer tick, asking Buildbucket for the build status
[19:56:49.515] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:49.515] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:32:0) after 1m0s
[19:57:49.589] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:32:0)
[19:57:49.589] Timer tick, asking Buildbucket for the build status
[19:57:49.743] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:49.743] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:33:0) after 1m0s
[19:58:49.799] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:33:0)
[19:58:49.799] Timer tick, asking Buildbucket for the build status
[19:58:50.334] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:50.334] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:34:0) after 1m0s
[19:59:50.453] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:34:0)
[19:59:50.453] Timer tick, asking Buildbucket for the build status
[19:59:50.651] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:50.651] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:35:0) after 1m0s
[20:00:50.800] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:35:0)
[20:00:50.800] Timer tick, asking Buildbucket for the build status
[20:00:50.985] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:50.985] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:36:0) after 1m0s
[20:01:51.100] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:36:0)
[20:01:51.100] Timer tick, asking Buildbucket for the build status
[20:01:51.409] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:51.409] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:37:0) after 1m0s
[20:02:51.425] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:37:0)
[20:02:51.425] Timer tick, asking Buildbucket for the build status
[20:02:52.544] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:52.544] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:38:0) after 1m0s
[20:03:52.620] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:38:0)
[20:03:52.620] Timer tick, asking Buildbucket for the build status
[20:03:52.822] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:52.822] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:39:0) after 1m0s
[20:04:52.911] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:39:0)
[20:04:52.911] Timer tick, asking Buildbucket for the build status
[20:04:53.458] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:53.458] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:40:0) after 1m0s
[20:05:53.533] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:40:0)
[20:05:53.533] Timer tick, asking Buildbucket for the build status
[20:05:54.032] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:54.032] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:41:0) after 1m0s
[20:06:54.067] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:41:0)
[20:06:54.067] Timer tick, asking Buildbucket for the build status
[20:06:54.268] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:54.268] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:42:0) after 1m0s
[20:07:54.466] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:42:0)
[20:07:54.466] Timer tick, asking Buildbucket for the build status
[20:07:54.832] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:54.832] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:43:0) after 1m0s
[20:08:54.939] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:43:0)
[20:08:54.939] Timer tick, asking Buildbucket for the build status
[20:08:55.775] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:55.775] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:44:0) after 1m0s
[20:09:55.977] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:44:0)
[20:09:55.977] Timer tick, asking Buildbucket for the build status
[20:09:56.451] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:56.451] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:45:0) after 1m0s
[20:10:56.557] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:45:0)
[20:10:56.557] Timer tick, asking Buildbucket for the build status
[20:10:57.307] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:57.307] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:46:0) after 1m0s
[20:11:57.358] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:46:0)
[20:11:57.358] Timer tick, asking Buildbucket for the build status
[20:11:58.133] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:58.133] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:47:0) after 1m0s
[20:12:58.219] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:47:0)
[20:12:58.219] Timer tick, asking Buildbucket for the build status
[20:12:58.706] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:58.706] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:48:0) after 1m0s
[20:13:58.807] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:48:0)
[20:13:58.807] Timer tick, asking Buildbucket for the build status
[20:13:59.319] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:59.319] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:49:0) after 1m0s
[20:14:59.383] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:49:0)
[20:14:59.383] Timer tick, asking Buildbucket for the build status
[20:14:59.661] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:59.661] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:50:0) after 1m0s
[20:15:59.711] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:50:0)
[20:15:59.711] Timer tick, asking Buildbucket for the build status
[20:16:00.378] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:00.378] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:51:0) after 1m0s
[20:17:00.421] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:51:0)
[20:17:00.421] Timer tick, asking Buildbucket for the build status
[20:17:00.850] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:00.850] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:52:0) after 1m0s
[20:18:00.879] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:52:0)
[20:18:00.879] Timer tick, asking Buildbucket for the build status
[20:18:01.800] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:01.800] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:53:0) after 1m0s
[20:19:01.865] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:53:0)
[20:19:01.865] Timer tick, asking Buildbucket for the build status
[20:19:02.183] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:02.183] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:54:0) after 1m0s
[20:20:02.239] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:54:0)
[20:20:02.239] Timer tick, asking Buildbucket for the build status
[20:20:02.458] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:02.458] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:55:0) after 1m0s
[20:21:02.495] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:55:0)
[20:21:02.495] Timer tick, asking Buildbucket for the build status
[20:21:03.583] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:03.584] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:56:0) after 1m0s
[20:22:03.631] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:56:0)
[20:22:03.631] Timer tick, asking Buildbucket for the build status
[20:22:04.586] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:04.586] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:57:0) after 1m0s
[20:23:04.668] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:57:0)
[20:23:04.668] Timer tick, asking Buildbucket for the build status
[20:23:04.900] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:04.900] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:58:0) after 1m0s
[20:24:04.928] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:58:0)
[20:24:04.928] Timer tick, asking Buildbucket for the build status
[20:24:05.457] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:05.457] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:59:0) after 1m0s
[20:25:05.491] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:59:0)
[20:25:05.491] Timer tick, asking Buildbucket for the build status
[20:25:05.704] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:05.704] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:60:0) after 1m0s
[20:26:05.776] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:60:0)
[20:26:05.776] Timer tick, asking Buildbucket for the build status
[20:26:06.575] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:06.575] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:61:0) after 1m0s
[20:27:06.641] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:61:0)
[20:27:06.641] Timer tick, asking Buildbucket for the build status
[20:27:06.862] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:06.862] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:62:0) after 1m0s
[20:28:06.858] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:62:0)
[20:28:06.858] Timer tick, asking Buildbucket for the build status
[20:28:07.506] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:07.506] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:63:0) after 1m0s
[20:29:07.555] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:63:0)
[20:29:07.555] Timer tick, asking Buildbucket for the build status
[20:29:08.114] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:08.114] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:64:0) after 1m0s
[20:30:08.153] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:64:0)
[20:30:08.153] Timer tick, asking Buildbucket for the build status
[20:30:08.347] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:08.347] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:65:0) after 1m0s
[20:31:08.419] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:65:0)
[20:31:08.419] Timer tick, asking Buildbucket for the build status
[20:31:08.717] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:08.717] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:66:0) after 1m0s
[20:32:08.829] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:66:0)
[20:32:08.829] Timer tick, asking Buildbucket for the build status
[20:32:10.721] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:10.721] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:67:0) after 1m0s
[20:33:10.766] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:67:0)
[20:33:10.766] Timer tick, asking Buildbucket for the build status
[20:33:11.402] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:11.402] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:68:0) after 1m0s
[20:34:11.429] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:68:0)
[20:34:11.429] Timer tick, asking Buildbucket for the build status
[20:34:11.841] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:11.841] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:69:0) after 1m0s
[20:35:11.932] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:69:0)
[20:35:11.932] Timer tick, asking Buildbucket for the build status
[20:35:12.724] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:12.724] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:70:0) after 1m0s
[20:36:12.773] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:70:0)
[20:36:12.773] Timer tick, asking Buildbucket for the build status
[20:36:13.206] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:13.206] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:71:0) after 1m0s
[20:37:13.366] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:71:0)
[20:37:13.366] Timer tick, asking Buildbucket for the build status
[20:37:13.647] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:13.647] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:72:0) after 1m0s
[20:38:13.721] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:72:0)
[20:38:13.721] Timer tick, asking Buildbucket for the build status
[20:38:14.761] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:14.761] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:73:0) after 1m0s
[20:39:14.740] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:73:0)
[20:39:14.740] Timer tick, asking Buildbucket for the build status
[20:39:14.946] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:14.946] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:74:0) after 1m0s
[20:40:14.981] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:74:0)
[20:40:14.981] Timer tick, asking Buildbucket for the build status
[20:40:15.475] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:15.475] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:75:0) after 1m0s
[20:41:15.647] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:75:0)
[20:41:15.647] Timer tick, asking Buildbucket for the build status
[20:41:17.024] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:17.024] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:76:0) after 1m0s
[20:42:17.082] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:76:0)
[20:42:17.082] Timer tick, asking Buildbucket for the build status
[20:42:17.789] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:17.790] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:77:0) after 1m0s
[20:43:18.292] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:77:0)
[20:43:18.292] Timer tick, asking Buildbucket for the build status
[20:43:18.624] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:18.624] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:78:0) after 1m0s
[20:44:19.426] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:78:0)
[20:44:19.426] Timer tick, asking Buildbucket for the build status
[20:44:21.324] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:21.324] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:79:0) after 1m0s
[20:45:21.390] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:79:0)
[20:45:21.390] Timer tick, asking Buildbucket for the build status
[20:45:21.747] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:21.747] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:80:0) after 1m0s
[20:46:21.874] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:80:0)
[20:46:21.874] Timer tick, asking Buildbucket for the build status
[20:46:22.114] Build 8903310100941056224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:22.114] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9068740163438451232:81:0) after 1m0s
[20:46:27.674] Received PubSub notification, asking Buildbucket for the build status
[20:46:28.119] Build 8903310100941056224: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[20:46:28.119] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1567543587267462",
"created_by": "project:chromium",
"created_ts": "1567538847940687",
"failure_reason": "BUILD_FAILURE",
"id": "8903310100941056224",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3b0f7b92d3fafd46eec40316eb9bf26f36ad3a4c\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**site_per_process_unit_tests on Android device Nexus 5X** failed because of:\\n\\n- OfflinePageUtilsTest.TestGetCachedOfflinePageSizeAllPagesInRange\"}, \"properties\": {\"$build/goma\": {\"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-119-afz1\", \"branch\": \"refs/heads/master\", \"buildername\": \"Site Isolation Android\", \"buildnumber\": 36684, \"got_angle_revision\": \"4376600310936a326d7fbefea686e92c39d6994b\", \"got_dawn_revision\": \"c7910d19fe50116c74511567b67656beb8f53716\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"3b0f7b92d3fafd46eec40316eb9bf26f36ad3a4c\", \"got_revision_cp\": \"refs/heads/master@{#692777}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"23c7a63ca141f53efe4d2982fb9abf767837a7c3\", \"got_v8_revision_cp\": \"refs/heads/7.8.263@{#1}\", \"got_webrtc_revision\": \"1aa7e2fa2d6de586253d93575b2d3b6ae70cb056\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29046}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3b0f7b92d3fafd46eec40316eb9bf26f36ad3a4c\", \"swarm_hashes\": {\"components_browsertests\": \"b41b822293e1751e66e68cb84d5d86696e258d6f\", \"components_unittests\": \"3eb8edae72c9946c21a7d33ba7208316b529e493\", \"content_browsertests\": \"d21b5a34f35123797ba15c10dc6c7b4d83a4e497\", \"content_unittests\": \"340ea2e9a9474e2dd4d8d9b9855a16848ca7eb12\", \"unit_tests\": \"02da98e5687bdd3f606f0729fcc9178fce0d32ed\"}, \"swarm_hashes_refs/heads/master(at){#692777}_without_patch\": {\"components_browsertests\": \"b41b822293e1751e66e68cb84d5d86696e258d6f\", \"components_unittests\": \"3eb8edae72c9946c21a7d33ba7208316b529e493\", \"content_browsertests\": \"d21b5a34f35123797ba15c10dc6c7b4d83a4e497\", \"content_unittests\": \"340ea2e9a9474e2dd4d8d9b9855a16848ca7eb12\", \"unit_tests\": \"02da98e5687bdd3f606f0729fcc9178fce0d32ed\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2fce50e69d80f7d3cf1b01087d63cb5fa68f8d968b73601af44a2f0cc34bc410_v2\", \"builder_6e17dc7623fba43c9632527d18d71e4843b504e37694b559b7b91daecd01d18c_v2\", \"builder_8e7ad9a10a7772feba5b42dda56992e918fe88f23579c3ddaf696dde66ee4238_v2\", \"builder_a432d290b72bc0f3cae401fbd647c33c14340f64e89770e9c9c39d6230495526_v2\", \"builder_e961d9d7e9bca679490a4aaa8599bd975f9b7e0b713a988ed6895a059d04d2ac_v2\", \"builder_f5b4454e99a04aaa5ba743b59cc70de19e20bebedef65e50e3b90534a8d6a5e4_v2\", \"builder_fdb8dca0f90a2608c4f778e5f29672efafa0e8947deee24935132ecfe3180a21_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-119-afz1\"], \"image\": [\"chrome-xenial-19052201-9cb74617499\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4397-8786906\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**site_per_process_unit_tests on Android device Nexus 5X** failed because of:\\n\\n- OfflinePageUtilsTest.TestGetCachedOfflinePageSizeAllPagesInRange\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1567538892749159",
"status": "COMPLETED",
"status_changed_ts": "1567538894537955",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/36684",
"builder:Site Isolation Android",
"buildset:commit/git/3b0f7b92d3fafd46eec40316eb9bf26f36ad3a4c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3b0f7b92d3fafd46eec40316eb9bf26f36ad3a4c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9068740163438451232",
"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/8903310100941056224/+/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:47116a3c41e66610",
"user_agent:luci-scheduler"
],
"updated_ts": "1567543587409800",
"url": "https://ci.chromium.org/b/8903310100941056224",
"utcnow_ts": "1567543588093930"
}
}
[20:46:28.119] Invocation finished in 1h19m1.944716331s with status FAILED