[11:18:10.503] New invocation is queued and will start shortly
[11:18:11.835] Starting the invocation (attempt 1)
[11:18:11.874] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:18:11.874] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:18:11.874] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9049745582166576096",
"parameters_json": "{\"builder_name\":\"Site Isolation Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"bb07474c5ff24db2af0123e2f7f94e3df5d4d036\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Site Isolation Android",
"scheduler_invocation_id:9049745582166576096",
"scheduler_job_id:chromium/Site Isolation Android",
"user_agent:luci-scheduler",
"buildset:commit/git/bb07474c5ff24db2af0123e2f7f94e3df5d4d036",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bb07474c5ff24db2af0123e2f7f94e3df5d4d036",
"gitiles_ref:refs/heads/master"
]
}
[11:18:13.666] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1585653492067491",
"id": "8884315519860488640",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bb07474c5ff24db2af0123e2f7f94e3df5d4d036\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1585653493334307",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/43069",
"builder:Site Isolation Android",
"buildset:commit/git/bb07474c5ff24db2af0123e2f7f94e3df5d4d036",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bb07474c5ff24db2af0123e2f7f94e3df5d4d036",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9049745582166576096",
"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/8884315519860488640/+/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": "1585653493334513",
"url": "https://ci.chromium.org/b/8884315519860488640",
"utcnow_ts": "1585653493654694"
}
}
[11:18:13.666] Task URL: https://ci.chromium.org/b/8884315519860488640
[11:18:13.666] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:2:0) after 1m0s
[11:19:13.687] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:2:0)
[11:19:13.687] Timer tick, asking Buildbucket for the build status
[11:19:13.947] Build 8884315519860488640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:19:13.947] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:3:0) after 1m0s
[11:19:17.733] Received PubSub notification, asking Buildbucket for the build status
[11:19:17.959] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:13.919] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:3:0)
[11:20:13.919] Timer tick, asking Buildbucket for the build status
[11:20:14.192] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:14.192] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:5:0) after 1m0s
[11:21:14.334] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:5:0)
[11:21:14.334] Timer tick, asking Buildbucket for the build status
[11:21:14.489] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:14.489] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:6:0) after 1m0s
[11:22:14.483] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:6:0)
[11:22:14.483] Timer tick, asking Buildbucket for the build status
[11:22:14.693] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:14.693] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:7:0) after 1m0s
[11:23:14.734] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:7:0)
[11:23:14.734] Timer tick, asking Buildbucket for the build status
[11:23:14.960] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:14.960] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:8:0) after 1m0s
[11:24:14.985] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:8:0)
[11:24:14.985] Timer tick, asking Buildbucket for the build status
[11:24:15.125] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:15.125] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:9:0) after 1m0s
[11:25:15.202] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:9:0)
[11:25:15.202] Timer tick, asking Buildbucket for the build status
[11:25:15.374] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:15.374] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:10:0) after 1m0s
[11:26:15.534] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:10:0)
[11:26:15.534] Timer tick, asking Buildbucket for the build status
[11:26:15.874] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:15.874] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:11:0) after 1m0s
[11:27:15.894] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:11:0)
[11:27:15.894] Timer tick, asking Buildbucket for the build status
[11:27:16.126] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:16.126] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:12:0) after 1m0s
[11:28:16.244] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:12:0)
[11:28:16.244] Timer tick, asking Buildbucket for the build status
[11:28:16.720] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:16.720] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:13:0) after 1m0s
[11:29:16.738] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:13:0)
[11:29:16.738] Timer tick, asking Buildbucket for the build status
[11:29:16.852] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:16.853] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:14:0) after 1m0s
[11:30:16.876] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:14:0)
[11:30:16.876] Timer tick, asking Buildbucket for the build status
[11:30:16.998] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:16.998] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:15:0) after 1m0s
[11:31:17.033] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:15:0)
[11:31:17.033] Timer tick, asking Buildbucket for the build status
[11:31:17.229] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:17.229] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:16:0) after 1m0s
[11:32:17.255] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:16:0)
[11:32:17.255] Timer tick, asking Buildbucket for the build status
[11:32:17.489] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:17.489] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:17:0) after 1m0s
[11:33:17.553] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:17:0)
[11:33:17.553] Timer tick, asking Buildbucket for the build status
[11:33:17.724] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:17.724] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:18:0) after 1m0s
[11:34:17.826] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:18:0)
[11:34:17.826] Timer tick, asking Buildbucket for the build status
[11:34:17.959] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:17.959] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:19:0) after 1m0s
[11:35:18.000] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:19:0)
[11:35:18.000] Timer tick, asking Buildbucket for the build status
[11:35:18.213] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:18.213] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:20:0) after 1m0s
[11:36:18.245] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:20:0)
[11:36:18.245] Timer tick, asking Buildbucket for the build status
[11:36:18.392] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:18.392] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:21:0) after 1m0s
[11:37:18.443] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:21:0)
[11:37:18.443] Timer tick, asking Buildbucket for the build status
[11:37:18.937] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:18.937] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:22:0) after 1m0s
[11:38:19.002] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:22:0)
[11:38:19.002] Timer tick, asking Buildbucket for the build status
[11:38:19.327] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:19.327] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:23:0) after 1m0s
[11:39:19.347] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:23:0)
[11:39:19.347] Timer tick, asking Buildbucket for the build status
[11:39:19.474] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:19.474] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:24:0) after 1m0s
[11:40:19.583] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:24:0)
[11:40:19.583] Timer tick, asking Buildbucket for the build status
[11:40:20.332] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:20.332] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:25:0) after 1m0s
[11:41:20.428] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:25:0)
[11:41:20.428] Timer tick, asking Buildbucket for the build status
[11:41:20.534] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:20.534] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:26:0) after 1m0s
[11:42:20.598] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:26:0)
[11:42:20.598] Timer tick, asking Buildbucket for the build status
[11:42:20.735] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:20.735] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:27:0) after 1m0s
[11:43:20.738] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:27:0)
[11:43:20.738] Timer tick, asking Buildbucket for the build status
[11:43:20.921] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:20.921] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:28:0) after 1m0s
[11:44:20.992] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:28:0)
[11:44:20.992] Timer tick, asking Buildbucket for the build status
[11:44:21.311] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:21.311] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:29:0) after 1m0s
[11:45:21.446] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:29:0)
[11:45:21.446] Timer tick, asking Buildbucket for the build status
[11:45:21.736] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:21.736] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:30:0) after 1m0s
[11:46:21.816] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:30:0)
[11:46:21.816] Timer tick, asking Buildbucket for the build status
[11:46:22.018] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:22.018] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:31:0) after 1m0s
[11:47:22.105] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:31:0)
[11:47:22.105] Timer tick, asking Buildbucket for the build status
[11:47:22.460] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:22.460] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:32:0) after 1m0s
[11:48:22.460] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:32:0)
[11:48:22.460] Timer tick, asking Buildbucket for the build status
[11:48:22.848] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:22.848] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:33:0) after 1m0s
[11:49:22.899] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:33:0)
[11:49:22.899] Timer tick, asking Buildbucket for the build status
[11:49:23.181] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:23.181] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:34:0) after 1m0s
[11:50:23.240] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:34:0)
[11:50:23.240] Timer tick, asking Buildbucket for the build status
[11:50:23.488] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:23.488] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:35:0) after 1m0s
[11:51:23.462] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:35:0)
[11:51:23.462] Timer tick, asking Buildbucket for the build status
[11:51:23.879] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:23.879] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:36:0) after 1m0s
[11:52:23.922] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:36:0)
[11:52:23.922] Timer tick, asking Buildbucket for the build status
[11:52:24.042] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:24.042] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:37:0) after 1m0s
[11:53:24.069] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:37:0)
[11:53:24.069] Timer tick, asking Buildbucket for the build status
[11:53:25.411] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:25.411] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:38:0) after 1m0s
[11:54:25.433] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:38:0)
[11:54:25.433] Timer tick, asking Buildbucket for the build status
[11:54:25.626] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:25.626] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:39:0) after 1m0s
[11:55:25.644] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:39:0)
[11:55:25.644] Timer tick, asking Buildbucket for the build status
[11:55:25.980] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:25.980] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:40:0) after 1m0s
[11:56:26.010] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:40:0)
[11:56:26.010] Timer tick, asking Buildbucket for the build status
[11:56:26.451] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:26.451] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:41:0) after 1m0s
[11:57:26.486] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:41:0)
[11:57:26.486] Timer tick, asking Buildbucket for the build status
[11:57:26.600] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:26.600] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:42:0) after 1m0s
[11:58:26.631] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:42:0)
[11:58:26.631] Timer tick, asking Buildbucket for the build status
[11:58:26.992] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:26.992] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:43:0) after 1m0s
[11:59:27.061] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:43:0)
[11:59:27.061] Timer tick, asking Buildbucket for the build status
[11:59:27.265] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:27.265] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:44:0) after 1m0s
[12:00:27.346] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:44:0)
[12:00:27.346] Timer tick, asking Buildbucket for the build status
[12:00:27.495] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:27.495] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:45:0) after 1m0s
[12:01:27.593] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:45:0)
[12:01:27.593] Timer tick, asking Buildbucket for the build status
[12:01:27.880] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:27.880] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:46:0) after 1m0s
[12:02:28.056] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:46:0)
[12:02:28.056] Timer tick, asking Buildbucket for the build status
[12:02:28.444] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:28.444] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:47:0) after 1m0s
[12:03:28.483] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:47:0)
[12:03:28.483] Timer tick, asking Buildbucket for the build status
[12:03:28.863] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:28.864] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:48:0) after 1m0s
[12:04:28.889] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:48:0)
[12:04:28.889] Timer tick, asking Buildbucket for the build status
[12:04:29.076] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:29.076] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:49:0) after 1m0s
[12:05:29.087] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:49:0)
[12:05:29.087] Timer tick, asking Buildbucket for the build status
[12:05:29.607] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:29.607] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:50:0) after 1m0s
[12:06:29.586] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:50:0)
[12:06:29.586] Timer tick, asking Buildbucket for the build status
[12:06:29.730] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:29.730] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:51:0) after 1m0s
[12:07:29.761] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:51:0)
[12:07:29.761] Timer tick, asking Buildbucket for the build status
[12:07:30.207] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:30.207] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:52:0) after 1m0s
[12:08:30.214] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:52:0)
[12:08:30.214] Timer tick, asking Buildbucket for the build status
[12:08:30.349] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:30.349] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:53:0) after 1m0s
[12:09:30.404] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:53:0)
[12:09:30.404] Timer tick, asking Buildbucket for the build status
[12:09:30.570] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:30.570] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:54:0) after 1m0s
[12:10:30.556] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:54:0)
[12:10:30.556] Timer tick, asking Buildbucket for the build status
[12:10:30.734] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:30.734] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:55:0) after 1m0s
[12:11:30.766] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:55:0)
[12:11:30.766] Timer tick, asking Buildbucket for the build status
[12:11:30.996] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:30.996] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:56:0) after 1m0s
[12:12:31.117] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:56:0)
[12:12:31.117] Timer tick, asking Buildbucket for the build status
[12:12:31.263] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:31.263] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:57:0) after 1m0s
[12:13:31.364] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:57:0)
[12:13:31.364] Timer tick, asking Buildbucket for the build status
[12:13:31.829] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:31.829] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:58:0) after 1m0s
[12:14:31.984] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:58:0)
[12:14:31.984] Timer tick, asking Buildbucket for the build status
[12:14:32.134] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:32.134] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:59:0) after 1m0s
[12:15:32.165] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:59:0)
[12:15:32.165] Timer tick, asking Buildbucket for the build status
[12:15:32.391] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:32.391] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:60:0) after 1m0s
[12:16:32.445] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:60:0)
[12:16:32.445] Timer tick, asking Buildbucket for the build status
[12:16:32.592] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:32.592] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:61:0) after 1m0s
[12:17:32.673] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:61:0)
[12:17:32.673] Timer tick, asking Buildbucket for the build status
[12:17:33.079] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:33.079] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:62:0) after 1m0s
[12:18:33.125] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:62:0)
[12:18:33.125] Timer tick, asking Buildbucket for the build status
[12:18:35.237] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:35.237] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:63:0) after 1m0s
[12:19:35.264] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:63:0)
[12:19:35.264] Timer tick, asking Buildbucket for the build status
[12:19:35.648] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:35.648] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:64:0) after 1m0s
[12:20:35.773] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:64:0)
[12:20:35.773] Timer tick, asking Buildbucket for the build status
[12:20:35.937] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:35.937] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:65:0) after 1m0s
[12:21:35.961] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:65:0)
[12:21:35.961] Timer tick, asking Buildbucket for the build status
[12:21:36.092] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:36.092] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:66:0) after 1m0s
[12:22:36.127] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:66:0)
[12:22:36.127] Timer tick, asking Buildbucket for the build status
[12:22:36.283] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:36.283] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:67:0) after 1m0s
[12:23:36.373] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:67:0)
[12:23:36.373] Timer tick, asking Buildbucket for the build status
[12:23:41.313] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:41.313] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:68:0) after 1m0s
[12:24:41.334] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:68:0)
[12:24:41.334] Timer tick, asking Buildbucket for the build status
[12:24:41.764] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:41.764] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:69:0) after 1m0s
[12:25:41.796] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:69:0)
[12:25:41.796] Timer tick, asking Buildbucket for the build status
[12:25:41.938] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:41.938] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:70:0) after 1m0s
[12:26:41.999] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:70:0)
[12:26:41.999] Timer tick, asking Buildbucket for the build status
[12:26:42.181] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:42.181] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:71:0) after 1m0s
[12:27:42.206] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:71:0)
[12:27:42.206] Timer tick, asking Buildbucket for the build status
[12:27:43.554] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:43.554] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:72:0) after 1m0s
[12:28:43.640] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:72:0)
[12:28:43.640] Timer tick, asking Buildbucket for the build status
[12:28:44.254] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:44.254] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:73:0) after 1m0s
[12:29:44.271] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:73:0)
[12:29:44.271] Timer tick, asking Buildbucket for the build status
[12:29:44.799] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:44.799] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:74:0) after 1m0s
[12:30:44.782] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:74:0)
[12:30:44.782] Timer tick, asking Buildbucket for the build status
[12:30:44.883] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:44.883] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:75:0) after 1m0s
[12:31:44.957] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:75:0)
[12:31:44.957] Timer tick, asking Buildbucket for the build status
[12:31:45.128] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:45.128] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:76:0) after 1m0s
[12:32:45.187] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:76:0)
[12:32:45.187] Timer tick, asking Buildbucket for the build status
[12:32:45.334] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:45.334] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:77:0) after 1m0s
[12:33:45.359] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:77:0)
[12:33:45.359] Timer tick, asking Buildbucket for the build status
[12:33:45.692] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:45.692] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:78:0) after 1m0s
[12:34:45.818] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:78:0)
[12:34:45.818] Timer tick, asking Buildbucket for the build status
[12:34:46.130] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:46.130] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:79:0) after 1m0s
[12:35:46.179] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:79:0)
[12:35:46.179] Timer tick, asking Buildbucket for the build status
[12:35:46.388] Build 8884315519860488640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:46.388] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9049745582166576096:80:0) after 1m0s
[12:36:34.910] Received PubSub notification, asking Buildbucket for the build status
[12:36:35.138] Build 8884315519860488640: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[12:36:35.138] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1585658194357934",
"created_by": "project:chromium",
"created_ts": "1585653492067491",
"failure_reason": "BUILD_FAILURE",
"id": "8884315519860488640",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bb07474c5ff24db2af0123e2f7f94e3df5d4d036\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"2 Test Suite(s) failed.\\n\\n**site_per_process_content_browsertests on Android device Nexus 5X** failed because of:\\n\\n- All/BlockedSchemeNavigationBrowserTest.PDF_NavigationFromFrame_Block/1\\n\\n- SiteIsolation/ForceEnabledOriginTrialsBrowserTest.ForceEnabledOriginTrials_MainPage/1\\n\\n**site_per_process_unit_tests on Android device Nexus 5X** failed.\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"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-central1-b-8-104-68qc\", \"branch\": \"refs/heads/master\", \"buildername\": \"Site Isolation Android\", \"buildnumber\": 43069, \"got_angle_revision\": \"bf4a734c7e6ca690f321e2aa7e10e3d37102b194\", \"got_dawn_revision\": \"276b0652658d1bb1037191fe63930b7cf32aeedc\", \"got_nacl_revision\": \"ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\", \"got_revision\": \"bb07474c5ff24db2af0123e2f7f94e3df5d4d036\", \"got_revision_cp\": \"refs/heads/master@{#754869}\", \"got_swarming_client_revision\": \"cc958279ffd6853e0a1b227a7e957ca334fe56af\", \"got_swiftshader_revision\": \"5cf1e9a31c90ccd9ec40d0c5ef1357f6e0ec1cfd\", \"got_v8_revision\": \"056e6aee712040d9139e159d6f409edf82fc2925\", \"got_v8_revision_cp\": \"refs/heads/8.3.103@{#1}\", \"got_webrtc_revision\": \"fa068336f9e04dacb80eee6f19a77e60a5f32398\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30944}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bb07474c5ff24db2af0123e2f7f94e3df5d4d036\", \"swarm_hashes\": {\"components_browsertests\": \"b9285da1e818f0cb7942512fd6e7b9540aed420a\", \"components_unittests\": \"fc4aa198229fa75389424de325d63bae714a457a\", \"content_browsertests\": \"6b354418fbbb7682b249393cc3e85ba52c676096\", \"content_unittests\": \"98a47d4417d429689cea908a419ec56b0ec1ca14\", \"unit_tests\": \"98735b11767ab09ba36e3bc74c7e46851f7428ba\"}, \"swarm_hashes_refs/heads/master(at){#754869}_without_patch\": {\"components_browsertests\": \"b9285da1e818f0cb7942512fd6e7b9540aed420a\", \"components_unittests\": \"fc4aa198229fa75389424de325d63bae714a457a\", \"content_browsertests\": \"6b354418fbbb7682b249393cc3e85ba52c676096\", \"content_unittests\": \"98a47d4417d429689cea908a419ec56b0ec1ca14\", \"unit_tests\": \"98735b11767ab09ba36e3bc74c7e46851f7428ba\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_20aad707bde81044119dd77cf5c9d90dd36ea4d1587e350d19bd0c1a6099dab7_v2\", \"builder_e961d9d7e9bca679490a4aaa8599bd975f9b7e0b713a988ed6895a059d04d2ac_v2\", \"builder_eae3a4dc277b4e2282e9fce002ae6861b5ee5509389502cf3acc9366ec79a2d3_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-104-68qc\"], \"image\": [\"chrome-xenial-20032200-f0f7a76907a\"], \"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\": [\"4910-0007b8d\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"2 Test Suite(s) failed.\\n\\n**site_per_process_content_browsertests on Android device Nexus 5X** failed because of:\\n\\n- All/BlockedSchemeNavigationBrowserTest.PDF_NavigationFromFrame_Block/1\\n\\n- SiteIsolation/ForceEnabledOriginTrialsBrowserTest.ForceEnabledOriginTrials_MainPage/1\\n\\n**site_per_process_unit_tests on Android device Nexus 5X** failed.\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1585653556898432",
"status": "COMPLETED",
"status_changed_ts": "1585653557376143",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/43069",
"builder:Site Isolation Android",
"buildset:commit/git/bb07474c5ff24db2af0123e2f7f94e3df5d4d036",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bb07474c5ff24db2af0123e2f7f94e3df5d4d036",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9049745582166576096",
"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/8884315519860488640/+/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:4b4921b872ef8b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1585658194462534",
"url": "https://ci.chromium.org/b/8884315519860488640",
"utcnow_ts": "1585658195126699"
}
}
[12:36:35.138] Invocation finished in 1h18m24.653482158s with status FAILED