[22:40:50.246] New invocation is queued and will start shortly
[22:40:51.323] Starting the invocation (attempt 1)
[22:40:51.364] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:40:51.364] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:40:51.364] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9069362174469801488",
"parameters_json": "{\"builder_name\":\"Site Isolation Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"4aecba8b4d2b08bbf257ed7b90c482308fae24b1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Site Isolation Android",
"scheduler_invocation_id:9069362174469801488",
"scheduler_job_id:chromium/Site Isolation Android",
"user_agent:luci-scheduler",
"buildset:commit/git/4aecba8b4d2b08bbf257ed7b90c482308fae24b1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4aecba8b4d2b08bbf257ed7b90c482308fae24b1",
"gitiles_ref:refs/heads/master"
]
}
[22:40:53.464] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1566945652215913",
"id": "8903932111740819648",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"4aecba8b4d2b08bbf257ed7b90c482308fae24b1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1566945653229651",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/36544",
"builder:Site Isolation Android",
"buildset:commit/git/4aecba8b4d2b08bbf257ed7b90c482308fae24b1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4aecba8b4d2b08bbf257ed7b90c482308fae24b1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9069362174469801488",
"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/8903932111740819648/+/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": "1566945653229782",
"url": "https://ci.chromium.org/b/8903932111740819648",
"utcnow_ts": "1566945653444911"
}
}
[22:40:53.464] Task URL: https://ci.chromium.org/b/8903932111740819648
[22:40:53.464] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:2:0) after 1m0s
[22:41:13.316] Received PubSub notification, asking Buildbucket for the build status
[22:41:13.476] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:53.536] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:2:0)
[22:41:53.536] Timer tick, asking Buildbucket for the build status
[22:41:54.228] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:54.228] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:4:0) after 1m0s
[22:42:54.308] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:4:0)
[22:42:54.308] Timer tick, asking Buildbucket for the build status
[22:42:56.606] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:56.606] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:5:0) after 1m0s
[22:43:56.645] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:5:0)
[22:43:56.645] Timer tick, asking Buildbucket for the build status
[22:43:57.260] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:57.260] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:6:0) after 1m0s
[22:44:57.300] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:6:0)
[22:44:57.300] Timer tick, asking Buildbucket for the build status
[22:44:57.495] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:57.495] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:7:0) after 1m0s
[22:45:57.587] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:7:0)
[22:45:57.587] Timer tick, asking Buildbucket for the build status
[22:45:57.936] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:57.936] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:8:0) after 1m0s
[22:46:57.964] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:8:0)
[22:46:57.964] Timer tick, asking Buildbucket for the build status
[22:46:58.158] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:58.158] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:9:0) after 1m0s
[22:47:58.239] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:9:0)
[22:47:58.239] Timer tick, asking Buildbucket for the build status
[22:47:58.401] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:58.401] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:10:0) after 1m0s
[22:48:58.440] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:10:0)
[22:48:58.440] Timer tick, asking Buildbucket for the build status
[22:48:58.696] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:58.696] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:11:0) after 1m0s
[22:49:58.753] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:11:0)
[22:49:58.753] Timer tick, asking Buildbucket for the build status
[22:49:59.848] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:59.848] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:12:0) after 1m0s
[22:50:59.925] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:12:0)
[22:50:59.925] Timer tick, asking Buildbucket for the build status
[22:51:00.124] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:00.124] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:13:0) after 1m0s
[22:52:00.165] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:13:0)
[22:52:00.165] Timer tick, asking Buildbucket for the build status
[22:52:01.614] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:01.614] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:14:0) after 1m0s
[22:53:01.631] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:14:0)
[22:53:01.631] Timer tick, asking Buildbucket for the build status
[22:53:02.135] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:02.135] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:15:0) after 1m0s
[22:54:02.136] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:15:0)
[22:54:02.136] Timer tick, asking Buildbucket for the build status
[22:54:03.124] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:03.124] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:16:0) after 1m0s
[22:55:03.156] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:16:0)
[22:55:03.156] Timer tick, asking Buildbucket for the build status
[22:55:03.618] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:03.618] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:17:0) after 1m0s
[22:56:03.658] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:17:0)
[22:56:03.658] Timer tick, asking Buildbucket for the build status
[22:56:04.256] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:04.256] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:18:0) after 1m0s
[22:57:04.294] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:18:0)
[22:57:04.294] Timer tick, asking Buildbucket for the build status
[22:57:05.055] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:05.055] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:19:0) after 1m0s
[22:58:05.085] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:19:0)
[22:58:05.085] Timer tick, asking Buildbucket for the build status
[22:58:05.291] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:05.291] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:20:0) after 1m0s
[22:59:05.402] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:20:0)
[22:59:05.402] Timer tick, asking Buildbucket for the build status
[22:59:05.841] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:05.841] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:21:0) after 1m0s
[23:00:05.932] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:21:0)
[23:00:05.932] Timer tick, asking Buildbucket for the build status
[23:00:06.193] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:06.193] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:22:0) after 1m0s
[23:01:06.228] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:22:0)
[23:01:06.228] Timer tick, asking Buildbucket for the build status
[23:01:07.106] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:07.106] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:23:0) after 1m0s
[23:02:07.139] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:23:0)
[23:02:07.139] Timer tick, asking Buildbucket for the build status
[23:02:07.607] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:07.607] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:24:0) after 1m0s
[23:03:07.706] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:24:0)
[23:03:07.706] Timer tick, asking Buildbucket for the build status
[23:03:07.902] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:07.902] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:25:0) after 1m0s
[23:04:08.211] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:25:0)
[23:04:08.211] Timer tick, asking Buildbucket for the build status
[23:04:08.906] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:08.906] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:26:0) after 1m0s
[23:05:09.136] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:26:0)
[23:05:09.136] Timer tick, asking Buildbucket for the build status
[23:05:10.992] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:10.992] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:27:0) after 1m0s
[23:06:11.080] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:27:0)
[23:06:11.080] Timer tick, asking Buildbucket for the build status
[23:06:11.332] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:11.332] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:28:0) after 1m0s
[23:07:11.464] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:28:0)
[23:07:11.464] Timer tick, asking Buildbucket for the build status
[23:07:13.175] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:13.175] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:29:0) after 1m0s
[23:08:13.201] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:29:0)
[23:08:13.201] Timer tick, asking Buildbucket for the build status
[23:08:13.716] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:13.716] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:30:0) after 1m0s
[23:09:13.751] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:30:0)
[23:09:13.751] Timer tick, asking Buildbucket for the build status
[23:09:14.931] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:14.931] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:31:0) after 1m0s
[23:10:15.026] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:31:0)
[23:10:15.026] Timer tick, asking Buildbucket for the build status
[23:10:15.285] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:15.285] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:32:0) after 1m0s
[23:11:15.308] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:32:0)
[23:11:15.308] Timer tick, asking Buildbucket for the build status
[23:11:15.533] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:15.533] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:33:0) after 1m0s
[23:12:15.542] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:33:0)
[23:12:15.542] Timer tick, asking Buildbucket for the build status
[23:12:17.650] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:17.650] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:34:0) after 1m0s
[23:13:17.681] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:34:0)
[23:13:17.681] Timer tick, asking Buildbucket for the build status
[23:13:18.893] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:18.893] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:35:0) after 1m0s
[23:14:18.916] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:35:0)
[23:14:18.916] Timer tick, asking Buildbucket for the build status
[23:14:20.629] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:20.629] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:36:0) after 1m0s
[23:15:20.880] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:36:0)
[23:15:20.880] Timer tick, asking Buildbucket for the build status
[23:15:21.561] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:21.561] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:37:0) after 1m0s
[23:16:21.783] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:37:0)
[23:16:21.783] Timer tick, asking Buildbucket for the build status
[23:16:23.015] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:23.015] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:38:0) after 1m0s
[23:17:23.176] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:38:0)
[23:17:23.176] Timer tick, asking Buildbucket for the build status
[23:17:23.693] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:23.693] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:39:0) after 1m0s
[23:18:23.771] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:39:0)
[23:18:23.771] Timer tick, asking Buildbucket for the build status
[23:18:24.801] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:24.801] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:40:0) after 1m0s
[23:19:24.841] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:40:0)
[23:19:24.841] Timer tick, asking Buildbucket for the build status
[23:19:25.071] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:25.071] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:41:0) after 1m0s
[23:20:25.181] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:41:0)
[23:20:25.181] Timer tick, asking Buildbucket for the build status
[23:20:26.048] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:26.048] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:42:0) after 1m0s
[23:21:26.083] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:42:0)
[23:21:26.083] Timer tick, asking Buildbucket for the build status
[23:21:26.341] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:26.341] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:43:0) after 1m0s
[23:22:26.362] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:43:0)
[23:22:26.362] Timer tick, asking Buildbucket for the build status
[23:22:28.342] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:28.342] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:44:0) after 1m0s
[23:23:28.366] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:44:0)
[23:23:28.366] Timer tick, asking Buildbucket for the build status
[23:23:28.596] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:28.596] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:45:0) after 1m0s
[23:24:28.619] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:45:0)
[23:24:28.619] Timer tick, asking Buildbucket for the build status
[23:24:29.251] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:29.251] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:46:0) after 1m0s
[23:25:29.270] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:46:0)
[23:25:29.270] Timer tick, asking Buildbucket for the build status
[23:25:29.794] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:29.794] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:47:0) after 1m0s
[23:26:29.967] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:47:0)
[23:26:29.967] Timer tick, asking Buildbucket for the build status
[23:26:30.423] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:30.423] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:48:0) after 1m0s
[23:27:30.514] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:48:0)
[23:27:30.514] Timer tick, asking Buildbucket for the build status
[23:27:30.828] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:30.828] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:49:0) after 1m0s
[23:28:30.899] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:49:0)
[23:28:30.899] Timer tick, asking Buildbucket for the build status
[23:28:31.145] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:31.145] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:50:0) after 1m0s
[23:29:31.171] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:50:0)
[23:29:31.171] Timer tick, asking Buildbucket for the build status
[23:29:31.396] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:31.396] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:51:0) after 1m0s
[23:30:31.447] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:51:0)
[23:30:31.447] Timer tick, asking Buildbucket for the build status
[23:30:31.978] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:31.978] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:52:0) after 1m0s
[23:31:32.004] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:52:0)
[23:31:32.004] Timer tick, asking Buildbucket for the build status
[23:31:32.171] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:32.171] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:53:0) after 1m0s
[23:32:32.204] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:53:0)
[23:32:32.204] Timer tick, asking Buildbucket for the build status
[23:32:32.410] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:32.410] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:54:0) after 1m0s
[23:33:32.434] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:54:0)
[23:33:32.434] Timer tick, asking Buildbucket for the build status
[23:33:33.551] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:33.551] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:55:0) after 1m0s
[23:34:33.672] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:55:0)
[23:34:33.672] Timer tick, asking Buildbucket for the build status
[23:34:35.152] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:35.152] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:56:0) after 1m0s
[23:35:35.213] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:56:0)
[23:35:35.213] Timer tick, asking Buildbucket for the build status
[23:35:36.369] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:36.369] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:57:0) after 1m0s
[23:36:36.436] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:57:0)
[23:36:36.436] Timer tick, asking Buildbucket for the build status
[23:36:36.968] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:36.968] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:58:0) after 1m0s
[23:37:37.015] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:58:0)
[23:37:37.015] Timer tick, asking Buildbucket for the build status
[23:37:37.654] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:37.654] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:59:0) after 1m0s
[23:38:37.730] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:59:0)
[23:38:37.730] Timer tick, asking Buildbucket for the build status
[23:38:37.933] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:37.933] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:60:0) after 1m0s
[23:39:38.127] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:60:0)
[23:39:38.127] Timer tick, asking Buildbucket for the build status
[23:39:38.360] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:38.360] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:61:0) after 1m0s
[23:40:38.541] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:61:0)
[23:40:38.541] Timer tick, asking Buildbucket for the build status
[23:40:38.826] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:38.826] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:62:0) after 1m0s
[23:41:39.053] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:62:0)
[23:41:39.053] Timer tick, asking Buildbucket for the build status
[23:41:39.741] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:39.741] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:63:0) after 1m0s
[23:42:39.776] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:63:0)
[23:42:39.776] Timer tick, asking Buildbucket for the build status
[23:42:39.978] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:39.978] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:64:0) after 1m0s
[23:43:40.108] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:64:0)
[23:43:40.108] Timer tick, asking Buildbucket for the build status
[23:43:40.982] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:40.982] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:65:0) after 1m0s
[23:44:41.072] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:65:0)
[23:44:41.073] Timer tick, asking Buildbucket for the build status
[23:44:41.350] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:41.350] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:66:0) after 1m0s
[23:45:41.498] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:66:0)
[23:45:41.498] Timer tick, asking Buildbucket for the build status
[23:45:41.789] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:41.789] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:67:0) after 1m0s
[23:46:41.946] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:67:0)
[23:46:41.946] Timer tick, asking Buildbucket for the build status
[23:46:42.290] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:42.290] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:68:0) after 1m0s
[23:47:42.392] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:68:0)
[23:47:42.392] Timer tick, asking Buildbucket for the build status
[23:47:42.751] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:42.751] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:69:0) after 1m0s
[23:48:42.854] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:69:0)
[23:48:42.854] Timer tick, asking Buildbucket for the build status
[23:48:43.111] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:43.111] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:70:0) after 1m0s
[23:49:43.251] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:70:0)
[23:49:43.251] Timer tick, asking Buildbucket for the build status
[23:49:43.752] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:43.752] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:71:0) after 1m0s
[23:50:43.792] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:71:0)
[23:50:43.792] Timer tick, asking Buildbucket for the build status
[23:50:44.492] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:44.493] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:72:0) after 1m0s
[23:51:44.593] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:72:0)
[23:51:44.593] Timer tick, asking Buildbucket for the build status
[23:51:44.831] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:44.831] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:73:0) after 1m0s
[23:52:44.858] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:73:0)
[23:52:44.858] Timer tick, asking Buildbucket for the build status
[23:52:45.053] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:45.053] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:74:0) after 1m0s
[23:53:45.048] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:74:0)
[23:53:45.048] Timer tick, asking Buildbucket for the build status
[23:53:45.218] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:45.218] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:75:0) after 1m0s
[23:54:45.306] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:75:0)
[23:54:45.306] Timer tick, asking Buildbucket for the build status
[23:54:45.577] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:45.577] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:76:0) after 1m0s
[23:55:45.668] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:76:0)
[23:55:45.668] Timer tick, asking Buildbucket for the build status
[23:55:46.336] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:46.336] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:77:0) after 1m0s
[23:56:46.363] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:77:0)
[23:56:46.363] Timer tick, asking Buildbucket for the build status
[23:56:46.623] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:46.623] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:78:0) after 1m0s
[23:57:46.678] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:78:0)
[23:57:46.678] Timer tick, asking Buildbucket for the build status
[23:57:47.493] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:47.493] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:79:0) after 1m0s
[23:58:47.637] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:79:0)
[23:58:47.637] Timer tick, asking Buildbucket for the build status
[23:58:48.146] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:48.146] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:80:0) after 1m0s
[23:59:48.194] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:80:0)
[23:59:48.194] Timer tick, asking Buildbucket for the build status
[23:59:48.378] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:48.378] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:81:0) after 1m0s
[00:00:48.465] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:81:0)
[00:00:48.465] Timer tick, asking Buildbucket for the build status
[00:00:49.094] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:49.094] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:82:0) after 1m0s
[00:01:49.136] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:82:0)
[00:01:49.136] Timer tick, asking Buildbucket for the build status
[00:01:49.851] Build 8903932111740819648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:49.851] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069362174469801488:83:0) after 1m0s
[00:02:38.372] Received PubSub notification, asking Buildbucket for the build status
[00:02:38.561] Build 8903932111740819648: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:02:38.561] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1566950557770184",
"created_by": "project:chromium",
"created_ts": "1566945652215913",
"failure_reason": "BUILD_FAILURE",
"id": "8903932111740819648",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"4aecba8b4d2b08bbf257ed7b90c482308fae24b1\"}}",
"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-east1-d-8-90-jif9\", \"branch\": \"refs/heads/master\", \"buildername\": \"Site Isolation Android\", \"buildnumber\": 36544, \"got_angle_revision\": \"14a9be448e0137013809830d791d7a29d5c137d3\", \"got_dawn_revision\": \"b9b088f57e3d4567b9441251170cf1719125b2d1\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"4aecba8b4d2b08bbf257ed7b90c482308fae24b1\", \"got_revision_cp\": \"refs/heads/master@{#690911}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"0cd38a1778212cb7a316ee158fae6cc1e8dcda02\", \"got_v8_revision_cp\": \"refs/heads/7.8.205@{#1}\", \"got_webrtc_revision\": \"2d5aec56fdf7ff61166bb5fd3c67d4bcb81b0d5a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28975}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"4aecba8b4d2b08bbf257ed7b90c482308fae24b1\", \"swarm_hashes\": {\"components_browsertests\": \"93ad8d169b273d0c67ea1ae10ab66ef7b059a8c1\", \"components_unittests\": \"9428c2dd3a644186ae696c23fe40d5713f720bd8\", \"content_browsertests\": \"58d0f0f6317ea193a97bb74f502435c7f4572de4\", \"content_unittests\": \"89eb491769d5f74d4f43ab21bd4d198d8d040620\", \"unit_tests\": \"1ad1c972dd3518e5918f62286e47661bb43bd5d7\"}, \"swarm_hashes_refs/heads/master(at){#690911}_without_patch\": {\"components_browsertests\": \"93ad8d169b273d0c67ea1ae10ab66ef7b059a8c1\", \"components_unittests\": \"9428c2dd3a644186ae696c23fe40d5713f720bd8\", \"content_browsertests\": \"58d0f0f6317ea193a97bb74f502435c7f4572de4\", \"content_unittests\": \"89eb491769d5f74d4f43ab21bd4d198d8d040620\", \"unit_tests\": \"1ad1c972dd3518e5918f62286e47661bb43bd5d7\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3d74f5cea4cbf91e4b26f92a522c5c74e61b738440ef29ba16f5c9a596a5b201_v2\", \"builder_5018db700e530f8e7ae860b5b3feab2b02d3dedf1bbd3e704c6c59fde1b308dd_v2\", \"builder_544ce64b59aa2a62f93b2d1058d8c643105c17650fa0dc2da4c94ddccafb95f6_v2\", \"builder_9abb531f9fa28fcfd3ce68b2370bf6d67201ddb083beddbd2671f7c5fe25be9e_v2\", \"builder_abbf48b1e6e19aeefb5c876c0c73e4cbb1754b9b4f4f24d1586c5301d4d12b1d_v2\", \"builder_cc8a16e85eefa617bab544f2f33977139159d8b8c3b7333fd512c64ffd131f2c_v2\", \"builder_e961d9d7e9bca679490a4aaa8599bd975f9b7e0b713a988ed6895a059d04d2ac_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-east1-d-8-90-jif9\"], \"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-east\", \"us-east1\", \"us-east1-d\"]}}, \"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": "1566945670840216",
"status": "COMPLETED",
"status_changed_ts": "1566945672727845",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/36544",
"builder:Site Isolation Android",
"buildset:commit/git/4aecba8b4d2b08bbf257ed7b90c482308fae24b1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4aecba8b4d2b08bbf257ed7b90c482308fae24b1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9069362174469801488",
"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/8903932111740819648/+/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:46ee0ebbb88af310",
"user_agent:luci-scheduler"
],
"updated_ts": "1566950557974201",
"url": "https://ci.chromium.org/b/8903932111740819648",
"utcnow_ts": "1566950558544457"
}
}
[00:02:38.561] Invocation finished in 1h21m48.33268408s with status FAILED