[23:33:56.488] New invocation is queued and will start shortly
[23:33:57.576] Starting the invocation (attempt 1)
[23:33:57.610] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:33:57.610] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:33:57.611] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9073073309079149152",
"parameters_json": "{\"builder_name\":\"Site Isolation Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"a343db33b1ded331a1a83cb2f940a90493f2cdeb\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Site Isolation Android",
"scheduler_invocation_id:9073073309079149152",
"scheduler_job_id:chromium/Site Isolation Android",
"user_agent:luci-scheduler",
"buildset:commit/git/a343db33b1ded331a1a83cb2f940a90493f2cdeb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a343db33b1ded331a1a83cb2f940a90493f2cdeb",
"gitiles_ref:refs/heads/master"
]
}
[23:33:58.386] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1563406437783435",
"id": "8907643247053307376",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a343db33b1ded331a1a83cb2f940a90493f2cdeb\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1563406438252053",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/35654",
"builder:Site Isolation Android",
"buildset:commit/git/a343db33b1ded331a1a83cb2f940a90493f2cdeb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a343db33b1ded331a1a83cb2f940a90493f2cdeb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9073073309079149152",
"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/8907643247053307376/+/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": "1563406438252156",
"url": "https://ci.chromium.org/b/8907643247053307376",
"utcnow_ts": "1563406438373969"
}
}
[23:33:58.386] Task URL: https://ci.chromium.org/b/8907643247053307376
[23:33:58.386] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:2:0) after 1m0s
[23:34:53.370] Received PubSub notification, asking Buildbucket for the build status
[23:34:56.862] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:58.411] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:2:0)
[23:34:58.411] Timer tick, asking Buildbucket for the build status
[23:34:58.645] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:58.645] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:4:0) after 1m0s
[23:35:59.037] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:4:0)
[23:35:59.037] Timer tick, asking Buildbucket for the build status
[23:36:00.719] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:00.719] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:5:0) after 1m0s
[23:37:00.962] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:5:0)
[23:37:00.962] Timer tick, asking Buildbucket for the build status
[23:37:01.696] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:01.696] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:6:0) after 1m0s
[23:38:01.807] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:6:0)
[23:38:01.807] Timer tick, asking Buildbucket for the build status
[23:38:02.072] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:02.072] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:7:0) after 1m0s
[23:39:02.270] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:7:0)
[23:39:02.270] Timer tick, asking Buildbucket for the build status
[23:39:02.504] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:02.504] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:8:0) after 1m0s
[23:40:02.743] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:8:0)
[23:40:02.743] Timer tick, asking Buildbucket for the build status
[23:40:05.081] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:05.081] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:9:0) after 1m0s
[23:41:05.323] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:9:0)
[23:41:05.323] Timer tick, asking Buildbucket for the build status
[23:41:05.990] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:05.990] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:10:0) after 1m0s
[23:42:06.273] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:10:0)
[23:42:06.273] Timer tick, asking Buildbucket for the build status
[23:42:08.959] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:08.959] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:11:0) after 1m0s
[23:43:08.979] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:11:0)
[23:43:08.979] Timer tick, asking Buildbucket for the build status
[23:43:10.349] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:10.349] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:12:0) after 1m0s
[23:44:10.492] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:12:0)
[23:44:10.492] Timer tick, asking Buildbucket for the build status
[23:44:13.143] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:13.143] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:13:0) after 1m0s
[23:45:13.253] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:13:0)
[23:45:13.253] Timer tick, asking Buildbucket for the build status
[23:45:13.425] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:13.425] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:14:0) after 1m0s
[23:46:13.409] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:14:0)
[23:46:13.409] Timer tick, asking Buildbucket for the build status
[23:46:13.917] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:13.917] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:15:0) after 1m0s
[23:47:14.052] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:15:0)
[23:47:14.052] Timer tick, asking Buildbucket for the build status
[23:47:14.342] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:14.342] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:16:0) after 1m0s
[23:48:14.370] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:16:0)
[23:48:14.370] Timer tick, asking Buildbucket for the build status
[23:48:14.498] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:14.498] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:17:0) after 1m0s
[23:49:14.599] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:17:0)
[23:49:14.599] Timer tick, asking Buildbucket for the build status
[23:49:18.583] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:18.583] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:18:0) after 1m0s
[23:50:18.644] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:18:0)
[23:50:18.644] Timer tick, asking Buildbucket for the build status
[23:50:18.914] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:18.914] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:19:0) after 1m0s
[23:51:18.982] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:19:0)
[23:51:18.982] Timer tick, asking Buildbucket for the build status
[23:51:19.705] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:19.705] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:20:0) after 1m0s
[23:52:19.776] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:20:0)
[23:52:19.776] Timer tick, asking Buildbucket for the build status
[23:52:20.958] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:20.958] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:21:0) after 1m0s
[23:53:21.020] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:21:0)
[23:53:21.020] Timer tick, asking Buildbucket for the build status
[23:53:21.443] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:21.443] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:22:0) after 1m0s
[23:54:22.368] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:22:0)
[23:54:22.368] Timer tick, asking Buildbucket for the build status
[23:54:26.019] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:26.019] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:23:0) after 1m0s
[23:55:26.055] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:23:0)
[23:55:26.055] Timer tick, asking Buildbucket for the build status
[23:55:26.353] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:26.354] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:24:0) after 1m0s
[23:56:26.428] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:24:0)
[23:56:26.428] Timer tick, asking Buildbucket for the build status
[23:56:26.773] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:26.773] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:25:0) after 1m0s
[23:57:27.002] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:25:0)
[23:57:27.002] Timer tick, asking Buildbucket for the build status
[23:57:27.225] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:27.225] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:26:0) after 1m0s
[23:58:27.533] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:26:0)
[23:58:27.533] Timer tick, asking Buildbucket for the build status
[23:58:29.696] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:29.696] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:27:0) after 1m0s
[23:59:29.842] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:27:0)
[23:59:29.842] Timer tick, asking Buildbucket for the build status
[23:59:30.007] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:30.007] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:28:0) after 1m0s
[00:00:30.542] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:28:0)
[00:00:30.542] Timer tick, asking Buildbucket for the build status
[00:00:31.224] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:31.224] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:29:0) after 1m0s
[00:01:31.346] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:29:0)
[00:01:31.346] Timer tick, asking Buildbucket for the build status
[00:01:32.165] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:32.165] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:30:0) after 1m0s
[00:02:32.569] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:30:0)
[00:02:32.569] Timer tick, asking Buildbucket for the build status
[00:02:33.164] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:33.164] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:31:0) after 1m0s
[00:03:33.482] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:31:0)
[00:03:33.482] Timer tick, asking Buildbucket for the build status
[00:03:34.270] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:34.270] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:32:0) after 1m0s
[00:04:34.421] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:32:0)
[00:04:34.421] Timer tick, asking Buildbucket for the build status
[00:04:35.967] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:35.967] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:33:0) after 1m0s
[00:05:35.989] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:33:0)
[00:05:35.989] Timer tick, asking Buildbucket for the build status
[00:05:41.484] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:41.484] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:34:0) after 1m0s
[00:06:41.804] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:34:0)
[00:06:41.804] Timer tick, asking Buildbucket for the build status
[00:06:42.119] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:42.119] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:35:0) after 1m0s
[00:07:42.142] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:35:0)
[00:07:42.142] Timer tick, asking Buildbucket for the build status
[00:07:42.491] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:42.491] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:36:0) after 1m0s
[00:08:42.483] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:36:0)
[00:08:42.483] Timer tick, asking Buildbucket for the build status
[00:08:42.819] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:42.819] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:37:0) after 1m0s
[00:09:42.845] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:37:0)
[00:09:42.845] Timer tick, asking Buildbucket for the build status
[00:09:43.087] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:43.087] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:38:0) after 1m0s
[00:10:43.146] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:38:0)
[00:10:43.146] Timer tick, asking Buildbucket for the build status
[00:10:44.654] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:44.654] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:39:0) after 1m0s
[00:11:44.833] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:39:0)
[00:11:44.833] Timer tick, asking Buildbucket for the build status
[00:11:49.387] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:49.387] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:40:0) after 1m0s
[00:12:49.452] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:40:0)
[00:12:49.452] Timer tick, asking Buildbucket for the build status
[00:12:49.823] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:49.823] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:41:0) after 1m0s
[00:13:49.864] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:41:0)
[00:13:49.864] Timer tick, asking Buildbucket for the build status
[00:13:50.223] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:50.223] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:42:0) after 1m0s
[00:14:50.263] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:42:0)
[00:14:50.263] Timer tick, asking Buildbucket for the build status
[00:14:51.977] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:51.977] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:43:0) after 1m0s
[00:15:52.051] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:43:0)
[00:15:52.051] Timer tick, asking Buildbucket for the build status
[00:15:54.958] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:54.958] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:44:0) after 1m0s
[00:16:54.988] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:44:0)
[00:16:54.988] Timer tick, asking Buildbucket for the build status
[00:16:55.374] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:55.374] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:45:0) after 1m0s
[00:17:55.482] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:45:0)
[00:17:55.482] Timer tick, asking Buildbucket for the build status
[00:17:56.560] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:56.560] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:46:0) after 1m0s
[00:18:56.616] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:46:0)
[00:18:56.616] Timer tick, asking Buildbucket for the build status
[00:19:02.442] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:02.442] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:47:0) after 1m0s
[00:20:02.549] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:47:0)
[00:20:02.549] Timer tick, asking Buildbucket for the build status
[00:20:03.554] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:03.554] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:48:0) after 1m0s
[00:21:03.766] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:48:0)
[00:21:03.766] Timer tick, asking Buildbucket for the build status
[00:21:04.352] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:04.352] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:49:0) after 1m0s
[00:22:04.534] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:49:0)
[00:22:04.534] Timer tick, asking Buildbucket for the build status
[00:22:04.746] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:04.746] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:50:0) after 1m0s
[00:23:04.770] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:50:0)
[00:23:04.770] Timer tick, asking Buildbucket for the build status
[00:23:06.871] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:06.871] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:51:0) after 1m0s
[00:24:06.936] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:51:0)
[00:24:06.936] Timer tick, asking Buildbucket for the build status
[00:24:09.976] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:09.976] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:52:0) after 1m0s
[00:25:10.117] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:52:0)
[00:25:10.117] Timer tick, asking Buildbucket for the build status
[00:25:10.494] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:10.494] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:53:0) after 1m0s
[00:26:10.495] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:53:0)
[00:26:10.495] Timer tick, asking Buildbucket for the build status
[00:26:13.913] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:13.913] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:54:0) after 1m0s
[00:27:14.071] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:54:0)
[00:27:14.071] Timer tick, asking Buildbucket for the build status
[00:27:14.367] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:14.367] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:55:0) after 1m0s
[00:28:14.601] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:55:0)
[00:28:14.601] Timer tick, asking Buildbucket for the build status
[00:28:16.332] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:16.332] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:56:0) after 1m0s
[00:29:16.484] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:56:0)
[00:29:16.484] Timer tick, asking Buildbucket for the build status
[00:29:18.215] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:18.215] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:57:0) after 1m0s
[00:30:18.421] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:57:0)
[00:30:18.421] Timer tick, asking Buildbucket for the build status
[00:30:19.584] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:19.584] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:58:0) after 1m0s
[00:31:19.605] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:58:0)
[00:31:19.605] Timer tick, asking Buildbucket for the build status
[00:31:20.955] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:20.955] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:59:0) after 1m0s
[00:32:21.094] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:59:0)
[00:32:21.094] Timer tick, asking Buildbucket for the build status
[00:32:21.471] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:21.471] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:60:0) after 1m0s
[00:33:21.532] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:60:0)
[00:33:21.532] Timer tick, asking Buildbucket for the build status
[00:33:25.407] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:25.408] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:61:0) after 1m0s
[00:34:25.441] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:61:0)
[00:34:25.441] Timer tick, asking Buildbucket for the build status
[00:34:27.844] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:27.844] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:62:0) after 1m0s
[00:35:27.886] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:62:0)
[00:35:27.886] Timer tick, asking Buildbucket for the build status
[00:35:28.078] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:28.078] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:63:0) after 1m0s
[00:36:28.160] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:63:0)
[00:36:28.160] Timer tick, asking Buildbucket for the build status
[00:36:29.265] Build 8907643247053307376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:29.265] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9073073309079149152:64:0) after 1m0s
[00:37:02.179] Received PubSub notification, asking Buildbucket for the build status
[00:37:02.475] Build 8907643247053307376: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:37:02.475] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1563410220390294",
"created_by": "project:chromium",
"created_ts": "1563406437783435",
"failure_reason": "BUILD_FAILURE",
"id": "8907643247053307376",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a343db33b1ded331a1a83cb2f940a90493f2cdeb\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**site_per_process_unit_tests on Android device Nexus 5X** failed because of:\\n\\n- OfflinePageUtilsTest.TestGetCachedOfflinePageSizeAllPagesInRange\"}, \"properties\": {\"$build/goma\": {\"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-49-qen0\", \"branch\": \"refs/heads/master\", \"buildername\": \"Site Isolation Android\", \"buildnumber\": 35654, \"got_angle_revision\": \"0efe516eeaeff1ea7d9c79d577e73573509f814c\", \"got_dawn_revision\": \"9dbb81f004b65faacbff0e5508bc7b7a40654f2b\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"a343db33b1ded331a1a83cb2f940a90493f2cdeb\", \"got_revision_cp\": \"refs/heads/master@{#678496}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"faec9ebf780179693789d7e5d473769ac237d6ce\", \"got_v8_revision_cp\": \"refs/heads/7.7.263@{#1}\", \"got_webrtc_revision\": \"b04e2561be34029bc06867946a706d268500212d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28591}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a343db33b1ded331a1a83cb2f940a90493f2cdeb\", \"swarm_hashes\": {\"components_browsertests\": \"12319987cab424949caf359f06ef48bea06d9e97\", \"components_unittests\": \"877a629dc6a948d9bdecc2558c816acea6fe4142\", \"content_browsertests\": \"2479d9c9cdfb87e42c0572d4e8f86df98ebffd52\", \"content_unittests\": \"930e22c396ad7a6e8497da9ed71d2081844c1035\", \"unit_tests\": \"f9f513a9fd35c30bcbfac8d9e4af2d2aeab24234\"}, \"swarm_hashes_refs/heads/master(at){#678496}_without_patch\": {\"components_browsertests\": \"12319987cab424949caf359f06ef48bea06d9e97\", \"components_unittests\": \"877a629dc6a948d9bdecc2558c816acea6fe4142\", \"content_browsertests\": \"2479d9c9cdfb87e42c0572d4e8f86df98ebffd52\", \"content_unittests\": \"930e22c396ad7a6e8497da9ed71d2081844c1035\", \"unit_tests\": \"f9f513a9fd35c30bcbfac8d9e4af2d2aeab24234\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5315cf154f9c1ae6b2621094599bc107171a849e1c0f232ec66a504ee800a364_v2\", \"builder_e961d9d7e9bca679490a4aaa8599bd975f9b7e0b713a988ed6895a059d04d2ac_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-49-qen0\"], \"image\": [\"chrome-xenial-19052201-9cb74617499\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4370-0e704a3\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**site_per_process_unit_tests on Android device Nexus 5X** failed because of:\\n\\n- OfflinePageUtilsTest.TestGetCachedOfflinePageSizeAllPagesInRange\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1563406492535370",
"status": "COMPLETED",
"status_changed_ts": "1563406492901256",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/35654",
"builder:Site Isolation Android",
"buildset:commit/git/a343db33b1ded331a1a83cb2f940a90493f2cdeb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a343db33b1ded331a1a83cb2f940a90493f2cdeb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9073073309079149152",
"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/8907643247053307376/+/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:461b1a98540c3110",
"user_agent:luci-scheduler"
],
"updated_ts": "1563410221670714",
"url": "https://ci.chromium.org/b/8907643247053307376",
"utcnow_ts": "1563410222467830"
}
}
[00:37:02.475] Invocation finished in 1h3m6.007119875s with status FAILED