[12:12:12.408] New invocation is queued and will start shortly
[12:12:13.784] Starting the invocation (attempt 1)
[12:12:13.825] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:12:13.825] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:12:13.825] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9078914405960413280",
"parameters_json": "{\"builder_name\":\"Site Isolation Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"dbd95b99fe062191d33efa75dad11fbbbb8dc261\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Site Isolation Android",
"scheduler_invocation_id:9078914405960413280",
"scheduler_job_id:chromium/Site Isolation Android",
"user_agent:luci-scheduler",
"buildset:commit/git/dbd95b99fe062191d33efa75dad11fbbbb8dc261",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dbd95b99fe062191d33efa75dad11fbbbb8dc261",
"gitiles_ref:refs/heads/master"
]
}
[12:12:17.950] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1557835935257641",
"id": "8913484342309789984",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"dbd95b99fe062191d33efa75dad11fbbbb8dc261\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557835937549850",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/33895",
"builder:Site Isolation Android",
"buildset:commit/git/dbd95b99fe062191d33efa75dad11fbbbb8dc261",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dbd95b99fe062191d33efa75dad11fbbbb8dc261",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078914405960413280",
"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/8913484342309789984/+/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": "1557835937549988",
"url": "https://ci.chromium.org/b/8913484342309789984",
"utcnow_ts": "1557835937880805"
}
}
[12:12:17.950] Task URL: https://ci.chromium.org/b/8913484342309789984
[12:12:17.950] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:2:0) after 1m0s
[12:13:17.980] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:2:0)
[12:13:17.980] Timer tick, asking Buildbucket for the build status
[12:13:18.323] Build 8913484342309789984: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:13:18.323] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:3:0) after 1m0s
[12:14:19.070] Received PubSub notification, asking Buildbucket for the build status
[12:14:19.349] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:19.813] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:3:0)
[12:14:19.813] Timer tick, asking Buildbucket for the build status
[12:14:20.004] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:20.004] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:5:0) after 1m0s
[12:15:20.044] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:5:0)
[12:15:20.044] Timer tick, asking Buildbucket for the build status
[12:15:20.273] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:20.273] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:6:0) after 1m0s
[12:16:20.294] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:6:0)
[12:16:20.294] Timer tick, asking Buildbucket for the build status
[12:16:21.350] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:21.350] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:7:0) after 1m0s
[12:17:21.383] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:7:0)
[12:17:21.383] Timer tick, asking Buildbucket for the build status
[12:17:21.929] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:21.929] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:8:0) after 1m0s
[12:18:21.949] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:8:0)
[12:18:21.949] Timer tick, asking Buildbucket for the build status
[12:18:23.880] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:23.880] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:9:0) after 1m0s
[12:19:23.875] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:9:0)
[12:19:23.875] Timer tick, asking Buildbucket for the build status
[12:19:24.654] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:24.654] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:10:0) after 1m0s
[12:20:24.632] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:10:0)
[12:20:24.632] Timer tick, asking Buildbucket for the build status
[12:20:24.960] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:24.960] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:11:0) after 1m0s
[12:21:24.978] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:11:0)
[12:21:24.978] Timer tick, asking Buildbucket for the build status
[12:21:25.810] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:25.810] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:12:0) after 1m0s
[12:22:25.833] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:12:0)
[12:22:25.833] Timer tick, asking Buildbucket for the build status
[12:22:26.299] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:26.299] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:13:0) after 1m0s
[12:23:26.364] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:13:0)
[12:23:26.364] Timer tick, asking Buildbucket for the build status
[12:23:27.146] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:27.146] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:14:0) after 1m0s
[12:24:27.165] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:14:0)
[12:24:27.165] Timer tick, asking Buildbucket for the build status
[12:24:27.862] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:27.863] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:15:0) after 1m0s
[12:25:27.879] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:15:0)
[12:25:27.879] Timer tick, asking Buildbucket for the build status
[12:25:27.998] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:27.998] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:16:0) after 1m0s
[12:26:28.027] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:16:0)
[12:26:28.027] Timer tick, asking Buildbucket for the build status
[12:26:28.590] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:28.591] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:17:0) after 1m0s
[12:27:28.561] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:17:0)
[12:27:28.561] Timer tick, asking Buildbucket for the build status
[12:27:28.710] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:28.710] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:18:0) after 1m0s
[12:28:28.729] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:18:0)
[12:28:28.729] Timer tick, asking Buildbucket for the build status
[12:28:28.835] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:28.835] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:19:0) after 1m0s
[12:29:28.853] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:19:0)
[12:29:28.853] Timer tick, asking Buildbucket for the build status
[12:29:28.982] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:28.982] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:20:0) after 1m0s
[12:30:28.984] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:20:0)
[12:30:28.984] Timer tick, asking Buildbucket for the build status
[12:30:29.160] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:29.160] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:21:0) after 1m0s
[12:31:29.179] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:21:0)
[12:31:29.179] Timer tick, asking Buildbucket for the build status
[12:31:29.537] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:29.537] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:22:0) after 1m0s
[12:32:29.558] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:22:0)
[12:32:29.558] Timer tick, asking Buildbucket for the build status
[12:32:29.715] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:29.715] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:23:0) after 1m0s
[12:33:29.706] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:23:0)
[12:33:29.706] Timer tick, asking Buildbucket for the build status
[12:33:29.984] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:29.984] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:24:0) after 1m0s
[12:34:30.001] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:24:0)
[12:34:30.001] Timer tick, asking Buildbucket for the build status
[12:34:30.484] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:30.484] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:25:0) after 1m0s
[12:35:30.479] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:25:0)
[12:35:30.479] Timer tick, asking Buildbucket for the build status
[12:35:30.600] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:30.600] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:26:0) after 1m0s
[12:36:30.584] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:26:0)
[12:36:30.584] Timer tick, asking Buildbucket for the build status
[12:36:30.967] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:30.967] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:27:0) after 1m0s
[12:37:30.976] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:27:0)
[12:37:30.976] Timer tick, asking Buildbucket for the build status
[12:37:31.172] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:31.172] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:28:0) after 1m0s
[12:38:31.196] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:28:0)
[12:38:31.196] Timer tick, asking Buildbucket for the build status
[12:38:31.842] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:31.842] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:29:0) after 1m0s
[12:39:31.868] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:29:0)
[12:39:31.868] Timer tick, asking Buildbucket for the build status
[12:39:32.251] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:32.251] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:30:0) after 1m0s
[12:40:32.359] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:30:0)
[12:40:32.359] Timer tick, asking Buildbucket for the build status
[12:40:32.578] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:32.578] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:31:0) after 1m0s
[12:41:32.916] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:31:0)
[12:41:32.916] Timer tick, asking Buildbucket for the build status
[12:41:33.279] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:33.279] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:32:0) after 1m0s
[12:42:33.369] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:32:0)
[12:42:33.369] Timer tick, asking Buildbucket for the build status
[12:42:33.980] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:33.980] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:33:0) after 1m0s
[12:43:33.993] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:33:0)
[12:43:33.993] Timer tick, asking Buildbucket for the build status
[12:43:34.541] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:34.541] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:34:0) after 1m0s
[12:44:34.628] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:34:0)
[12:44:34.628] Timer tick, asking Buildbucket for the build status
[12:44:35.213] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:35.213] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:35:0) after 1m0s
[12:45:35.233] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:35:0)
[12:45:35.233] Timer tick, asking Buildbucket for the build status
[12:45:35.692] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:35.692] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:36:0) after 1m0s
[12:46:35.787] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:36:0)
[12:46:35.787] Timer tick, asking Buildbucket for the build status
[12:46:35.937] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:35.937] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:37:0) after 1m0s
[12:47:36.017] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:37:0)
[12:47:36.017] Timer tick, asking Buildbucket for the build status
[12:47:36.218] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:36.218] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:38:0) after 1m0s
[12:48:36.346] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:38:0)
[12:48:36.346] Timer tick, asking Buildbucket for the build status
[12:48:36.645] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:36.645] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:39:0) after 1m0s
[12:49:36.672] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:39:0)
[12:49:36.672] Timer tick, asking Buildbucket for the build status
[12:49:36.870] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:36.870] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:40:0) after 1m0s
[12:50:36.897] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:40:0)
[12:50:36.897] Timer tick, asking Buildbucket for the build status
[12:50:37.262] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:37.262] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:41:0) after 1m0s
[12:51:37.279] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:41:0)
[12:51:37.279] Timer tick, asking Buildbucket for the build status
[12:51:37.563] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:37.563] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:42:0) after 1m0s
[12:52:37.558] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:42:0)
[12:52:37.558] Timer tick, asking Buildbucket for the build status
[12:52:37.855] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:37.855] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:43:0) after 1m0s
[12:53:37.877] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:43:0)
[12:53:37.877] Timer tick, asking Buildbucket for the build status
[12:53:38.153] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:38.153] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:44:0) after 1m0s
[12:54:38.177] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:44:0)
[12:54:38.177] Timer tick, asking Buildbucket for the build status
[12:54:38.360] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:38.360] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:45:0) after 1m0s
[12:55:38.379] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:45:0)
[12:55:38.379] Timer tick, asking Buildbucket for the build status
[12:55:38.563] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:38.563] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:46:0) after 1m0s
[12:56:38.662] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:46:0)
[12:56:38.663] Timer tick, asking Buildbucket for the build status
[12:56:38.754] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:38.754] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:47:0) after 1m0s
[12:57:38.785] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:47:0)
[12:57:38.785] Timer tick, asking Buildbucket for the build status
[12:57:40.223] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:40.223] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:48:0) after 1m0s
[12:58:40.260] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:48:0)
[12:58:40.260] Timer tick, asking Buildbucket for the build status
[12:58:41.351] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:41.351] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:49:0) after 1m0s
[12:59:41.363] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:49:0)
[12:59:41.363] Timer tick, asking Buildbucket for the build status
[12:59:41.959] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:41.959] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:50:0) after 1m0s
[13:00:42.030] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:50:0)
[13:00:42.030] Timer tick, asking Buildbucket for the build status
[13:00:42.901] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:42.901] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:51:0) after 1m0s
[13:01:42.923] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:51:0)
[13:01:42.923] Timer tick, asking Buildbucket for the build status
[13:01:43.075] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:43.075] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:52:0) after 1m0s
[13:02:43.205] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:52:0)
[13:02:43.205] Timer tick, asking Buildbucket for the build status
[13:02:43.329] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:43.329] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:53:0) after 1m0s
[13:03:43.348] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:53:0)
[13:03:43.348] Timer tick, asking Buildbucket for the build status
[13:03:43.783] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:43.783] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:54:0) after 1m0s
[13:04:43.810] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:54:0)
[13:04:43.810] Timer tick, asking Buildbucket for the build status
[13:04:44.095] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:44.095] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:55:0) after 1m0s
[13:05:44.115] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:55:0)
[13:05:44.115] Timer tick, asking Buildbucket for the build status
[13:05:44.294] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:44.294] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:56:0) after 1m0s
[13:06:44.417] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:56:0)
[13:06:44.417] Timer tick, asking Buildbucket for the build status
[13:06:44.528] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:44.528] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:57:0) after 1m0s
[13:07:44.662] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:57:0)
[13:07:44.662] Timer tick, asking Buildbucket for the build status
[13:07:44.752] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:44.752] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:58:0) after 1m0s
[13:08:44.934] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:58:0)
[13:08:44.934] Timer tick, asking Buildbucket for the build status
[13:08:45.059] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:45.059] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:59:0) after 1m0s
[13:09:45.069] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:59:0)
[13:09:45.069] Timer tick, asking Buildbucket for the build status
[13:09:45.389] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:45.389] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:60:0) after 1m0s
[13:10:45.553] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:60:0)
[13:10:45.553] Timer tick, asking Buildbucket for the build status
[13:10:45.825] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:45.825] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:61:0) after 1m0s
[13:11:45.857] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:61:0)
[13:11:45.857] Timer tick, asking Buildbucket for the build status
[13:11:46.533] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:46.533] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:62:0) after 1m0s
[13:12:46.554] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:62:0)
[13:12:46.554] Timer tick, asking Buildbucket for the build status
[13:12:47.129] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:47.129] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:63:0) after 1m0s
[13:13:47.151] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:63:0)
[13:13:47.151] Timer tick, asking Buildbucket for the build status
[13:13:47.421] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:47.421] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:64:0) after 1m0s
[13:14:47.442] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:64:0)
[13:14:47.442] Timer tick, asking Buildbucket for the build status
[13:14:48.532] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:48.532] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:65:0) after 1m0s
[13:15:48.552] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:65:0)
[13:15:48.552] Timer tick, asking Buildbucket for the build status
[13:15:48.716] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:48.716] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:66:0) after 1m0s
[13:16:48.697] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:66:0)
[13:16:48.697] Timer tick, asking Buildbucket for the build status
[13:16:49.574] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:49.574] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:67:0) after 1m0s
[13:17:49.575] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:67:0)
[13:17:49.575] Timer tick, asking Buildbucket for the build status
[13:17:49.714] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:49.714] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:68:0) after 1m0s
[13:18:49.751] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:68:0)
[13:18:49.751] Timer tick, asking Buildbucket for the build status
[13:18:50.153] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:50.153] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:69:0) after 1m0s
[13:19:50.165] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:69:0)
[13:19:50.165] Timer tick, asking Buildbucket for the build status
[13:19:50.417] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:50.417] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:70:0) after 1m0s
[13:20:50.430] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:70:0)
[13:20:50.430] Timer tick, asking Buildbucket for the build status
[13:20:50.614] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:50.614] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:71:0) after 1m0s
[13:21:50.636] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:71:0)
[13:21:50.636] Timer tick, asking Buildbucket for the build status
[13:21:50.737] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:50.737] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:72:0) after 1m0s
[13:22:50.765] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:72:0)
[13:22:50.765] Timer tick, asking Buildbucket for the build status
[13:22:51.642] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:51.642] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:73:0) after 1m0s
[13:23:51.667] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:73:0)
[13:23:51.667] Timer tick, asking Buildbucket for the build status
[13:23:51.758] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:51.758] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:74:0) after 1m0s
[13:24:51.737] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:74:0)
[13:24:51.737] Timer tick, asking Buildbucket for the build status
[13:24:51.895] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:51.895] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:75:0) after 1m0s
[13:25:51.918] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:75:0)
[13:25:51.918] Timer tick, asking Buildbucket for the build status
[13:25:53.250] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:53.250] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:76:0) after 1m0s
[13:26:53.223] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:76:0)
[13:26:53.223] Timer tick, asking Buildbucket for the build status
[13:26:54.654] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:54.654] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:77:0) after 1m0s
[13:27:54.840] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:77:0)
[13:27:54.840] Timer tick, asking Buildbucket for the build status
[13:27:55.119] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:55.119] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:78:0) after 1m0s
[13:28:55.144] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:78:0)
[13:28:55.144] Timer tick, asking Buildbucket for the build status
[13:28:55.652] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:55.652] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:79:0) after 1m0s
[13:29:55.673] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:79:0)
[13:29:55.673] Timer tick, asking Buildbucket for the build status
[13:29:56.286] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:56.286] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:80:0) after 1m0s
[13:30:56.288] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:80:0)
[13:30:56.288] Timer tick, asking Buildbucket for the build status
[13:30:56.431] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:56.431] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:81:0) after 1m0s
[13:31:56.429] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:81:0)
[13:31:56.429] Timer tick, asking Buildbucket for the build status
[13:31:56.565] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:56.565] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:82:0) after 1m0s
[13:32:56.571] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:82:0)
[13:32:56.571] Timer tick, asking Buildbucket for the build status
[13:32:58.333] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:58.333] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:83:0) after 1m0s
[13:33:58.366] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:83:0)
[13:33:58.366] Timer tick, asking Buildbucket for the build status
[13:34:00.161] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:00.161] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:84:0) after 1m0s
[13:35:00.185] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:84:0)
[13:35:00.185] Timer tick, asking Buildbucket for the build status
[13:35:02.175] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:02.175] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:85:0) after 1m0s
[13:36:02.337] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:85:0)
[13:36:02.337] Timer tick, asking Buildbucket for the build status
[13:36:02.641] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:02.641] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:86:0) after 1m0s
[13:37:02.620] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:86:0)
[13:37:02.620] Timer tick, asking Buildbucket for the build status
[13:37:02.956] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:02.956] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:87:0) after 1m0s
[13:38:02.978] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:87:0)
[13:38:02.978] Timer tick, asking Buildbucket for the build status
[13:38:03.284] Build 8913484342309789984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:03.284] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9078914405960413280:88:0) after 1m0s
[13:38:37.448] Received PubSub notification, asking Buildbucket for the build status
[13:38:37.953] Build 8913484342309789984: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[13:38:37.953] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1557841116992031",
"created_by": "project:chromium",
"created_ts": "1557835935257641",
"failure_reason": "BUILD_FAILURE",
"id": "8913484342309789984",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"dbd95b99fe062191d33efa75dad11fbbbb8dc261\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 tests failed: ['site_per_process_unit_tests 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\": 33895, \"got_angle_revision\": \"b783fbc799922b43c0988c8686854da85330e679\", \"got_dawn_revision\": \"2e56970932ec01ef2b030fe6b27f305c7733a47f\", \"got_nacl_revision\": \"ca374f5112fca6b36fc5d73e117a60b82f394993\", \"got_revision\": \"dbd95b99fe062191d33efa75dad11fbbbb8dc261\", \"got_revision_cp\": \"refs/heads/master@{#659457}\", \"got_swarming_client_revision\": \"1b65f4e862045f3ba430a4cbd0643f5b1b66c230\", \"got_v8_revision\": \"c27e7b4f22ec6902fa30797a535cca040aa78b0a\", \"got_v8_revision_cp\": \"refs/heads/7.6.172@{#1}\", \"got_webrtc_revision\": \"e62a08a87a6f21672b2ae8ba25632007f417a3f1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27934}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"dbd95b99fe062191d33efa75dad11fbbbb8dc261\", \"swarm_hashes\": {\"components_browsertests\": \"82f8c5c54fbe83d1c48148d50babee92a99f7e9a\", \"components_unittests\": \"a6c7dce2021e138ca0f3786e52936df791eb7688\", \"content_browsertests\": \"a593753609a2969cdd46d762cd1b891cdf61b449\", \"content_unittests\": \"ad749c65ef05094982dad5fbd0b92775745a481d\", \"unit_tests\": \"31402a30f1def91710cb457e27f5e5c8509675e2\"}, \"swarm_hashes_refs/heads/master(at){#659457}_without_patch\": {\"components_browsertests\": \"82f8c5c54fbe83d1c48148d50babee92a99f7e9a\", \"components_unittests\": \"a6c7dce2021e138ca0f3786e52936df791eb7688\", \"content_browsertests\": \"a593753609a2969cdd46d762cd1b891cdf61b449\", \"content_unittests\": \"ad749c65ef05094982dad5fbd0b92775745a481d\", \"unit_tests\": \"31402a30f1def91710cb457e27f5e5c8509675e2\"}}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Site Isolation Android\"], \"caches\": [\"builder_e961d9d7e9bca679490a4aaa8599bd975f9b7e0b713a988ed6895a059d04d2ac_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Ivy_Bridge_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"swarm2384-c4\"], \"inside_docker\": [\"0\"], \"kvm\": [\"0\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4293-d3520ea\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-f\"]}}, \"ui\": {\"info\": \"1 tests failed: ['site_per_process_unit_tests on Android device Nexus 5X']\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1557836058122389",
"status": "COMPLETED",
"status_changed_ts": "1557836058804423",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/33895",
"builder:Site Isolation Android",
"buildset:commit/git/dbd95b99fe062191d33efa75dad11fbbbb8dc261",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dbd95b99fe062191d33efa75dad11fbbbb8dc261",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078914405960413280",
"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/8913484342309789984/+/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:44cf138c102c3510",
"user_agent:luci-scheduler"
],
"updated_ts": "1557841117075679",
"url": "https://ci.chromium.org/b/8913484342309789984",
"utcnow_ts": "1557841117927514"
}
}
[13:38:37.953] Invocation finished in 1h26m25.561084435s with status FAILED