[01:41:30.692] New invocation is queued and will start shortly
[01:41:32.036] Starting the invocation (attempt 1)
[01:41:32.085] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:41:32.085] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:41:32.085] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9087107812852925216",
"parameters_json": "{\"builder_name\":\"Site Isolation Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"c850d558879542cfea8224c245555299c24af8f3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Site Isolation Android",
"scheduler_invocation_id:9087107812852925216",
"scheduler_job_id:chromium/Site Isolation Android",
"user_agent:luci-scheduler",
"buildset:commit/git/c850d558879542cfea8224c245555299c24af8f3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c850d558879542cfea8224c245555299c24af8f3",
"gitiles_ref:refs/heads/master"
]
}
[01:41:34.780] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550022092221373",
"id": "8921677750585433344",
"lease_expiration_ts": "1550166094173637",
"lease_key": "1807162862",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"c850d558879542cfea8224c245555299c24af8f3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1550022094479416",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/30485",
"builder:Site Isolation Android",
"buildset:commit/git/c850d558879542cfea8224c245555299c24af8f3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c850d558879542cfea8224c245555299c24af8f3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087107812852925216",
"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/8921677750585433344/+/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:42fd55afed77f710",
"user_agent:luci-scheduler"
],
"updated_ts": "1550022094479563",
"url": "https://ci.chromium.org/b/8921677750585433344",
"utcnow_ts": "1550022094737650"
}
}
[01:41:34.780] Task URL: https://ci.chromium.org/b/8921677750585433344
[01:41:34.780] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:2:0) after 1m0s
[01:42:15.435] Received PubSub notification, asking Buildbucket for the build status
[01:42:16.476] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:34.812] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:2:0)
[01:42:34.812] Timer tick, asking Buildbucket for the build status
[01:42:35.207] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:35.207] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:4:0) after 1m0s
[01:43:35.325] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:4:0)
[01:43:35.325] Timer tick, asking Buildbucket for the build status
[01:43:36.473] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:36.473] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:5:0) after 1m0s
[01:44:36.494] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:5:0)
[01:44:36.494] Timer tick, asking Buildbucket for the build status
[01:44:37.034] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:37.034] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:6:0) after 1m0s
[01:45:37.054] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:6:0)
[01:45:37.054] Timer tick, asking Buildbucket for the build status
[01:45:37.404] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:37.404] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:7:0) after 1m0s
[01:46:37.431] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:7:0)
[01:46:37.431] Timer tick, asking Buildbucket for the build status
[01:46:37.504] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:37.504] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:8:0) after 1m0s
[01:47:37.535] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:8:0)
[01:47:37.535] Timer tick, asking Buildbucket for the build status
[01:47:37.607] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:37.607] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:9:0) after 1m0s
[01:48:37.627] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:9:0)
[01:48:37.627] Timer tick, asking Buildbucket for the build status
[01:48:37.686] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:37.686] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:10:0) after 1m0s
[01:49:37.765] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:10:0)
[01:49:37.765] Timer tick, asking Buildbucket for the build status
[01:49:37.860] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:37.860] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:11:0) after 1m0s
[01:50:37.877] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:11:0)
[01:50:37.877] Timer tick, asking Buildbucket for the build status
[01:50:38.314] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:38.314] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:12:0) after 1m0s
[01:51:38.346] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:12:0)
[01:51:38.346] Timer tick, asking Buildbucket for the build status
[01:51:38.452] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:38.452] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:13:0) after 1m0s
[01:52:38.474] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:13:0)
[01:52:38.474] Timer tick, asking Buildbucket for the build status
[01:52:38.871] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:38.871] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:14:0) after 1m0s
[01:53:38.890] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:14:0)
[01:53:38.890] Timer tick, asking Buildbucket for the build status
[01:53:39.117] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:39.117] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:15:0) after 1m0s
[01:54:39.137] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:15:0)
[01:54:39.137] Timer tick, asking Buildbucket for the build status
[01:54:39.414] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:39.414] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:16:0) after 1m0s
[01:55:39.441] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:16:0)
[01:55:39.441] Timer tick, asking Buildbucket for the build status
[01:55:39.657] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:39.657] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:17:0) after 1m0s
[01:56:39.688] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:17:0)
[01:56:39.688] Timer tick, asking Buildbucket for the build status
[01:56:39.847] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:39.847] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:18:0) after 1m0s
[01:57:39.863] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:18:0)
[01:57:39.863] Timer tick, asking Buildbucket for the build status
[01:57:39.933] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:39.933] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:19:0) after 1m0s
[01:58:39.955] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:19:0)
[01:58:39.955] Timer tick, asking Buildbucket for the build status
[01:58:39.992] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:39.992] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:20:0) after 1m0s
[01:59:40.022] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:20:0)
[01:59:40.022] Timer tick, asking Buildbucket for the build status
[01:59:40.073] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:40.073] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:21:0) after 1m0s
[02:00:40.097] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:21:0)
[02:00:40.097] Timer tick, asking Buildbucket for the build status
[02:00:40.286] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:40.286] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:22:0) after 1m0s
[02:01:40.307] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:22:0)
[02:01:40.308] Timer tick, asking Buildbucket for the build status
[02:01:40.388] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:40.388] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:23:0) after 1m0s
[02:02:40.414] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:23:0)
[02:02:40.414] Timer tick, asking Buildbucket for the build status
[02:02:40.519] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:40.519] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:24:0) after 1m0s
[02:03:40.540] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:24:0)
[02:03:40.540] Timer tick, asking Buildbucket for the build status
[02:03:40.646] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:40.646] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:25:0) after 1m0s
[02:04:40.666] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:25:0)
[02:04:40.666] Timer tick, asking Buildbucket for the build status
[02:04:40.738] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:40.738] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:26:0) after 1m0s
[02:05:40.757] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:26:0)
[02:05:40.757] Timer tick, asking Buildbucket for the build status
[02:05:41.068] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:41.068] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:27:0) after 1m0s
[02:06:41.088] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:27:0)
[02:06:41.088] Timer tick, asking Buildbucket for the build status
[02:06:41.156] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:41.156] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:28:0) after 1m0s
[02:07:41.178] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:28:0)
[02:07:41.178] Timer tick, asking Buildbucket for the build status
[02:07:41.272] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:41.272] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:29:0) after 1m0s
[02:08:41.290] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:29:0)
[02:08:41.290] Timer tick, asking Buildbucket for the build status
[02:08:41.349] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:41.349] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:30:0) after 1m0s
[02:09:41.371] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:30:0)
[02:09:41.371] Timer tick, asking Buildbucket for the build status
[02:09:41.564] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:41.564] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:31:0) after 1m0s
[02:10:41.583] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:31:0)
[02:10:41.583] Timer tick, asking Buildbucket for the build status
[02:10:41.761] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:41.761] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:32:0) after 1m0s
[02:11:41.782] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:32:0)
[02:11:41.782] Timer tick, asking Buildbucket for the build status
[02:11:41.882] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:41.882] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:33:0) after 1m0s
[02:12:41.901] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:33:0)
[02:12:41.901] Timer tick, asking Buildbucket for the build status
[02:12:41.949] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:41.949] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:34:0) after 1m0s
[02:13:41.976] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:34:0)
[02:13:41.976] Timer tick, asking Buildbucket for the build status
[02:13:42.062] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:42.062] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:35:0) after 1m0s
[02:14:42.136] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:35:0)
[02:14:42.136] Timer tick, asking Buildbucket for the build status
[02:14:42.619] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:42.619] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:36:0) after 1m0s
[02:15:42.642] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:36:0)
[02:15:42.642] Timer tick, asking Buildbucket for the build status
[02:15:42.762] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:42.762] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:37:0) after 1m0s
[02:16:42.783] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:37:0)
[02:16:42.783] Timer tick, asking Buildbucket for the build status
[02:16:42.851] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:42.851] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:38:0) after 1m0s
[02:17:42.903] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:38:0)
[02:17:42.903] Timer tick, asking Buildbucket for the build status
[02:17:42.950] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:42.950] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:39:0) after 1m0s
[02:18:42.997] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:39:0)
[02:18:42.997] Timer tick, asking Buildbucket for the build status
[02:18:43.066] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:43.066] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:40:0) after 1m0s
[02:19:43.090] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:40:0)
[02:19:43.090] Timer tick, asking Buildbucket for the build status
[02:19:43.128] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:43.128] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:41:0) after 1m0s
[02:20:43.217] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:41:0)
[02:20:43.217] Timer tick, asking Buildbucket for the build status
[02:20:43.366] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:43.366] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:42:0) after 1m0s
[02:21:43.400] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:42:0)
[02:21:43.400] Timer tick, asking Buildbucket for the build status
[02:21:43.507] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:43.507] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:43:0) after 1m0s
[02:22:43.582] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:43:0)
[02:22:43.582] Timer tick, asking Buildbucket for the build status
[02:22:44.396] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:44.396] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:44:0) after 1m0s
[02:23:44.418] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:44:0)
[02:23:44.418] Timer tick, asking Buildbucket for the build status
[02:23:44.469] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:44.469] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:45:0) after 1m0s
[02:24:44.623] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:45:0)
[02:24:44.623] Timer tick, asking Buildbucket for the build status
[02:24:44.738] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:44.738] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:46:0) after 1m0s
[02:25:44.799] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:46:0)
[02:25:44.799] Timer tick, asking Buildbucket for the build status
[02:25:44.972] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:44.972] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:47:0) after 1m0s
[02:26:44.991] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:47:0)
[02:26:44.991] Timer tick, asking Buildbucket for the build status
[02:26:45.042] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:45.042] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:48:0) after 1m0s
[02:27:45.062] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:48:0)
[02:27:45.062] Timer tick, asking Buildbucket for the build status
[02:27:45.109] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:45.109] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:49:0) after 1m0s
[02:28:45.133] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:49:0)
[02:28:45.133] Timer tick, asking Buildbucket for the build status
[02:28:45.291] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:45.291] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:50:0) after 1m0s
[02:29:45.315] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:50:0)
[02:29:45.315] Timer tick, asking Buildbucket for the build status
[02:29:45.373] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:45.373] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:51:0) after 1m0s
[02:30:45.396] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:51:0)
[02:30:45.396] Timer tick, asking Buildbucket for the build status
[02:30:45.572] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:45.572] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:52:0) after 1m0s
[02:31:45.659] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:52:0)
[02:31:45.659] Timer tick, asking Buildbucket for the build status
[02:31:45.766] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:45.766] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:53:0) after 1m0s
[02:32:45.786] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:53:0)
[02:32:45.786] Timer tick, asking Buildbucket for the build status
[02:32:45.914] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:45.914] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:54:0) after 1m0s
[02:33:45.934] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:54:0)
[02:33:45.934] Timer tick, asking Buildbucket for the build status
[02:33:46.216] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:46.216] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:55:0) after 1m0s
[02:34:46.243] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:55:0)
[02:34:46.243] Timer tick, asking Buildbucket for the build status
[02:34:46.291] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:46.291] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:56:0) after 1m0s
[02:35:46.311] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:56:0)
[02:35:46.311] Timer tick, asking Buildbucket for the build status
[02:35:46.627] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:46.627] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:57:0) after 1m0s
[02:36:46.616] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:57:0)
[02:36:46.616] Timer tick, asking Buildbucket for the build status
[02:36:46.682] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:46.682] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:58:0) after 1m0s
[02:37:46.777] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:58:0)
[02:37:46.777] Timer tick, asking Buildbucket for the build status
[02:37:46.833] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:46.833] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:59:0) after 1m0s
[02:38:46.853] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:59:0)
[02:38:46.853] Timer tick, asking Buildbucket for the build status
[02:38:46.925] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:46.925] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:60:0) after 1m0s
[02:39:46.947] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:60:0)
[02:39:46.947] Timer tick, asking Buildbucket for the build status
[02:39:47.110] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:47.110] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:61:0) after 1m0s
[02:40:47.143] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:61:0)
[02:40:47.143] Timer tick, asking Buildbucket for the build status
[02:40:47.225] Build 8921677750585433344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:47.225] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9087107812852925216:62:0) after 1m0s
[02:41:44.817] Received PubSub notification, asking Buildbucket for the build status
[02:41:44.860] Build 8921677750585433344: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:41:44.860] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1550025696351662",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550022092221373",
"failure_reason": "BUILD_FAILURE",
"id": "8921677750585433344",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"c850d558879542cfea8224c245555299c24af8f3\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Site Isolation Android\"], \"kvm\": [\"0\"], \"id\": [\"swarm2384-c4\"], \"server_version\": [\"4085-c81638b\"], \"image\": [\"chrome-trusty-18042300-b7223b463e3\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-f\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Ivy_Bridge_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e961d9d7e9bca679490a4aaa8599bd975f9b7e0b713a988ed6895a059d04d2ac_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"Failure site_per_process_content_browsertests on Android device Nexus 5X\"}, \"properties\": {\"swarm_hashes_refs/heads/master(at){#631497}_without_patch\": {\"components_unittests\": \"c1332836ab36502051d1d52a5f8e64405c489f94\", \"unit_tests\": \"28f6378c940a6170032e03b2247ea2f2df2259cf\", \"components_browsertests\": \"fc124fd7c503cce108a400454f815ec2d7606feb\", \"content_browsertests\": \"44b09fe9fe068587e9e72fa8cc03cb57fb340009\", \"content_unittests\": \"a8e18b0d4602e3a13960afaf2a8a0e662ab3a8a8\"}, \"got_nacl_revision\": \"ff15c51d20785fa51c0ef03ccb480b87299acbeb\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"c850d558879542cfea8224c245555299c24af8f3\", \"recipe\": \"chromium\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"got_dawn_revision\": \"bb5696bcd3b7a9ba5cd6a74cd1e9665bc74deb2c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26626}\", \"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"got_revision_cp\": \"refs/heads/master@{#631497}\", \"branch\": \"refs/heads/master\", \"revision\": \"c850d558879542cfea8224c245555299c24af8f3\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Site Isolation Android\", \"got_webrtc_revision\": \"f13c2cd9ee41f4ca572232a4e397b05449474632\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"eca36cb5fc9032eb9ae07b5c7d2489b53a9266f2\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1550022092221373, \"tags\": [\"build_address:luci.chromium.ci/Site Isolation Android/30485\", \"builder:Site Isolation Android\", \"buildset:commit/git/c850d558879542cfea8224c245555299c24af8f3\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c850d558879542cfea8224c245555299c24af8f3\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9087107812852925216\", \"scheduler_job_id:chromium/Site Isolation Android\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8921677750585433344\"}}, \"got_v8_revision\": \"a881b983340e77e384d4534f3ae0d721dc32324d\", \"got_v8_revision_cp\": \"refs/heads/7.4.151@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 30485, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Site Isolation Android\", \"key\": \"builder\"}, {\"value\": \"commit/git/c850d558879542cfea8224c245555299c24af8f3\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/c850d558879542cfea8224c245555299c24af8f3\", \"key\": \"buildset\"}, {\"value\": \"9087107812852925216\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"chromium/Site Isolation Android\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"chromium\", \"builder\": \"Site Isolation Android\", \"bucket\": \"ci\"}, \"number\": 30485, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"2acf9ddf085124300131f0a719a64564504c97a6\"}, \"recipe\": {\"name\": \"chromium\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8921677750585433344\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"c850d558879542cfea8224c245555299c24af8f3\"}}, \"createTime\": \"2019-02-13T01:41:32.221373Z\"}}, \"path_config\": \"generic\", \"swarm_hashes\": {\"components_unittests\": \"c1332836ab36502051d1d52a5f8e64405c489f94\", \"unit_tests\": \"28f6378c940a6170032e03b2247ea2f2df2259cf\", \"components_browsertests\": \"fc124fd7c503cce108a400454f815ec2d7606feb\", \"content_browsertests\": \"44b09fe9fe068587e9e72fa8cc03cb57fb340009\", \"content_unittests\": \"a8e18b0d4602e3a13960afaf2a8a0e662ab3a8a8\"}, \"bot_id\": \"swarm2384-c4\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1550022134572356",
"status": "COMPLETED",
"status_changed_ts": "1550025703248294",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/30485",
"builder:Site Isolation Android",
"buildset:commit/git/c850d558879542cfea8224c245555299c24af8f3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c850d558879542cfea8224c245555299c24af8f3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087107812852925216",
"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/8921677750585433344/+/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:42fd55afed77f710",
"user_agent:luci-scheduler"
],
"updated_ts": "1550025703283329",
"url": "https://ci.chromium.org/b/8921677750585433344",
"utcnow_ts": "1550025704849821"
}
}
[02:41:44.860] Invocation finished in 1h0m14.185206994s with status FAILED