[02:34:34.407] New invocation is queued and will start shortly
[02:34:35.787] Starting the invocation (attempt 1)
[02:34:35.936] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:34:35.937] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:34:35.937] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9057298072545186704",
"parameters_json": "{\"builder_name\":\"Site Isolation Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"342ace1205a6812c17995ef3015ab673ec9b7520\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Site Isolation Android",
"scheduler_invocation_id:9057298072545186704",
"scheduler_job_id:chromium/Site Isolation Android",
"user_agent:luci-scheduler",
"buildset:commit/git/342ace1205a6812c17995ef3015ab673ec9b7520",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/342ace1205a6812c17995ef3015ab673ec9b7520",
"gitiles_ref:refs/heads/master"
]
}
[02:34:37.825] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1578450876460134",
"id": "8891868009723673696",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"342ace1205a6812c17995ef3015ab673ec9b7520\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1578450877631263",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/40616",
"builder:Site Isolation Android",
"buildset:commit/git/342ace1205a6812c17995ef3015ab673ec9b7520",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/342ace1205a6812c17995ef3015ab673ec9b7520",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9057298072545186704",
"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/8891868009723673696/+/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": "1578450877631369",
"url": "https://ci.chromium.org/b/8891868009723673696",
"utcnow_ts": "1578450877778002"
}
}
[02:34:37.825] Task URL: https://ci.chromium.org/b/8891868009723673696
[02:34:37.825] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:2:0) after 1m0s
[02:35:13.188] Received PubSub notification, asking Buildbucket for the build status
[02:35:13.407] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:37.813] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:2:0)
[02:35:37.813] Timer tick, asking Buildbucket for the build status
[02:35:38.036] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:38.036] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:4:0) after 1m0s
[02:36:38.116] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:4:0)
[02:36:38.116] Timer tick, asking Buildbucket for the build status
[02:36:38.348] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:38.348] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:5:0) after 1m0s
[02:37:38.464] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:5:0)
[02:37:38.464] Timer tick, asking Buildbucket for the build status
[02:37:38.876] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:38.876] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:6:0) after 1m0s
[02:38:39.081] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:6:0)
[02:38:39.082] Timer tick, asking Buildbucket for the build status
[02:38:39.617] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:39.617] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:7:0) after 1m0s
[02:39:39.742] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:7:0)
[02:39:39.742] Timer tick, asking Buildbucket for the build status
[02:39:40.195] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:40.195] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:8:0) after 1m0s
[02:40:40.215] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:8:0)
[02:40:40.215] Timer tick, asking Buildbucket for the build status
[02:40:40.484] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:40.484] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:9:0) after 1m0s
[02:41:40.573] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:9:0)
[02:41:40.573] Timer tick, asking Buildbucket for the build status
[02:41:40.993] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:40.993] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:10:0) after 1m0s
[02:42:41.063] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:10:0)
[02:42:41.063] Timer tick, asking Buildbucket for the build status
[02:42:41.225] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:41.225] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:11:0) after 1m0s
[02:43:41.431] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:11:0)
[02:43:41.431] Timer tick, asking Buildbucket for the build status
[02:43:42.601] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:42.601] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:12:0) after 1m0s
[02:44:42.728] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:12:0)
[02:44:42.728] Timer tick, asking Buildbucket for the build status
[02:44:43.245] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:43.245] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:13:0) after 1m0s
[02:45:43.327] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:13:0)
[02:45:43.327] Timer tick, asking Buildbucket for the build status
[02:45:44.038] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:44.038] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:14:0) after 1m0s
[02:46:44.159] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:14:0)
[02:46:44.159] Timer tick, asking Buildbucket for the build status
[02:46:44.303] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:44.303] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:15:0) after 1m0s
[02:47:44.399] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:15:0)
[02:47:44.399] Timer tick, asking Buildbucket for the build status
[02:47:45.386] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:45.386] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:16:0) after 1m0s
[02:48:45.404] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:16:0)
[02:48:45.404] Timer tick, asking Buildbucket for the build status
[02:48:46.384] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:46.384] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:17:0) after 1m0s
[02:49:46.400] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:17:0)
[02:49:46.400] Timer tick, asking Buildbucket for the build status
[02:49:46.615] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:46.615] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:18:0) after 1m0s
[02:50:46.639] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:18:0)
[02:50:46.639] Timer tick, asking Buildbucket for the build status
[02:50:46.841] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:46.841] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:19:0) after 1m0s
[02:51:46.920] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:19:0)
[02:51:46.921] Timer tick, asking Buildbucket for the build status
[02:51:47.164] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:47.164] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:20:0) after 1m0s
[02:52:47.251] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:20:0)
[02:52:47.251] Timer tick, asking Buildbucket for the build status
[02:52:47.426] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:47.426] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:21:0) after 1m0s
[02:53:47.512] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:21:0)
[02:53:47.512] Timer tick, asking Buildbucket for the build status
[02:53:47.772] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:47.772] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:22:0) after 1m0s
[02:54:47.796] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:22:0)
[02:54:47.796] Timer tick, asking Buildbucket for the build status
[02:54:48.061] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:48.061] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:23:0) after 1m0s
[02:55:48.101] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:23:0)
[02:55:48.101] Timer tick, asking Buildbucket for the build status
[02:55:48.336] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:48.336] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:24:0) after 1m0s
[02:56:48.458] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:24:0)
[02:56:48.458] Timer tick, asking Buildbucket for the build status
[02:56:48.584] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:48.584] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:25:0) after 1m0s
[02:57:48.641] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:25:0)
[02:57:48.641] Timer tick, asking Buildbucket for the build status
[02:57:48.831] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:48.831] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:26:0) after 1m0s
[02:58:48.936] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:26:0)
[02:58:48.936] Timer tick, asking Buildbucket for the build status
[02:58:49.218] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:49.218] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:27:0) after 1m0s
[02:59:49.270] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:27:0)
[02:59:49.270] Timer tick, asking Buildbucket for the build status
[02:59:49.420] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:49.420] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:28:0) after 1m0s
[03:00:49.507] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:28:0)
[03:00:49.507] Timer tick, asking Buildbucket for the build status
[03:00:50.059] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:50.059] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:29:0) after 1m0s
[03:01:50.146] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:29:0)
[03:01:50.146] Timer tick, asking Buildbucket for the build status
[03:01:51.208] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:51.208] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:30:0) after 1m0s
[03:02:51.229] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:30:0)
[03:02:51.229] Timer tick, asking Buildbucket for the build status
[03:02:51.449] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:51.449] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:31:0) after 1m0s
[03:03:51.492] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:31:0)
[03:03:51.492] Timer tick, asking Buildbucket for the build status
[03:03:51.673] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:51.673] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:32:0) after 1m0s
[03:04:51.840] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:32:0)
[03:04:51.840] Timer tick, asking Buildbucket for the build status
[03:04:53.916] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:53.917] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:33:0) after 1m0s
[03:05:54.001] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:33:0)
[03:05:54.001] Timer tick, asking Buildbucket for the build status
[03:05:54.239] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:54.239] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:34:0) after 1m0s
[03:06:54.292] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:34:0)
[03:06:54.292] Timer tick, asking Buildbucket for the build status
[03:06:55.161] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:55.161] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:35:0) after 1m0s
[03:07:55.231] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:35:0)
[03:07:55.231] Timer tick, asking Buildbucket for the build status
[03:07:55.398] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:55.398] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:36:0) after 1m0s
[03:08:55.492] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:36:0)
[03:08:55.492] Timer tick, asking Buildbucket for the build status
[03:08:55.745] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:55.745] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:37:0) after 1m0s
[03:09:55.870] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:37:0)
[03:09:55.870] Timer tick, asking Buildbucket for the build status
[03:09:56.309] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:56.309] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:38:0) after 1m0s
[03:10:56.404] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:38:0)
[03:10:56.404] Timer tick, asking Buildbucket for the build status
[03:10:56.586] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:56.586] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:39:0) after 1m0s
[03:11:56.588] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:39:0)
[03:11:56.588] Timer tick, asking Buildbucket for the build status
[03:11:56.835] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:56.835] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:40:0) after 1m0s
[03:12:56.882] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:40:0)
[03:12:56.882] Timer tick, asking Buildbucket for the build status
[03:12:57.079] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:57.079] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:41:0) after 1m0s
[03:13:57.100] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:41:0)
[03:13:57.100] Timer tick, asking Buildbucket for the build status
[03:13:57.381] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:57.381] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:42:0) after 1m0s
[03:14:57.480] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:42:0)
[03:14:57.480] Timer tick, asking Buildbucket for the build status
[03:14:57.932] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:57.932] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:43:0) after 1m0s
[03:15:58.043] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:43:0)
[03:15:58.043] Timer tick, asking Buildbucket for the build status
[03:15:58.943] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:58.943] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:44:0) after 1m0s
[03:16:59.011] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:44:0)
[03:16:59.011] Timer tick, asking Buildbucket for the build status
[03:16:59.316] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:59.316] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:45:0) after 1m0s
[03:17:59.351] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:45:0)
[03:17:59.351] Timer tick, asking Buildbucket for the build status
[03:17:59.658] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:59.658] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:46:0) after 1m0s
[03:18:59.717] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:46:0)
[03:18:59.717] Timer tick, asking Buildbucket for the build status
[03:19:00.089] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:00.089] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:47:0) after 1m0s
[03:20:00.110] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:47:0)
[03:20:00.110] Timer tick, asking Buildbucket for the build status
[03:20:00.620] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:00.620] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:48:0) after 1m0s
[03:21:00.719] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:48:0)
[03:21:00.719] Timer tick, asking Buildbucket for the build status
[03:21:00.864] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:00.864] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:49:0) after 1m0s
[03:22:00.890] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:49:0)
[03:22:00.890] Timer tick, asking Buildbucket for the build status
[03:22:01.055] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:01.055] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:50:0) after 1m0s
[03:23:01.116] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:50:0)
[03:23:01.116] Timer tick, asking Buildbucket for the build status
[03:23:01.588] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:01.588] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:51:0) after 1m0s
[03:24:01.622] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:51:0)
[03:24:01.622] Timer tick, asking Buildbucket for the build status
[03:24:03.628] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:03.628] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:52:0) after 1m0s
[03:25:03.645] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:52:0)
[03:25:03.645] Timer tick, asking Buildbucket for the build status
[03:25:04.194] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:04.194] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:53:0) after 1m0s
[03:26:04.375] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:53:0)
[03:26:04.375] Timer tick, asking Buildbucket for the build status
[03:26:04.673] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:04.673] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:54:0) after 1m0s
[03:27:04.705] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:54:0)
[03:27:04.705] Timer tick, asking Buildbucket for the build status
[03:27:05.355] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:05.355] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:55:0) after 1m0s
[03:28:05.393] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:55:0)
[03:28:05.393] Timer tick, asking Buildbucket for the build status
[03:28:05.621] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:05.621] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:56:0) after 1m0s
[03:29:05.681] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:56:0)
[03:29:05.681] Timer tick, asking Buildbucket for the build status
[03:29:06.036] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:06.036] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:57:0) after 1m0s
[03:30:06.022] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:57:0)
[03:30:06.022] Timer tick, asking Buildbucket for the build status
[03:30:06.256] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:06.256] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:58:0) after 1m0s
[03:31:06.372] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:58:0)
[03:31:06.372] Timer tick, asking Buildbucket for the build status
[03:31:06.719] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:06.719] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:59:0) after 1m0s
[03:32:06.750] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:59:0)
[03:32:06.750] Timer tick, asking Buildbucket for the build status
[03:32:07.269] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:07.269] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:60:0) after 1m0s
[03:33:07.267] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:60:0)
[03:33:07.267] Timer tick, asking Buildbucket for the build status
[03:33:07.471] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:07.471] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:61:0) after 1m0s
[03:34:07.482] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:61:0)
[03:34:07.482] Timer tick, asking Buildbucket for the build status
[03:34:07.777] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:07.777] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:62:0) after 1m0s
[03:35:07.804] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:62:0)
[03:35:07.804] Timer tick, asking Buildbucket for the build status
[03:35:07.986] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:07.986] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:63:0) after 1m0s
[03:36:08.028] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:63:0)
[03:36:08.028] Timer tick, asking Buildbucket for the build status
[03:36:08.594] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:08.594] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:64:0) after 1m0s
[03:37:08.620] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:64:0)
[03:37:08.620] Timer tick, asking Buildbucket for the build status
[03:37:08.797] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:08.797] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:65:0) after 1m0s
[03:38:08.814] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:65:0)
[03:38:08.814] Timer tick, asking Buildbucket for the build status
[03:38:09.011] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:09.011] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:66:0) after 1m0s
[03:39:09.039] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:66:0)
[03:39:09.039] Timer tick, asking Buildbucket for the build status
[03:39:09.766] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:09.766] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:67:0) after 1m0s
[03:40:09.813] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:67:0)
[03:40:09.813] Timer tick, asking Buildbucket for the build status
[03:40:10.162] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:10.162] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:68:0) after 1m0s
[03:41:10.257] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:68:0)
[03:41:10.257] Timer tick, asking Buildbucket for the build status
[03:41:10.455] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:10.455] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:69:0) after 1m0s
[03:42:10.528] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:69:0)
[03:42:10.528] Timer tick, asking Buildbucket for the build status
[03:42:10.753] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:10.753] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:70:0) after 1m0s
[03:43:10.780] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:70:0)
[03:43:10.780] Timer tick, asking Buildbucket for the build status
[03:43:10.927] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:10.927] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:71:0) after 1m0s
[03:44:10.958] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:71:0)
[03:44:10.958] Timer tick, asking Buildbucket for the build status
[03:44:11.753] Build 8891868009723673696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:11.753] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9057298072545186704:72:0) after 1m0s
[03:45:10.866] Received PubSub notification, asking Buildbucket for the build status
[03:45:11.167] Build 8891868009723673696: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[03:45:11.168] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1578455110273531",
"created_by": "project:chromium",
"created_ts": "1578450876460134",
"failure_reason": "BUILD_FAILURE",
"id": "8891868009723673696",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"342ace1205a6812c17995ef3015ab673ec9b7520\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 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_TopFrameHasBlockedScheme_Block/1\\n\\n- All/BlockedSchemeNavigationBrowserTest.PDF_NavigationFromFrame_Block/1\"}, \"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-51-1jze\", \"branch\": \"refs/heads/master\", \"buildername\": \"Site Isolation Android\", \"buildnumber\": 40616, \"got_angle_revision\": \"db49bc7dd0759fac8cb4065aa2afc3172aed7948\", \"got_dawn_revision\": \"d1cba106c84920f5b236f553615b7293d3d3a814\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"342ace1205a6812c17995ef3015ab673ec9b7520\", \"got_revision_cp\": \"refs/heads/master@{#729195}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"43e33165a8f03a9b85b782f3a87bf49fbd90b25b\", \"got_v8_revision\": \"8f9a888e1a66be718fc1fd3e2553b6c0f5a48728\", \"got_v8_revision_cp\": \"refs/heads/8.1.107@{#1}\", \"got_webrtc_revision\": \"75b58972cbb788300b23a93bcb92012343d5606b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30169}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"342ace1205a6812c17995ef3015ab673ec9b7520\", \"swarm_hashes\": {\"components_browsertests\": \"b61fb962b692c7be054362801fce3f9d9b97c1a9\", \"components_unittests\": \"514bd4ded68e629d1b0d6cbbe83358850d929b0a\", \"content_browsertests\": \"2d860ba082711aae6853baa13696b795e80c58e4\", \"content_unittests\": \"0e09ca24021d6b1222738c30fde5c280a8d91019\", \"unit_tests\": \"a4cff5c4f204d15571b9ffec4115da5260b13d04\"}, \"swarm_hashes_refs/heads/master(at){#729195}_without_patch\": {\"components_browsertests\": \"b61fb962b692c7be054362801fce3f9d9b97c1a9\", \"components_unittests\": \"514bd4ded68e629d1b0d6cbbe83358850d929b0a\", \"content_browsertests\": \"2d860ba082711aae6853baa13696b795e80c58e4\", \"content_unittests\": \"0e09ca24021d6b1222738c30fde5c280a8d91019\", \"unit_tests\": \"a4cff5c4f204d15571b9ffec4115da5260b13d04\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_360e090537af5d0e85670c6ae42c4dc4bbec8921cc650184022f9d46f257e3c4_v2\", \"git\", \"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-51-1jze\"], \"image\": [\"chrome-xenial-19121500-b03549ec744\"], \"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\": [\"4733-6bd8c41\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 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_TopFrameHasBlockedScheme_Block/1\\n\\n- All/BlockedSchemeNavigationBrowserTest.PDF_NavigationFromFrame_Block/1\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1578450911794711",
"status": "COMPLETED",
"status_changed_ts": "1578450912714165",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/40616",
"builder:Site Isolation Android",
"buildset:commit/git/342ace1205a6812c17995ef3015ab673ec9b7520",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/342ace1205a6812c17995ef3015ab673ec9b7520",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9057298072545186704",
"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/8891868009723673696/+/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:499bd2a02a324f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1578455110561068",
"url": "https://ci.chromium.org/b/8891868009723673696",
"utcnow_ts": "1578455111143937"
}
}
[03:45:11.168] Invocation finished in 1h10m36.783065463s with status FAILED