[09:48:37.225] New invocation is queued and will start shortly
[09:48:38.902] Starting the invocation (attempt 1)
[09:48:38.999] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:48:38.999] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:48:38.999] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9069501355137216000",
"parameters_json": "{\"builder_name\":\"Site Isolation Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"6b0fd0337680fa854ce43a4834c370247827af49\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Site Isolation Android",
"scheduler_invocation_id:9069501355137216000",
"scheduler_job_id:chromium/Site Isolation Android",
"user_agent:luci-scheduler",
"buildset:commit/git/6b0fd0337680fa854ce43a4834c370247827af49",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6b0fd0337680fa854ce43a4834c370247827af49",
"gitiles_ref:refs/heads/master"
]
}
[09:48:40.861] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1566812919224516",
"id": "8904071292369780736",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"6b0fd0337680fa854ce43a4834c370247827af49\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1566812919888417",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/36511",
"builder:Site Isolation Android",
"buildset:commit/git/6b0fd0337680fa854ce43a4834c370247827af49",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6b0fd0337680fa854ce43a4834c370247827af49",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9069501355137216000",
"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/8904071292369780736/+/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": "1566812919888555",
"url": "https://ci.chromium.org/b/8904071292369780736",
"utcnow_ts": "1566812920844907"
}
}
[09:48:40.861] Task URL: https://ci.chromium.org/b/8904071292369780736
[09:48:40.861] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:2:0) after 1m0s
[09:49:00.072] Received PubSub notification, asking Buildbucket for the build status
[09:49:00.676] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:40.881] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:2:0)
[09:49:40.882] Timer tick, asking Buildbucket for the build status
[09:49:41.169] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:41.169] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:4:0) after 1m0s
[09:50:41.147] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:4:0)
[09:50:41.147] Timer tick, asking Buildbucket for the build status
[09:50:41.362] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:41.362] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:5:0) after 1m0s
[09:51:41.414] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:5:0)
[09:51:41.414] Timer tick, asking Buildbucket for the build status
[09:51:41.663] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:41.663] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:6:0) after 1m0s
[09:52:41.738] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:6:0)
[09:52:41.738] Timer tick, asking Buildbucket for the build status
[09:52:44.167] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:44.168] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:7:0) after 1m0s
[09:53:44.192] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:7:0)
[09:53:44.192] Timer tick, asking Buildbucket for the build status
[09:53:45.523] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:45.523] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:8:0) after 1m0s
[09:54:45.544] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:8:0)
[09:54:45.544] Timer tick, asking Buildbucket for the build status
[09:54:45.721] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:45.721] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:9:0) after 1m0s
[09:55:45.780] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:9:0)
[09:55:45.780] Timer tick, asking Buildbucket for the build status
[09:55:46.032] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:46.032] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:10:0) after 1m0s
[09:56:46.089] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:10:0)
[09:56:46.089] Timer tick, asking Buildbucket for the build status
[09:56:46.396] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:46.396] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:11:0) after 1m0s
[09:57:46.443] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:11:0)
[09:57:46.443] Timer tick, asking Buildbucket for the build status
[09:57:48.786] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:48.786] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:12:0) after 1m0s
[09:58:48.846] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:12:0)
[09:58:48.846] Timer tick, asking Buildbucket for the build status
[09:58:49.097] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:49.097] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:13:0) after 1m0s
[09:59:49.163] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:13:0)
[09:59:49.163] Timer tick, asking Buildbucket for the build status
[09:59:50.303] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:50.303] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:14:0) after 1m0s
[10:00:50.364] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:14:0)
[10:00:50.364] Timer tick, asking Buildbucket for the build status
[10:00:50.791] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:50.791] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:15:0) after 1m0s
[10:01:50.818] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:15:0)
[10:01:50.818] Timer tick, asking Buildbucket for the build status
[10:01:53.904] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:53.904] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:16:0) after 1m0s
[10:02:54.027] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:16:0)
[10:02:54.027] Timer tick, asking Buildbucket for the build status
[10:02:55.696] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:55.696] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:17:0) after 1m0s
[10:03:55.793] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:17:0)
[10:03:55.793] Timer tick, asking Buildbucket for the build status
[10:03:56.233] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:56.233] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:18:0) after 1m0s
[10:04:56.290] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:18:0)
[10:04:56.290] Timer tick, asking Buildbucket for the build status
[10:04:56.652] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:56.652] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:19:0) after 1m0s
[10:05:56.654] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:19:0)
[10:05:56.654] Timer tick, asking Buildbucket for the build status
[10:05:57.078] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:57.078] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:20:0) after 1m0s
[10:06:57.105] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:20:0)
[10:06:57.105] Timer tick, asking Buildbucket for the build status
[10:06:57.364] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:57.364] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:21:0) after 1m0s
[10:07:57.391] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:21:0)
[10:07:57.391] Timer tick, asking Buildbucket for the build status
[10:07:59.990] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:59.990] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:22:0) after 1m0s
[10:08:59.970] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:22:0)
[10:08:59.970] Timer tick, asking Buildbucket for the build status
[10:09:00.185] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:00.185] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:23:0) after 1m0s
[10:10:00.243] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:23:0)
[10:10:00.243] Timer tick, asking Buildbucket for the build status
[10:10:00.860] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:00.860] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:24:0) after 1m0s
[10:11:01.027] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:24:0)
[10:11:01.027] Timer tick, asking Buildbucket for the build status
[10:11:02.163] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:02.163] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:25:0) after 1m0s
[10:12:02.257] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:25:0)
[10:12:02.257] Timer tick, asking Buildbucket for the build status
[10:12:03.212] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:03.212] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:26:0) after 1m0s
[10:13:03.249] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:26:0)
[10:13:03.250] Timer tick, asking Buildbucket for the build status
[10:13:03.512] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:03.512] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:27:0) after 1m0s
[10:14:03.528] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:27:0)
[10:14:03.528] Timer tick, asking Buildbucket for the build status
[10:14:03.899] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:03.899] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:28:0) after 1m0s
[10:15:03.984] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:28:0)
[10:15:03.984] Timer tick, asking Buildbucket for the build status
[10:15:04.569] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:04.570] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:29:0) after 1m0s
[10:16:04.570] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:29:0)
[10:16:04.570] Timer tick, asking Buildbucket for the build status
[10:16:04.884] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:04.884] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:30:0) after 1m0s
[10:17:04.997] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:30:0)
[10:17:04.997] Timer tick, asking Buildbucket for the build status
[10:17:05.309] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:05.309] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:31:0) after 1m0s
[10:18:05.333] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:31:0)
[10:18:05.333] Timer tick, asking Buildbucket for the build status
[10:18:05.638] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:05.638] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:32:0) after 1m0s
[10:19:05.718] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:32:0)
[10:19:05.718] Timer tick, asking Buildbucket for the build status
[10:19:06.525] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:06.525] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:33:0) after 1m0s
[10:20:06.618] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:33:0)
[10:20:06.618] Timer tick, asking Buildbucket for the build status
[10:20:08.312] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:08.312] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:34:0) after 1m0s
[10:21:08.408] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:34:0)
[10:21:08.408] Timer tick, asking Buildbucket for the build status
[10:21:09.221] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:09.221] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:35:0) after 1m0s
[10:22:09.285] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:35:0)
[10:22:09.285] Timer tick, asking Buildbucket for the build status
[10:22:10.188] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:10.188] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:36:0) after 1m0s
[10:23:10.260] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:36:0)
[10:23:10.260] Timer tick, asking Buildbucket for the build status
[10:23:10.665] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:10.665] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:37:0) after 1m0s
[10:24:10.764] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:37:0)
[10:24:10.764] Timer tick, asking Buildbucket for the build status
[10:24:10.945] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:10.945] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:38:0) after 1m0s
[10:25:10.975] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:38:0)
[10:25:10.975] Timer tick, asking Buildbucket for the build status
[10:25:11.618] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:11.618] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:39:0) after 1m0s
[10:26:11.643] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:39:0)
[10:26:11.643] Timer tick, asking Buildbucket for the build status
[10:26:12.200] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:12.200] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:40:0) after 1m0s
[10:27:12.225] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:40:0)
[10:27:12.225] Timer tick, asking Buildbucket for the build status
[10:27:12.457] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:12.457] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:41:0) after 1m0s
[10:28:12.498] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:41:0)
[10:28:12.498] Timer tick, asking Buildbucket for the build status
[10:28:13.335] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:13.335] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:42:0) after 1m0s
[10:29:13.383] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:42:0)
[10:29:13.383] Timer tick, asking Buildbucket for the build status
[10:29:14.138] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:14.138] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:43:0) after 1m0s
[10:30:14.201] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:43:0)
[10:30:14.201] Timer tick, asking Buildbucket for the build status
[10:30:14.865] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:14.865] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:44:0) after 1m0s
[10:31:14.926] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:44:0)
[10:31:14.926] Timer tick, asking Buildbucket for the build status
[10:31:15.326] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:15.326] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:45:0) after 1m0s
[10:32:15.408] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:45:0)
[10:32:15.408] Timer tick, asking Buildbucket for the build status
[10:32:15.743] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:15.743] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:46:0) after 1m0s
[10:33:15.779] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:46:0)
[10:33:15.779] Timer tick, asking Buildbucket for the build status
[10:33:16.013] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:16.013] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:47:0) after 1m0s
[10:34:16.094] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:47:0)
[10:34:16.094] Timer tick, asking Buildbucket for the build status
[10:34:16.720] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:16.721] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:48:0) after 1m0s
[10:35:16.752] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:48:0)
[10:35:16.752] Timer tick, asking Buildbucket for the build status
[10:35:16.991] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:16.991] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:49:0) after 1m0s
[10:36:17.031] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:49:0)
[10:36:17.031] Timer tick, asking Buildbucket for the build status
[10:36:17.244] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:17.245] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:50:0) after 1m0s
[10:37:17.331] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:50:0)
[10:37:17.331] Timer tick, asking Buildbucket for the build status
[10:37:18.005] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:18.005] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:51:0) after 1m0s
[10:38:18.028] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:51:0)
[10:38:18.028] Timer tick, asking Buildbucket for the build status
[10:38:20.786] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:20.786] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:52:0) after 1m0s
[10:39:20.824] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:52:0)
[10:39:20.824] Timer tick, asking Buildbucket for the build status
[10:39:23.206] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:23.206] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:53:0) after 1m0s
[10:40:23.208] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:53:0)
[10:40:23.208] Timer tick, asking Buildbucket for the build status
[10:40:23.893] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:23.893] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:54:0) after 1m0s
[10:41:23.920] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:54:0)
[10:41:23.920] Timer tick, asking Buildbucket for the build status
[10:41:24.487] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:24.487] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:55:0) after 1m0s
[10:42:24.597] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:55:0)
[10:42:24.597] Timer tick, asking Buildbucket for the build status
[10:42:24.900] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:24.900] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:56:0) after 1m0s
[10:43:25.088] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:56:0)
[10:43:25.088] Timer tick, asking Buildbucket for the build status
[10:43:25.337] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:25.337] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:57:0) after 1m0s
[10:44:25.418] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:57:0)
[10:44:25.418] Timer tick, asking Buildbucket for the build status
[10:44:25.711] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:25.711] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:58:0) after 1m0s
[10:45:25.759] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:58:0)
[10:45:25.759] Timer tick, asking Buildbucket for the build status
[10:45:28.385] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:28.385] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:59:0) after 1m0s
[10:46:28.511] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:59:0)
[10:46:28.511] Timer tick, asking Buildbucket for the build status
[10:46:30.953] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:30.953] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:60:0) after 1m0s
[10:47:31.084] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:60:0)
[10:47:31.084] Timer tick, asking Buildbucket for the build status
[10:47:31.524] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:31.524] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:61:0) after 1m0s
[10:48:31.799] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:61:0)
[10:48:31.799] Timer tick, asking Buildbucket for the build status
[10:48:32.213] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:32.213] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:62:0) after 1m0s
[10:49:32.437] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:62:0)
[10:49:32.437] Timer tick, asking Buildbucket for the build status
[10:49:32.889] Build 8904071292369780736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:32.889] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9069501355137216000:63:0) after 1m0s
[10:50:06.633] Received PubSub notification, asking Buildbucket for the build status
[10:50:07.073] Build 8904071292369780736: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[10:50:07.073] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1566816604988713",
"created_by": "project:chromium",
"created_ts": "1566812919224516",
"failure_reason": "BUILD_FAILURE",
"id": "8904071292369780736",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"6b0fd0337680fa854ce43a4834c370247827af49\"}}",
"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-27-m80a\", \"branch\": \"refs/heads/master\", \"buildername\": \"Site Isolation Android\", \"buildnumber\": 36511, \"got_angle_revision\": \"3c6b2e1613c844f7265895de5611c0528641d501\", \"got_dawn_revision\": \"25cc723823e3aa9bf6d7edfcd308bb82ab05b0fc\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"6b0fd0337680fa854ce43a4834c370247827af49\", \"got_revision_cp\": \"refs/heads/master@{#690309}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"40ba72e3195abfa78729d496ed5cc951d5941174\", \"got_v8_revision_cp\": \"refs/heads/7.8.189@{#1}\", \"got_webrtc_revision\": \"a2dae38ee7729ec1d6fcb7d22b7a597c627ad81a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28937}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"6b0fd0337680fa854ce43a4834c370247827af49\", \"swarm_hashes\": {\"components_browsertests\": \"95045601e3e732ffb9f2fb13717aad44e59b70a5\", \"components_unittests\": \"9a8025150879fe78d14e10e7e63d4ec5c45e4d67\", \"content_browsertests\": \"3add9c71eaccfa8e918cc4978956935972aafc98\", \"content_unittests\": \"7443b6ebd23ed9e6d4c64812c3af786e1855505f\", \"unit_tests\": \"311c72021b350bc252cad82e38943fa25ad12917\"}, \"swarm_hashes_refs/heads/master(at){#690309}_without_patch\": {\"components_browsertests\": \"95045601e3e732ffb9f2fb13717aad44e59b70a5\", \"components_unittests\": \"9a8025150879fe78d14e10e7e63d4ec5c45e4d67\", \"content_browsertests\": \"3add9c71eaccfa8e918cc4978956935972aafc98\", \"content_unittests\": \"7443b6ebd23ed9e6d4c64812c3af786e1855505f\", \"unit_tests\": \"311c72021b350bc252cad82e38943fa25ad12917\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_73a5b2ce307b316a1240917eaad41a92e4adc44903c6789e05a5f10300306cda_v2\", \"builder_8d9306b59444da386057c91567cca45e610137a5e807a5cceb1e01a42cbb5baa_v2\", \"builder_bea46eaeedb7de1bd52f00a0308b818fe481ae542888a2fa5825ab1ef5ba3493_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-27-m80a\"], \"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": "1566812939002170",
"status": "COMPLETED",
"status_changed_ts": "1566812939515700",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/36511",
"builder:Site Isolation Android",
"buildset:commit/git/6b0fd0337680fa854ce43a4834c370247827af49",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6b0fd0337680fa854ce43a4834c370247827af49",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9069501355137216000",
"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/8904071292369780736/+/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:46e62562ff279810",
"user_agent:luci-scheduler"
],
"updated_ts": "1566816605795030",
"url": "https://ci.chromium.org/b/8904071292369780736",
"utcnow_ts": "1566816607054797"
}
}
[10:50:07.073] Invocation finished in 1h1m29.87173528s with status FAILED