[15:34:37.899] New invocation is queued and will start shortly
[15:34:39.460] Starting the invocation (attempt 1)
[15:34:39.508] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:34:39.508] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:34:39.508] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9076908537289459808",
"parameters_json": "{\"builder_name\":\"Site Isolation Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"4f992f236cebf86a336796cda6cbf5fcd74602a2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Site Isolation Android",
"scheduler_invocation_id:9076908537289459808",
"scheduler_job_id:chromium/Site Isolation Android",
"user_agent:luci-scheduler",
"buildset:commit/git/4f992f236cebf86a336796cda6cbf5fcd74602a2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4f992f236cebf86a336796cda6cbf5fcd74602a2",
"gitiles_ref:refs/heads/master"
]
}
[15:34:41.366] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1559748879822068",
"id": "8911478474549610352",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"4f992f236cebf86a336796cda6cbf5fcd74602a2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1559748880854168",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/34590",
"builder:Site Isolation Android",
"buildset:commit/git/4f992f236cebf86a336796cda6cbf5fcd74602a2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4f992f236cebf86a336796cda6cbf5fcd74602a2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9076908537289459808",
"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/8911478474549610352/+/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": "1559748880854269",
"url": "https://ci.chromium.org/b/8911478474549610352",
"utcnow_ts": "1559748881341669"
}
}
[15:34:41.366] Task URL: https://ci.chromium.org/b/8911478474549610352
[15:34:41.366] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:2:0) after 1m0s
[15:35:41.458] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:2:0)
[15:35:41.458] Timer tick, asking Buildbucket for the build status
[15:35:41.794] Build 8911478474549610352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:35:41.794] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:3:0) after 1m0s
[15:36:41.838] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:3:0)
[15:36:41.838] Timer tick, asking Buildbucket for the build status
[15:36:42.341] Build 8911478474549610352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:36:42.341] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:4:0) after 1m0s
[15:36:45.110] Received PubSub notification, asking Buildbucket for the build status
[15:36:45.319] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:42.378] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:4:0)
[15:37:42.378] Timer tick, asking Buildbucket for the build status
[15:37:43.009] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:43.009] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:6:0) after 1m0s
[15:38:43.106] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:6:0)
[15:38:43.106] Timer tick, asking Buildbucket for the build status
[15:38:43.467] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:43.467] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:7:0) after 1m0s
[15:39:43.475] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:7:0)
[15:39:43.475] Timer tick, asking Buildbucket for the build status
[15:39:43.580] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:43.580] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:8:0) after 1m0s
[15:40:43.642] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:8:0)
[15:40:43.642] Timer tick, asking Buildbucket for the build status
[15:40:46.410] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:46.410] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:9:0) after 1m0s
[15:41:46.424] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:9:0)
[15:41:46.424] Timer tick, asking Buildbucket for the build status
[15:41:48.790] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:48.790] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:10:0) after 1m0s
[15:42:48.853] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:10:0)
[15:42:48.853] Timer tick, asking Buildbucket for the build status
[15:42:48.993] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:48.993] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:11:0) after 1m0s
[15:43:49.015] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:11:0)
[15:43:49.015] Timer tick, asking Buildbucket for the build status
[15:43:50.242] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:50.242] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:12:0) after 1m0s
[15:44:50.400] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:12:0)
[15:44:50.400] Timer tick, asking Buildbucket for the build status
[15:44:52.374] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:52.374] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:13:0) after 1m0s
[15:45:52.431] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:13:0)
[15:45:52.431] Timer tick, asking Buildbucket for the build status
[15:45:52.793] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:52.793] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:14:0) after 1m0s
[15:46:52.858] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:14:0)
[15:46:52.859] Timer tick, asking Buildbucket for the build status
[15:46:53.985] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:53.985] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:15:0) after 1m0s
[15:47:54.012] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:15:0)
[15:47:54.012] Timer tick, asking Buildbucket for the build status
[15:47:54.250] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:54.250] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:16:0) after 1m0s
[15:48:54.268] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:16:0)
[15:48:54.268] Timer tick, asking Buildbucket for the build status
[15:48:54.372] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:54.372] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:17:0) after 1m0s
[15:49:54.481] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:17:0)
[15:49:54.481] Timer tick, asking Buildbucket for the build status
[15:49:54.960] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:54.960] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:18:0) after 1m0s
[15:50:55.059] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:18:0)
[15:50:55.059] Timer tick, asking Buildbucket for the build status
[15:50:55.790] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:55.790] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:19:0) after 1m0s
[15:51:55.850] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:19:0)
[15:51:55.850] Timer tick, asking Buildbucket for the build status
[15:51:58.178] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:58.178] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:20:0) after 1m0s
[15:52:58.221] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:20:0)
[15:52:58.221] Timer tick, asking Buildbucket for the build status
[15:52:58.770] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:58.770] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:21:0) after 1m0s
[15:53:58.794] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:21:0)
[15:53:58.794] Timer tick, asking Buildbucket for the build status
[15:54:01.119] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:01.119] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:22:0) after 1m0s
[15:55:01.221] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:22:0)
[15:55:01.221] Timer tick, asking Buildbucket for the build status
[15:55:01.637] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:01.637] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:23:0) after 1m0s
[15:56:01.665] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:23:0)
[15:56:01.665] Timer tick, asking Buildbucket for the build status
[15:56:02.398] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:02.398] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:24:0) after 1m0s
[15:57:02.417] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:24:0)
[15:57:02.417] Timer tick, asking Buildbucket for the build status
[15:57:02.598] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:02.598] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:25:0) after 1m0s
[15:58:02.629] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:25:0)
[15:58:02.629] Timer tick, asking Buildbucket for the build status
[15:58:04.089] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:04.089] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:26:0) after 1m0s
[15:59:04.236] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:26:0)
[15:59:04.236] Timer tick, asking Buildbucket for the build status
[15:59:04.769] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:04.769] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:27:0) after 1m0s
[16:00:04.906] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:27:0)
[16:00:04.906] Timer tick, asking Buildbucket for the build status
[16:00:05.441] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:05.441] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:28:0) after 1m0s
[16:01:05.507] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:28:0)
[16:01:05.507] Timer tick, asking Buildbucket for the build status
[16:01:06.360] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:06.360] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:29:0) after 1m0s
[16:02:06.483] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:29:0)
[16:02:06.483] Timer tick, asking Buildbucket for the build status
[16:02:06.887] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:06.887] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:30:0) after 1m0s
[16:03:06.934] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:30:0)
[16:03:06.934] Timer tick, asking Buildbucket for the build status
[16:03:07.558] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:07.558] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:31:0) after 1m0s
[16:04:07.613] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:31:0)
[16:04:07.613] Timer tick, asking Buildbucket for the build status
[16:04:09.742] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:09.742] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:32:0) after 1m0s
[16:05:09.721] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:32:0)
[16:05:09.721] Timer tick, asking Buildbucket for the build status
[16:05:09.830] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:09.830] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:33:0) after 1m0s
[16:06:09.952] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:33:0)
[16:06:09.952] Timer tick, asking Buildbucket for the build status
[16:06:11.264] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:11.264] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:34:0) after 1m0s
[16:07:11.295] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:34:0)
[16:07:11.295] Timer tick, asking Buildbucket for the build status
[16:07:11.480] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:11.480] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:35:0) after 1m0s
[16:08:11.499] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:35:0)
[16:08:11.499] Timer tick, asking Buildbucket for the build status
[16:08:11.912] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:11.912] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:36:0) after 1m0s
[16:09:12.051] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:36:0)
[16:09:12.051] Timer tick, asking Buildbucket for the build status
[16:09:12.790] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:12.790] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:37:0) after 1m0s
[16:10:12.863] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:37:0)
[16:10:12.863] Timer tick, asking Buildbucket for the build status
[16:10:13.060] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:13.060] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:38:0) after 1m0s
[16:11:13.191] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:38:0)
[16:11:13.191] Timer tick, asking Buildbucket for the build status
[16:11:14.030] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:14.030] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:39:0) after 1m0s
[16:12:14.057] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:39:0)
[16:12:14.057] Timer tick, asking Buildbucket for the build status
[16:12:14.208] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:14.208] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:40:0) after 1m0s
[16:13:14.278] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:40:0)
[16:13:14.278] Timer tick, asking Buildbucket for the build status
[16:13:14.861] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:14.861] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:41:0) after 1m0s
[16:14:14.892] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:41:0)
[16:14:14.892] Timer tick, asking Buildbucket for the build status
[16:14:15.695] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:15.695] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:42:0) after 1m0s
[16:15:15.678] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:42:0)
[16:15:15.678] Timer tick, asking Buildbucket for the build status
[16:15:15.785] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:15.785] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:43:0) after 1m0s
[16:16:15.809] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:43:0)
[16:16:15.809] Timer tick, asking Buildbucket for the build status
[16:16:16.324] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:16.324] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:44:0) after 1m0s
[16:17:16.361] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:44:0)
[16:17:16.361] Timer tick, asking Buildbucket for the build status
[16:17:16.534] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:16.534] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:45:0) after 1m0s
[16:18:16.584] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:45:0)
[16:18:16.584] Timer tick, asking Buildbucket for the build status
[16:18:17.319] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:17.319] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:46:0) after 1m0s
[16:19:17.390] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:46:0)
[16:19:17.390] Timer tick, asking Buildbucket for the build status
[16:19:17.960] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:17.960] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:47:0) after 1m0s
[16:20:18.052] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:47:0)
[16:20:18.052] Timer tick, asking Buildbucket for the build status
[16:20:19.645] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:19.645] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:48:0) after 1m0s
[16:21:19.715] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:48:0)
[16:21:19.715] Timer tick, asking Buildbucket for the build status
[16:21:20.741] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:20.741] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:49:0) after 1m0s
[16:22:20.795] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:49:0)
[16:22:20.795] Timer tick, asking Buildbucket for the build status
[16:22:22.347] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:22.347] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:50:0) after 1m0s
[16:23:22.371] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:50:0)
[16:23:22.371] Timer tick, asking Buildbucket for the build status
[16:23:22.548] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:22.548] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:51:0) after 1m0s
[16:24:22.566] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:51:0)
[16:24:22.566] Timer tick, asking Buildbucket for the build status
[16:24:23.474] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:23.474] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:52:0) after 1m0s
[16:25:23.513] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:52:0)
[16:25:23.513] Timer tick, asking Buildbucket for the build status
[16:25:23.875] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:23.875] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:53:0) after 1m0s
[16:26:23.939] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:53:0)
[16:26:23.939] Timer tick, asking Buildbucket for the build status
[16:26:24.777] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:24.777] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:54:0) after 1m0s
[16:27:24.800] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:54:0)
[16:27:24.800] Timer tick, asking Buildbucket for the build status
[16:27:25.381] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:25.381] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:55:0) after 1m0s
[16:28:25.408] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:55:0)
[16:28:25.408] Timer tick, asking Buildbucket for the build status
[16:28:25.718] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:25.718] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:56:0) after 1m0s
[16:29:25.748] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:56:0)
[16:29:25.748] Timer tick, asking Buildbucket for the build status
[16:29:26.052] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:26.052] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:57:0) after 1m0s
[16:30:26.095] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:57:0)
[16:30:26.095] Timer tick, asking Buildbucket for the build status
[16:30:27.769] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:27.769] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:58:0) after 1m0s
[16:31:27.789] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:58:0)
[16:31:27.789] Timer tick, asking Buildbucket for the build status
[16:31:29.966] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:29.966] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:59:0) after 1m0s
[16:32:30.023] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:59:0)
[16:32:30.023] Timer tick, asking Buildbucket for the build status
[16:32:30.195] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:30.195] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:60:0) after 1m0s
[16:33:30.184] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:60:0)
[16:33:30.184] Timer tick, asking Buildbucket for the build status
[16:33:30.523] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:30.523] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:61:0) after 1m0s
[16:34:30.629] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:61:0)
[16:34:30.629] Timer tick, asking Buildbucket for the build status
[16:34:30.866] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:30.866] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:62:0) after 1m0s
[16:35:30.892] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:62:0)
[16:35:30.892] Timer tick, asking Buildbucket for the build status
[16:35:32.438] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:32.438] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:63:0) after 1m0s
[16:36:32.520] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:63:0)
[16:36:32.520] Timer tick, asking Buildbucket for the build status
[16:36:33.977] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:33.977] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:64:0) after 1m0s
[16:37:34.016] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:64:0)
[16:37:34.016] Timer tick, asking Buildbucket for the build status
[16:37:36.536] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:36.536] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:65:0) after 1m0s
[16:38:36.687] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:65:0)
[16:38:36.687] Timer tick, asking Buildbucket for the build status
[16:38:38.258] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:38.258] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:66:0) after 1m0s
[16:39:38.507] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:66:0)
[16:39:38.507] Timer tick, asking Buildbucket for the build status
[16:39:38.683] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:38.683] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:67:0) after 1m0s
[16:40:38.936] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:67:0)
[16:40:38.936] Timer tick, asking Buildbucket for the build status
[16:40:39.344] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:39.344] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:68:0) after 1m0s
[16:41:39.740] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:68:0)
[16:41:39.740] Timer tick, asking Buildbucket for the build status
[16:41:39.891] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:39.891] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:69:0) after 1m0s
[16:42:40.162] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:69:0)
[16:42:40.162] Timer tick, asking Buildbucket for the build status
[16:42:41.112] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:41.112] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:70:0) after 1m0s
[16:43:41.365] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:70:0)
[16:43:41.365] Timer tick, asking Buildbucket for the build status
[16:43:41.638] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:41.638] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:71:0) after 1m0s
[16:44:41.704] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:71:0)
[16:44:41.704] Timer tick, asking Buildbucket for the build status
[16:44:42.134] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:42.134] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:72:0) after 1m0s
[16:45:42.264] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:72:0)
[16:45:42.264] Timer tick, asking Buildbucket for the build status
[16:45:43.548] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:43.548] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:73:0) after 1m0s
[16:46:43.643] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:73:0)
[16:46:43.643] Timer tick, asking Buildbucket for the build status
[16:46:43.886] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:43.886] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:74:0) after 1m0s
[16:47:43.958] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:74:0)
[16:47:43.958] Timer tick, asking Buildbucket for the build status
[16:47:46.200] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:46.200] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:75:0) after 1m0s
[16:48:46.323] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:75:0)
[16:48:46.323] Timer tick, asking Buildbucket for the build status
[16:48:48.161] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:48.161] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:76:0) after 1m0s
[16:49:48.211] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:76:0)
[16:49:48.211] Timer tick, asking Buildbucket for the build status
[16:49:49.911] Build 8911478474549610352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:49.911] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9076908537289459808:77:0) after 1m0s
[16:50:09.198] Received PubSub notification, asking Buildbucket for the build status
[16:50:09.735] Build 8911478474549610352: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[16:50:09.735] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1559753408761615",
"created_by": "project:chromium",
"created_ts": "1559748879822068",
"failure_reason": "BUILD_FAILURE",
"id": "8911478474549610352",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"4f992f236cebf86a336796cda6cbf5fcd74602a2\"}}",
"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\": {\"$build/goma\": {\"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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\": 34590, \"got_angle_revision\": \"4a75741655d330e257b2f20d7404cce1e51ef72e\", \"got_dawn_revision\": \"b632bc58ed373eb2020af65e0fdeaf07d46d1a9c\", \"got_nacl_revision\": \"0ddc033406886a709b901e0c312872529f9705e8\", \"got_revision\": \"4f992f236cebf86a336796cda6cbf5fcd74602a2\", \"got_revision_cp\": \"refs/heads/master@{#666296}\", \"got_swarming_client_revision\": \"9b1b0ed1f31dd42b128ae88ce50c2ab1f87ebaeb\", \"got_v8_revision\": \"13da560bef16ab254fe91e85541f8dd4db6c337a\", \"got_v8_revision_cp\": \"refs/heads/7.7.18@{#1}\", \"got_webrtc_revision\": \"479a3c0f92b957a9ba1c242e7fe6e4d37b49ce32\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28154}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"4f992f236cebf86a336796cda6cbf5fcd74602a2\", \"swarm_hashes\": {\"components_browsertests\": \"1950ea6774a3cbda485d22f7fbaf99f630ed1e13\", \"components_unittests\": \"4909296007f0c07fcc359b873f2b11a1694328c1\", \"content_browsertests\": \"c86845e5b396d2f8da61c1b0a4c9aa6f33c196dd\", \"content_unittests\": \"59ca198df222ea213b88c14a7576e34655c2aafd\", \"unit_tests\": \"733adbc05212eb628005736ad0cdbef3d139d1ba\"}, \"swarm_hashes_refs/heads/master(at){#666296}_without_patch\": {\"components_browsertests\": \"1950ea6774a3cbda485d22f7fbaf99f630ed1e13\", \"components_unittests\": \"4909296007f0c07fcc359b873f2b11a1694328c1\", \"content_browsertests\": \"c86845e5b396d2f8da61c1b0a4c9aa6f33c196dd\", \"content_unittests\": \"59ca198df222ea213b88c14a7576e34655c2aafd\", \"unit_tests\": \"733adbc05212eb628005736ad0cdbef3d139d1ba\"}}, \"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\": [\"4316-effbcfc\"], \"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": "1559749003836165",
"status": "COMPLETED",
"status_changed_ts": "1559749004810396",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/34590",
"builder:Site Isolation Android",
"buildset:commit/git/4f992f236cebf86a336796cda6cbf5fcd74602a2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4f992f236cebf86a336796cda6cbf5fcd74602a2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9076908537289459808",
"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/8911478474549610352/+/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:454118b8cf1fb110",
"user_agent:luci-scheduler"
],
"updated_ts": "1559753408826046",
"url": "https://ci.chromium.org/b/8911478474549610352",
"utcnow_ts": "1559753409719725"
}
}
[16:50:09.735] Invocation finished in 1h15m31.909031733s with status FAILED