[18:08:22.308] New invocation is queued and will start shortly
[18:08:23.388] Starting the invocation (attempt 1)
[18:08:23.434] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:08:23.434] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:08:23.435] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9082606473621533344",
"parameters_json": "{\"builder_name\":\"Site Isolation Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"a4e972fc340b7da21e2213cc4a92c2930fdc4e67\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Site Isolation Android",
"scheduler_invocation_id:9082606473621533344",
"scheduler_job_id:chromium/Site Isolation Android",
"user_agent:luci-scheduler",
"buildset:commit/git/a4e972fc340b7da21e2213cc4a92c2930fdc4e67",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a4e972fc340b7da21e2213cc4a92c2930fdc4e67",
"gitiles_ref:refs/heads/master"
]
}
[18:08:30.878] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1554314903633124",
"id": "8917176411565869296",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a4e972fc340b7da21e2213cc4a92c2930fdc4e67\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554314910652172",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/32397",
"builder:Site Isolation Android",
"buildset:commit/git/a4e972fc340b7da21e2213cc4a92c2930fdc4e67",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a4e972fc340b7da21e2213cc4a92c2930fdc4e67",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082606473621533344",
"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/8917176411565869296/+/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": "1554314910652295",
"url": "https://ci.chromium.org/b/8917176411565869296",
"utcnow_ts": "1554314910853343"
}
}
[18:08:30.878] Task URL: https://ci.chromium.org/b/8917176411565869296
[18:08:30.878] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:2:0) after 1m0s
[18:08:34.494] Received PubSub notification, asking Buildbucket for the build status
[18:08:34.637] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:30.874] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:2:0)
[18:09:30.874] Timer tick, asking Buildbucket for the build status
[18:09:31.118] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:31.118] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:4:0) after 1m0s
[18:10:31.113] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:4:0)
[18:10:31.113] Timer tick, asking Buildbucket for the build status
[18:10:31.289] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:31.289] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:5:0) after 1m0s
[18:11:31.279] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:5:0)
[18:11:31.279] Timer tick, asking Buildbucket for the build status
[18:11:31.695] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:31.695] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:6:0) after 1m0s
[18:12:31.703] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:6:0)
[18:12:31.703] Timer tick, asking Buildbucket for the build status
[18:12:32.216] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:32.216] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:7:0) after 1m0s
[18:13:32.209] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:7:0)
[18:13:32.209] Timer tick, asking Buildbucket for the build status
[18:13:32.303] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:32.303] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:8:0) after 1m0s
[18:14:32.313] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:8:0)
[18:14:32.313] Timer tick, asking Buildbucket for the build status
[18:14:32.445] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:32.445] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:9:0) after 1m0s
[18:15:32.466] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:9:0)
[18:15:32.466] Timer tick, asking Buildbucket for the build status
[18:15:33.096] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:33.096] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:10:0) after 1m0s
[18:16:33.086] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:10:0)
[18:16:33.086] Timer tick, asking Buildbucket for the build status
[18:16:33.599] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:33.599] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:11:0) after 1m0s
[18:17:33.604] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:11:0)
[18:17:33.604] Timer tick, asking Buildbucket for the build status
[18:17:33.773] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:33.773] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:12:0) after 1m0s
[18:18:33.800] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:12:0)
[18:18:33.800] Timer tick, asking Buildbucket for the build status
[18:18:34.589] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:34.589] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:13:0) after 1m0s
[18:19:34.615] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:13:0)
[18:19:34.615] Timer tick, asking Buildbucket for the build status
[18:19:35.013] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:35.013] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:14:0) after 1m0s
[18:20:35.003] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:14:0)
[18:20:35.003] Timer tick, asking Buildbucket for the build status
[18:20:35.265] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:35.265] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:15:0) after 1m0s
[18:21:35.286] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:15:0)
[18:21:35.286] Timer tick, asking Buildbucket for the build status
[18:21:36.201] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:36.201] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:16:0) after 1m0s
[18:22:36.182] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:16:0)
[18:22:36.182] Timer tick, asking Buildbucket for the build status
[18:22:36.796] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:36.796] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:17:0) after 1m0s
[18:23:36.803] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:17:0)
[18:23:36.803] Timer tick, asking Buildbucket for the build status
[18:23:37.598] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:37.598] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:18:0) after 1m0s
[18:24:37.641] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:18:0)
[18:24:37.641] Timer tick, asking Buildbucket for the build status
[18:24:37.725] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:37.725] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:19:0) after 1m0s
[18:25:37.760] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:19:0)
[18:25:37.760] Timer tick, asking Buildbucket for the build status
[18:25:37.865] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:37.865] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:20:0) after 1m0s
[18:26:37.868] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:20:0)
[18:26:37.868] Timer tick, asking Buildbucket for the build status
[18:26:37.971] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:37.971] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:21:0) after 1m0s
[18:27:37.990] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:21:0)
[18:27:37.990] Timer tick, asking Buildbucket for the build status
[18:27:38.478] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:38.478] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:22:0) after 1m0s
[18:28:38.517] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:22:0)
[18:28:38.517] Timer tick, asking Buildbucket for the build status
[18:28:38.620] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:38.620] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:23:0) after 1m0s
[18:29:38.627] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:23:0)
[18:29:38.627] Timer tick, asking Buildbucket for the build status
[18:29:39.474] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:39.474] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:24:0) after 1m0s
[18:30:39.535] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:24:0)
[18:30:39.535] Timer tick, asking Buildbucket for the build status
[18:30:39.635] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:39.635] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:25:0) after 1m0s
[18:31:39.649] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:25:0)
[18:31:39.649] Timer tick, asking Buildbucket for the build status
[18:31:40.028] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:40.028] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:26:0) after 1m0s
[18:32:40.034] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:26:0)
[18:32:40.034] Timer tick, asking Buildbucket for the build status
[18:32:40.151] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:40.151] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:27:0) after 1m0s
[18:33:40.130] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:27:0)
[18:33:40.130] Timer tick, asking Buildbucket for the build status
[18:33:40.827] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:40.827] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:28:0) after 1m0s
[18:34:40.911] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:28:0)
[18:34:40.911] Timer tick, asking Buildbucket for the build status
[18:34:41.409] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:41.409] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:29:0) after 1m0s
[18:35:41.402] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:29:0)
[18:35:41.402] Timer tick, asking Buildbucket for the build status
[18:35:41.498] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:41.498] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:30:0) after 1m0s
[18:36:41.483] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:30:0)
[18:36:41.483] Timer tick, asking Buildbucket for the build status
[18:36:41.559] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:41.559] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:31:0) after 1m0s
[18:37:41.596] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:31:0)
[18:37:41.596] Timer tick, asking Buildbucket for the build status
[18:37:41.751] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:41.751] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:32:0) after 1m0s
[18:38:41.776] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:32:0)
[18:38:41.776] Timer tick, asking Buildbucket for the build status
[18:38:41.984] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:41.984] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:33:0) after 1m0s
[18:39:42.002] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:33:0)
[18:39:42.002] Timer tick, asking Buildbucket for the build status
[18:39:42.984] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:42.984] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:34:0) after 1m0s
[18:40:42.986] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:34:0)
[18:40:42.986] Timer tick, asking Buildbucket for the build status
[18:40:43.164] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:43.164] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:35:0) after 1m0s
[18:41:43.149] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:35:0)
[18:41:43.149] Timer tick, asking Buildbucket for the build status
[18:41:43.269] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:43.269] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:36:0) after 1m0s
[18:42:43.243] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:36:0)
[18:42:43.243] Timer tick, asking Buildbucket for the build status
[18:42:43.870] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:43.870] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:37:0) after 1m0s
[18:43:43.935] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:37:0)
[18:43:43.935] Timer tick, asking Buildbucket for the build status
[18:43:44.295] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:44.295] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:38:0) after 1m0s
[18:44:44.315] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:38:0)
[18:44:44.315] Timer tick, asking Buildbucket for the build status
[18:44:45.170] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:45.170] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:39:0) after 1m0s
[18:45:45.156] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:39:0)
[18:45:45.156] Timer tick, asking Buildbucket for the build status
[18:45:45.344] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:45.344] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:40:0) after 1m0s
[18:46:45.364] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:40:0)
[18:46:45.364] Timer tick, asking Buildbucket for the build status
[18:46:45.547] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:45.547] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:41:0) after 1m0s
[18:47:45.570] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:41:0)
[18:47:45.570] Timer tick, asking Buildbucket for the build status
[18:47:45.701] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:45.701] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:42:0) after 1m0s
[18:48:45.780] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:42:0)
[18:48:45.780] Timer tick, asking Buildbucket for the build status
[18:48:46.003] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:46.003] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:43:0) after 1m0s
[18:49:45.983] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:43:0)
[18:49:45.983] Timer tick, asking Buildbucket for the build status
[18:49:46.184] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:46.184] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:44:0) after 1m0s
[18:50:46.214] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:44:0)
[18:50:46.214] Timer tick, asking Buildbucket for the build status
[18:50:46.351] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:46.351] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:45:0) after 1m0s
[18:51:46.380] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:45:0)
[18:51:46.380] Timer tick, asking Buildbucket for the build status
[18:51:46.779] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:46.779] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:46:0) after 1m0s
[18:52:46.770] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:46:0)
[18:52:46.770] Timer tick, asking Buildbucket for the build status
[18:52:46.981] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:46.981] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:47:0) after 1m0s
[18:53:46.993] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:47:0)
[18:53:46.993] Timer tick, asking Buildbucket for the build status
[18:53:47.189] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:47.189] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:48:0) after 1m0s
[18:54:47.214] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:48:0)
[18:54:47.214] Timer tick, asking Buildbucket for the build status
[18:54:47.403] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:47.403] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:49:0) after 1m0s
[18:55:47.400] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:49:0)
[18:55:47.400] Timer tick, asking Buildbucket for the build status
[18:55:47.520] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:47.520] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:50:0) after 1m0s
[18:56:47.533] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:50:0)
[18:56:47.533] Timer tick, asking Buildbucket for the build status
[18:56:47.648] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:47.648] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:51:0) after 1m0s
[18:57:47.627] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:51:0)
[18:57:47.627] Timer tick, asking Buildbucket for the build status
[18:57:47.805] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:47.805] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:52:0) after 1m0s
[18:58:47.807] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:52:0)
[18:58:47.807] Timer tick, asking Buildbucket for the build status
[18:58:48.498] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:48.498] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:53:0) after 1m0s
[18:59:48.587] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:53:0)
[18:59:48.587] Timer tick, asking Buildbucket for the build status
[18:59:49.475] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:49.475] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:54:0) after 1m0s
[19:00:49.541] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:54:0)
[19:00:49.541] Timer tick, asking Buildbucket for the build status
[19:00:49.654] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:49.654] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:55:0) after 1m0s
[19:01:49.628] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:55:0)
[19:01:49.628] Timer tick, asking Buildbucket for the build status
[19:01:49.786] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:49.786] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:56:0) after 1m0s
[19:02:49.812] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:56:0)
[19:02:49.812] Timer tick, asking Buildbucket for the build status
[19:02:50.037] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:50.037] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:57:0) after 1m0s
[19:03:50.035] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:57:0)
[19:03:50.035] Timer tick, asking Buildbucket for the build status
[19:03:50.123] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:50.123] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:58:0) after 1m0s
[19:04:50.195] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:58:0)
[19:04:50.195] Timer tick, asking Buildbucket for the build status
[19:04:50.304] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:50.304] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:59:0) after 1m0s
[19:05:50.300] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:59:0)
[19:05:50.300] Timer tick, asking Buildbucket for the build status
[19:05:50.402] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:50.403] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:60:0) after 1m0s
[19:06:50.406] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:60:0)
[19:06:50.406] Timer tick, asking Buildbucket for the build status
[19:06:51.305] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:51.305] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:61:0) after 1m0s
[19:07:51.334] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:61:0)
[19:07:51.334] Timer tick, asking Buildbucket for the build status
[19:07:51.496] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:51.496] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:62:0) after 1m0s
[19:08:51.488] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:62:0)
[19:08:51.488] Timer tick, asking Buildbucket for the build status
[19:08:51.732] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:51.732] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:63:0) after 1m0s
[19:09:51.759] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:63:0)
[19:09:51.759] Timer tick, asking Buildbucket for the build status
[19:09:51.893] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:51.893] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:64:0) after 1m0s
[19:10:51.904] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:64:0)
[19:10:51.904] Timer tick, asking Buildbucket for the build status
[19:10:52.063] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:52.063] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:65:0) after 1m0s
[19:11:52.042] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:65:0)
[19:11:52.042] Timer tick, asking Buildbucket for the build status
[19:11:52.231] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:52.231] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:66:0) after 1m0s
[19:12:52.261] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:66:0)
[19:12:52.261] Timer tick, asking Buildbucket for the build status
[19:12:52.463] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:52.463] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:67:0) after 1m0s
[19:13:52.518] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:67:0)
[19:13:52.518] Timer tick, asking Buildbucket for the build status
[19:13:53.052] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:53.052] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:68:0) after 1m0s
[19:14:53.052] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:68:0)
[19:14:53.052] Timer tick, asking Buildbucket for the build status
[19:14:53.274] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:53.275] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:69:0) after 1m0s
[19:15:53.311] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:69:0)
[19:15:53.311] Timer tick, asking Buildbucket for the build status
[19:15:54.197] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:54.197] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:70:0) after 1m0s
[19:16:54.224] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:70:0)
[19:16:54.224] Timer tick, asking Buildbucket for the build status
[19:16:55.157] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:55.157] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:71:0) after 1m0s
[19:17:55.188] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:71:0)
[19:17:55.188] Timer tick, asking Buildbucket for the build status
[19:17:55.528] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:55.528] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:72:0) after 1m0s
[19:18:55.570] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:72:0)
[19:18:55.570] Timer tick, asking Buildbucket for the build status
[19:18:56.066] Build 8917176411565869296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:56.066] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9082606473621533344:73:0) after 1m0s
[19:19:22.398] Received PubSub notification, asking Buildbucket for the build status
[19:19:22.911] Build 8917176411565869296: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[19:19:22.911] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1554319161931131",
"created_by": "project:chromium",
"created_ts": "1554314903633124",
"failure_reason": "BUILD_FAILURE",
"id": "8917176411565869296",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a4e972fc340b7da21e2213cc4a92c2930fdc4e67\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"5 tests failed: ['site_per_process_components_browsertests on Android device Nexus 5X', 'site_per_process_unit_tests on Android device Nexus 5X', 'site_per_process_components_unittests on Android device Nexus 5X', 'site_per_process_content_unittests on Android device Nexus 5X', 'site_per_process_content_browsertests on Android device Nexus 5X']\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"swarm2384-c4\", \"branch\": \"refs/heads/master\", \"buildername\": \"Site Isolation Android\", \"buildnumber\": 32397, \"got_angle_revision\": \"24980278a08ac1bef848c9f9ed035a9ebbeac07b\", \"got_dawn_revision\": \"20b0c33913e45cd3d553b42a43b75b27af817833\", \"got_nacl_revision\": \"de8da4841bc3d1ed020d798d5a7fd6fb3d817fa1\", \"got_revision\": \"a4e972fc340b7da21e2213cc4a92c2930fdc4e67\", \"got_revision_cp\": \"refs/heads/master@{#647372}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"b9b1bbc0364d4d191b48f51b20beb8b6c3deddb7\", \"got_v8_revision_cp\": \"refs/heads/7.5.174@{#1}\", \"got_webrtc_revision\": \"ade945d83496405c196a8cd82d79fbe086af065e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27440}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a4e972fc340b7da21e2213cc4a92c2930fdc4e67\", \"swarm_hashes\": {\"components_browsertests\": \"992028fa50b644854a06ee4f1a3e1bddd9e54007\", \"components_unittests\": \"a986c92980ab3a5460ffe4d2bff38810f84afdbb\", \"content_browsertests\": \"44258259ec6a734e89027a213f4b59e0fe7508b4\", \"content_unittests\": \"72fd27a122463592c1bf23a32747ec074e8189ad\", \"unit_tests\": \"e62c2d834dfddc765fbec00b7c3c9fc026d4a458\"}, \"swarm_hashes_refs/heads/master(at){#647372}_without_patch\": {\"components_browsertests\": \"992028fa50b644854a06ee4f1a3e1bddd9e54007\", \"components_unittests\": \"a986c92980ab3a5460ffe4d2bff38810f84afdbb\", \"content_browsertests\": \"44258259ec6a734e89027a213f4b59e0fe7508b4\", \"content_unittests\": \"72fd27a122463592c1bf23a32747ec074e8189ad\", \"unit_tests\": \"e62c2d834dfddc765fbec00b7c3c9fc026d4a458\"}}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Site Isolation Android\"], \"caches\": [\"builder_e961d9d7e9bca679490a4aaa8599bd975f9b7e0b713a988ed6895a059d04d2ac_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Ivy_Bridge_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"swarm2384-c4\"], \"image\": [\"chrome-trusty-18042300-b7223b463e3\"], \"inside_docker\": [\"0\"], \"kvm\": [\"0\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4237-a5bdaf4\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-f\"]}}, \"ui\": {\"info\": \"5 tests failed: ['site_per_process_components_browsertests on Android device Nexus 5X', 'site_per_process_unit_tests on Android device Nexus 5X', 'site_per_process_components_unittests on Android device Nexus 5X', 'site_per_process_content_unittests on Android device Nexus 5X', 'site_per_process_content_browsertests on Android device Nexus 5X']\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1554314913884491",
"status": "COMPLETED",
"status_changed_ts": "1554314914158840",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/32397",
"builder:Site Isolation Android",
"buildset:commit/git/a4e972fc340b7da21e2213cc4a92c2930fdc4e67",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a4e972fc340b7da21e2213cc4a92c2930fdc4e67",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082606473621533344",
"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/8917176411565869296/+/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:43fd34e58f202310",
"user_agent:luci-scheduler"
],
"updated_ts": "1554319162069696",
"url": "https://ci.chromium.org/b/8917176411565869296",
"utcnow_ts": "1554319162893996"
}
}
[19:19:22.911] Invocation finished in 1h11m0.621139515s with status FAILED