[16:47:34.216] New invocation is queued and will start shortly
[16:47:35.315] Starting the invocation (attempt 1)
[16:47:35.389] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:47:35.389] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:47:35.390] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9079168872489693584",
"parameters_json": "{\"builder_name\":\"Site Isolation Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"b4c153bcd1e8a9f640d0f89125657746539ce768\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Site Isolation Android",
"scheduler_invocation_id:9079168872489693584",
"scheduler_job_id:chromium/Site Isolation Android",
"user_agent:luci-scheduler",
"buildset:commit/git/b4c153bcd1e8a9f640d0f89125657746539ce768",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b4c153bcd1e8a9f640d0f89125657746539ce768",
"gitiles_ref:refs/heads/master"
]
}
[16:47:36.359] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1557593255557545",
"id": "8913738810419358272",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"b4c153bcd1e8a9f640d0f89125657746539ce768\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557593256096558",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/33848",
"builder:Site Isolation Android",
"buildset:commit/git/b4c153bcd1e8a9f640d0f89125657746539ce768",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b4c153bcd1e8a9f640d0f89125657746539ce768",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079168872489693584",
"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/8913738810419358272/+/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": "1557593256096653",
"url": "https://ci.chromium.org/b/8913738810419358272",
"utcnow_ts": "1557593256340823"
}
}
[16:47:36.359] Task URL: https://ci.chromium.org/b/8913738810419358272
[16:47:36.359] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:2:0) after 1m0s
[16:48:36.377] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:2:0)
[16:48:36.377] Timer tick, asking Buildbucket for the build status
[16:48:36.582] Build 8913738810419358272: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:48:36.582] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:3:0) after 1m0s
[16:49:36.603] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:3:0)
[16:49:36.603] Timer tick, asking Buildbucket for the build status
[16:49:38.012] Build 8913738810419358272: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:49:38.012] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:4:0) after 1m0s
[16:50:08.957] Received PubSub notification, asking Buildbucket for the build status
[16:50:09.207] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:38.015] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:4:0)
[16:50:38.015] Timer tick, asking Buildbucket for the build status
[16:50:38.970] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:38.970] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:6:0) after 1m0s
[16:51:38.992] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:6:0)
[16:51:38.992] Timer tick, asking Buildbucket for the build status
[16:51:39.270] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:39.270] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:7:0) after 1m0s
[16:52:39.284] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:7:0)
[16:52:39.284] Timer tick, asking Buildbucket for the build status
[16:52:39.443] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:39.443] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:8:0) after 1m0s
[16:53:39.458] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:8:0)
[16:53:39.458] Timer tick, asking Buildbucket for the build status
[16:53:40.593] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:40.593] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:9:0) after 1m0s
[16:54:40.609] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:9:0)
[16:54:40.609] Timer tick, asking Buildbucket for the build status
[16:54:40.743] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:40.744] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:10:0) after 1m0s
[16:55:40.776] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:10:0)
[16:55:40.776] Timer tick, asking Buildbucket for the build status
[16:55:40.863] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:40.863] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:11:0) after 1m0s
[16:56:40.887] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:11:0)
[16:56:40.887] Timer tick, asking Buildbucket for the build status
[16:56:41.579] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:41.579] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:12:0) after 1m0s
[16:57:41.667] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:12:0)
[16:57:41.667] Timer tick, asking Buildbucket for the build status
[16:57:42.111] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:42.111] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:13:0) after 1m0s
[16:58:42.119] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:13:0)
[16:58:42.119] Timer tick, asking Buildbucket for the build status
[16:58:42.538] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:42.538] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:14:0) after 1m0s
[16:59:42.587] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:14:0)
[16:59:42.587] Timer tick, asking Buildbucket for the build status
[16:59:43.760] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:43.760] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:15:0) after 1m0s
[17:00:43.789] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:15:0)
[17:00:43.789] Timer tick, asking Buildbucket for the build status
[17:00:44.294] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:44.294] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:16:0) after 1m0s
[17:01:44.343] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:16:0)
[17:01:44.343] Timer tick, asking Buildbucket for the build status
[17:01:45.126] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:45.126] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:17:0) after 1m0s
[17:02:45.144] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:17:0)
[17:02:45.144] Timer tick, asking Buildbucket for the build status
[17:02:45.358] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:45.358] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:18:0) after 1m0s
[17:03:45.372] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:18:0)
[17:03:45.372] Timer tick, asking Buildbucket for the build status
[17:03:45.791] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:45.791] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:19:0) after 1m0s
[17:04:45.821] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:19:0)
[17:04:45.821] Timer tick, asking Buildbucket for the build status
[17:04:46.601] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:46.601] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:20:0) after 1m0s
[17:05:46.618] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:20:0)
[17:05:46.618] Timer tick, asking Buildbucket for the build status
[17:05:46.819] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:46.819] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:21:0) after 1m0s
[17:06:46.840] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:21:0)
[17:06:46.840] Timer tick, asking Buildbucket for the build status
[17:06:47.496] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:47.496] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:22:0) after 1m0s
[17:07:47.531] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:22:0)
[17:07:47.531] Timer tick, asking Buildbucket for the build status
[17:07:47.794] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:47.794] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:23:0) after 1m0s
[17:08:47.879] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:23:0)
[17:08:47.879] Timer tick, asking Buildbucket for the build status
[17:08:48.558] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:48.558] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:24:0) after 1m0s
[17:09:48.584] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:24:0)
[17:09:48.585] Timer tick, asking Buildbucket for the build status
[17:09:48.753] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:48.753] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:25:0) after 1m0s
[17:10:48.795] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:25:0)
[17:10:48.795] Timer tick, asking Buildbucket for the build status
[17:10:51.107] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:51.107] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:26:0) after 1m0s
[17:11:51.132] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:26:0)
[17:11:51.132] Timer tick, asking Buildbucket for the build status
[17:11:52.214] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:52.215] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:27:0) after 1m0s
[17:12:52.204] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:27:0)
[17:12:52.204] Timer tick, asking Buildbucket for the build status
[17:12:53.600] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:53.600] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:28:0) after 1m0s
[17:13:53.617] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:28:0)
[17:13:53.617] Timer tick, asking Buildbucket for the build status
[17:13:53.895] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:53.895] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:29:0) after 1m0s
[17:14:53.919] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:29:0)
[17:14:53.919] Timer tick, asking Buildbucket for the build status
[17:14:54.564] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:54.564] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:30:0) after 1m0s
[17:15:54.587] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:30:0)
[17:15:54.587] Timer tick, asking Buildbucket for the build status
[17:15:54.851] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:54.851] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:31:0) after 1m0s
[17:16:54.881] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:31:0)
[17:16:54.881] Timer tick, asking Buildbucket for the build status
[17:16:55.361] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:55.361] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:32:0) after 1m0s
[17:17:55.370] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:32:0)
[17:17:55.370] Timer tick, asking Buildbucket for the build status
[17:17:55.495] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:55.495] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:33:0) after 1m0s
[17:18:55.538] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:33:0)
[17:18:55.538] Timer tick, asking Buildbucket for the build status
[17:18:56.374] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:56.374] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:34:0) after 1m0s
[17:19:56.412] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:34:0)
[17:19:56.412] Timer tick, asking Buildbucket for the build status
[17:19:56.577] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:56.577] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:35:0) after 1m0s
[17:20:56.566] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:35:0)
[17:20:56.566] Timer tick, asking Buildbucket for the build status
[17:20:56.836] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:56.836] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:36:0) after 1m0s
[17:21:56.857] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:36:0)
[17:21:56.857] Timer tick, asking Buildbucket for the build status
[17:21:57.232] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:57.232] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:37:0) after 1m0s
[17:22:57.257] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:37:0)
[17:22:57.257] Timer tick, asking Buildbucket for the build status
[17:22:57.394] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:57.394] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:38:0) after 1m0s
[17:23:57.416] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:38:0)
[17:23:57.416] Timer tick, asking Buildbucket for the build status
[17:23:58.104] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:58.104] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:39:0) after 1m0s
[17:24:58.142] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:39:0)
[17:24:58.143] Timer tick, asking Buildbucket for the build status
[17:24:58.339] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:58.339] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:40:0) after 1m0s
[17:25:58.362] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:40:0)
[17:25:58.362] Timer tick, asking Buildbucket for the build status
[17:25:58.528] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:58.528] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:41:0) after 1m0s
[17:26:58.535] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:41:0)
[17:26:58.535] Timer tick, asking Buildbucket for the build status
[17:26:58.686] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:58.686] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:42:0) after 1m0s
[17:27:58.725] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:42:0)
[17:27:58.725] Timer tick, asking Buildbucket for the build status
[17:27:58.998] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:58.998] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:43:0) after 1m0s
[17:28:58.992] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:43:0)
[17:28:58.992] Timer tick, asking Buildbucket for the build status
[17:28:59.145] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:59.145] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:44:0) after 1m0s
[17:29:59.138] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:44:0)
[17:29:59.138] Timer tick, asking Buildbucket for the build status
[17:29:59.365] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:59.365] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:45:0) after 1m0s
[17:30:59.394] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:45:0)
[17:30:59.394] Timer tick, asking Buildbucket for the build status
[17:30:59.557] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:59.557] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:46:0) after 1m0s
[17:31:59.594] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:46:0)
[17:31:59.594] Timer tick, asking Buildbucket for the build status
[17:32:00.227] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:00.227] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:47:0) after 1m0s
[17:33:00.259] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:47:0)
[17:33:00.259] Timer tick, asking Buildbucket for the build status
[17:33:00.394] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:00.394] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:48:0) after 1m0s
[17:34:00.417] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:48:0)
[17:34:00.417] Timer tick, asking Buildbucket for the build status
[17:34:00.561] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:00.561] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:49:0) after 1m0s
[17:35:00.585] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:49:0)
[17:35:00.585] Timer tick, asking Buildbucket for the build status
[17:35:00.893] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:00.893] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:50:0) after 1m0s
[17:36:00.892] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:50:0)
[17:36:00.892] Timer tick, asking Buildbucket for the build status
[17:36:01.007] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:01.007] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:51:0) after 1m0s
[17:37:01.840] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:51:0)
[17:37:01.840] Timer tick, asking Buildbucket for the build status
[17:37:02.651] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:02.651] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:52:0) after 1m0s
[17:38:02.668] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:52:0)
[17:38:02.668] Timer tick, asking Buildbucket for the build status
[17:38:03.415] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:03.415] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:53:0) after 1m0s
[17:39:03.385] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:53:0)
[17:39:03.385] Timer tick, asking Buildbucket for the build status
[17:39:04.172] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:04.172] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:54:0) after 1m0s
[17:40:04.195] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:54:0)
[17:40:04.195] Timer tick, asking Buildbucket for the build status
[17:40:04.369] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:04.369] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:55:0) after 1m0s
[17:41:04.401] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:55:0)
[17:41:04.401] Timer tick, asking Buildbucket for the build status
[17:41:04.852] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:04.852] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:56:0) after 1m0s
[17:42:08.835] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:56:0)
[17:42:08.835] Timer tick, asking Buildbucket for the build status
[17:42:09.049] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:09.049] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:57:0) after 1m0s
[17:43:09.084] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:57:0)
[17:43:09.084] Timer tick, asking Buildbucket for the build status
[17:43:09.304] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:09.304] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:58:0) after 1m0s
[17:44:09.336] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:58:0)
[17:44:09.336] Timer tick, asking Buildbucket for the build status
[17:44:09.435] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:09.435] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:59:0) after 1m0s
[17:45:09.457] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:59:0)
[17:45:09.457] Timer tick, asking Buildbucket for the build status
[17:45:09.800] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:09.800] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:60:0) after 1m0s
[17:46:09.823] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:60:0)
[17:46:09.823] Timer tick, asking Buildbucket for the build status
[17:46:10.036] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:10.036] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:61:0) after 1m0s
[17:47:10.066] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:61:0)
[17:47:10.066] Timer tick, asking Buildbucket for the build status
[17:47:10.310] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:10.310] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:62:0) after 1m0s
[17:48:10.330] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:62:0)
[17:48:10.330] Timer tick, asking Buildbucket for the build status
[17:48:10.557] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:10.557] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:63:0) after 1m0s
[17:49:10.580] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:63:0)
[17:49:10.580] Timer tick, asking Buildbucket for the build status
[17:49:11.102] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:11.102] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:64:0) after 1m0s
[17:50:11.134] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:64:0)
[17:50:11.134] Timer tick, asking Buildbucket for the build status
[17:50:11.846] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:11.846] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:65:0) after 1m0s
[17:51:11.894] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:65:0)
[17:51:11.894] Timer tick, asking Buildbucket for the build status
[17:51:12.167] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:12.167] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:66:0) after 1m0s
[17:52:12.189] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:66:0)
[17:52:12.189] Timer tick, asking Buildbucket for the build status
[17:52:12.591] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:12.591] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:67:0) after 1m0s
[17:53:12.589] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:67:0)
[17:53:12.589] Timer tick, asking Buildbucket for the build status
[17:53:13.200] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:13.200] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:68:0) after 1m0s
[17:54:13.233] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:68:0)
[17:54:13.233] Timer tick, asking Buildbucket for the build status
[17:54:14.594] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:14.594] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:69:0) after 1m0s
[17:55:14.616] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:69:0)
[17:55:14.616] Timer tick, asking Buildbucket for the build status
[17:55:15.593] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:15.593] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:70:0) after 1m0s
[17:56:15.587] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:70:0)
[17:56:15.587] Timer tick, asking Buildbucket for the build status
[17:56:16.943] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:16.943] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:71:0) after 1m0s
[17:57:16.979] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:71:0)
[17:57:16.979] Timer tick, asking Buildbucket for the build status
[17:57:17.116] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:17.116] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:72:0) after 1m0s
[17:58:17.135] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:72:0)
[17:58:17.135] Timer tick, asking Buildbucket for the build status
[17:58:18.012] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:18.012] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:73:0) after 1m0s
[17:59:18.108] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:73:0)
[17:59:18.108] Timer tick, asking Buildbucket for the build status
[17:59:18.204] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:18.204] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:74:0) after 1m0s
[18:00:18.250] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:74:0)
[18:00:18.250] Timer tick, asking Buildbucket for the build status
[18:00:18.848] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:18.848] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:75:0) after 1m0s
[18:01:18.844] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:75:0)
[18:01:18.844] Timer tick, asking Buildbucket for the build status
[18:01:19.266] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:19.266] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:76:0) after 1m0s
[18:02:19.289] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:76:0)
[18:02:19.289] Timer tick, asking Buildbucket for the build status
[18:02:19.531] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:19.531] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:77:0) after 1m0s
[18:03:19.551] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:77:0)
[18:03:19.551] Timer tick, asking Buildbucket for the build status
[18:03:19.715] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:19.715] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:78:0) after 1m0s
[18:04:19.750] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:78:0)
[18:04:19.750] Timer tick, asking Buildbucket for the build status
[18:04:19.869] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:19.869] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:79:0) after 1m0s
[18:05:19.897] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:79:0)
[18:05:19.897] Timer tick, asking Buildbucket for the build status
[18:05:20.036] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:20.036] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:80:0) after 1m0s
[18:06:20.115] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:80:0)
[18:06:20.115] Timer tick, asking Buildbucket for the build status
[18:06:21.092] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:21.092] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:81:0) after 1m0s
[18:07:21.189] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:81:0)
[18:07:21.189] Timer tick, asking Buildbucket for the build status
[18:07:21.968] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:21.968] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:82:0) after 1m0s
[18:08:22.001] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:82:0)
[18:08:22.001] Timer tick, asking Buildbucket for the build status
[18:08:22.199] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:22.199] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:83:0) after 1m0s
[18:09:22.222] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:83:0)
[18:09:22.222] Timer tick, asking Buildbucket for the build status
[18:09:23.545] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:23.545] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:84:0) after 1m0s
[18:10:23.565] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:84:0)
[18:10:23.565] Timer tick, asking Buildbucket for the build status
[18:10:24.740] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:24.740] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:85:0) after 1m0s
[18:11:24.783] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:85:0)
[18:11:24.783] Timer tick, asking Buildbucket for the build status
[18:11:25.290] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:25.290] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:86:0) after 1m0s
[18:12:25.294] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:86:0)
[18:12:25.294] Timer tick, asking Buildbucket for the build status
[18:12:25.436] Build 8913738810419358272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:25.436] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079168872489693584:87:0) after 1m0s
[18:13:13.582] Received PubSub notification, asking Buildbucket for the build status
[18:13:13.885] Build 8913738810419358272: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[18:13:13.885] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1557598393266621",
"created_by": "project:chromium",
"created_ts": "1557593255557545",
"failure_reason": "BUILD_FAILURE",
"id": "8913738810419358272",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"b4c153bcd1e8a9f640d0f89125657746539ce768\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"2 tests failed: ['site_per_process_content_browsertests 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\": 33848, \"got_angle_revision\": \"3dbe480b559e7919f303a15b4d4b0c788229c3ee\", \"got_dawn_revision\": \"97f08fa2e68f3505384395a8b6594fb70bdec6a3\", \"got_nacl_revision\": \"ca374f5112fca6b36fc5d73e117a60b82f394993\", \"got_revision\": \"b4c153bcd1e8a9f640d0f89125657746539ce768\", \"got_revision_cp\": \"refs/heads/master@{#658896}\", \"got_swarming_client_revision\": \"1b65f4e862045f3ba430a4cbd0643f5b1b66c230\", \"got_v8_revision\": \"512e74e0bdb9d98c4d729551c294127fff4b0434\", \"got_v8_revision_cp\": \"refs/heads/7.6.160@{#1}\", \"got_webrtc_revision\": \"46afbf9481fbcc939c998c898ca1031ce41cc6b1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27917}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"b4c153bcd1e8a9f640d0f89125657746539ce768\", \"swarm_hashes\": {\"components_browsertests\": \"1e8b0d2df04834b2afc97dadbccddba83b5cbbf7\", \"components_unittests\": \"656309867a0c2460a2e36aef98634505a57e4dc3\", \"content_browsertests\": \"7d28881d38dcccb24d9f734cc6c970e9720fb859\", \"content_unittests\": \"e5e77ec2f97bf370d3dd460f73caefe26a7cf742\", \"unit_tests\": \"8615968eae1a09a5794ffbc3ba7412ba095ac2c8\"}, \"swarm_hashes_refs/heads/master(at){#658896}_without_patch\": {\"components_browsertests\": \"1e8b0d2df04834b2afc97dadbccddba83b5cbbf7\", \"components_unittests\": \"656309867a0c2460a2e36aef98634505a57e4dc3\", \"content_browsertests\": \"7d28881d38dcccb24d9f734cc6c970e9720fb859\", \"content_unittests\": \"e5e77ec2f97bf370d3dd460f73caefe26a7cf742\", \"unit_tests\": \"8615968eae1a09a5794ffbc3ba7412ba095ac2c8\"}}, \"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\": [\"4292-d8c05fa\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-f\"]}}, \"ui\": {\"info\": \"2 tests failed: ['site_per_process_content_browsertests 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": "1557593407927219",
"status": "COMPLETED",
"status_changed_ts": "1557593408375686",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/33848",
"builder:Site Isolation Android",
"buildset:commit/git/b4c153bcd1e8a9f640d0f89125657746539ce768",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b4c153bcd1e8a9f640d0f89125657746539ce768",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079168872489693584",
"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/8913738810419358272/+/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:44c09c7ad7f48210",
"user_agent:luci-scheduler"
],
"updated_ts": "1557598393322284",
"url": "https://ci.chromium.org/b/8913738810419358272",
"utcnow_ts": "1557598393857759"
}
}
[18:13:13.885] Invocation finished in 1h25m39.686685342s with status FAILED