[21:07:08.629] New invocation is queued and will start shortly
[21:07:09.706] Starting the invocation (attempt 1)
[21:07:09.750] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:07:09.750] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:07:09.750] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9079152541532696432",
"parameters_json": "{\"builder_name\":\"Site Isolation Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"4464158ace883b09236303909cbecd2687d97e5e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Site Isolation Android",
"scheduler_invocation_id:9079152541532696432",
"scheduler_job_id:chromium/Site Isolation Android",
"user_agent:luci-scheduler",
"buildset:commit/git/4464158ace883b09236303909cbecd2687d97e5e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4464158ace883b09236303909cbecd2687d97e5e",
"gitiles_ref:refs/heads/master"
]
}
[21:07:11.485] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1557608829963327",
"id": "8913722479471377632",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"4464158ace883b09236303909cbecd2687d97e5e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557608831195413",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/33851",
"builder:Site Isolation Android",
"buildset:commit/git/4464158ace883b09236303909cbecd2687d97e5e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4464158ace883b09236303909cbecd2687d97e5e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079152541532696432",
"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/8913722479471377632/+/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": "1557608831195505",
"url": "https://ci.chromium.org/b/8913722479471377632",
"utcnow_ts": "1557608831478605"
}
}
[21:07:11.486] Task URL: https://ci.chromium.org/b/8913722479471377632
[21:07:11.486] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:2:0) after 1m0s
[21:08:11.504] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:2:0)
[21:08:11.504] Timer tick, asking Buildbucket for the build status
[21:08:11.777] Build 8913722479471377632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:08:11.777] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:3:0) after 1m0s
[21:09:11.803] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:3:0)
[21:09:11.803] Timer tick, asking Buildbucket for the build status
[21:09:12.584] Build 8913722479471377632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:09:12.584] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:4:0) after 1m0s
[21:09:21.842] Received PubSub notification, asking Buildbucket for the build status
[21:09:22.577] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:12.613] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:4:0)
[21:10:12.613] Timer tick, asking Buildbucket for the build status
[21:10:12.920] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:12.920] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:6:0) after 1m0s
[21:11:12.937] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:6:0)
[21:11:12.937] Timer tick, asking Buildbucket for the build status
[21:11:14.084] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:14.084] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:7:0) after 1m0s
[21:12:14.089] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:7:0)
[21:12:14.089] Timer tick, asking Buildbucket for the build status
[21:12:14.223] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:14.223] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:8:0) after 1m0s
[21:13:14.269] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:8:0)
[21:13:14.269] Timer tick, asking Buildbucket for the build status
[21:13:14.400] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:14.400] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:9:0) after 1m0s
[21:14:14.419] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:9:0)
[21:14:14.419] Timer tick, asking Buildbucket for the build status
[21:14:14.603] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:14.603] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:10:0) after 1m0s
[21:15:14.632] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:10:0)
[21:15:14.632] Timer tick, asking Buildbucket for the build status
[21:15:14.881] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:14.881] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:11:0) after 1m0s
[21:16:14.920] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:11:0)
[21:16:14.920] Timer tick, asking Buildbucket for the build status
[21:16:15.213] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:15.213] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:12:0) after 1m0s
[21:17:15.241] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:12:0)
[21:17:15.241] Timer tick, asking Buildbucket for the build status
[21:17:15.417] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:15.417] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:13:0) after 1m0s
[21:18:15.426] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:13:0)
[21:18:15.426] Timer tick, asking Buildbucket for the build status
[21:18:18.084] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:18.084] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:14:0) after 1m0s
[21:19:18.133] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:14:0)
[21:19:18.133] Timer tick, asking Buildbucket for the build status
[21:19:18.345] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:18.345] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:15:0) after 1m0s
[21:20:18.361] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:15:0)
[21:20:18.361] Timer tick, asking Buildbucket for the build status
[21:20:20.415] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:20.415] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:16:0) after 1m0s
[21:21:20.442] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:16:0)
[21:21:20.442] Timer tick, asking Buildbucket for the build status
[21:21:20.855] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:20.855] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:17:0) after 1m0s
[21:22:20.875] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:17:0)
[21:22:20.875] Timer tick, asking Buildbucket for the build status
[21:22:21.747] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:21.747] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:18:0) after 1m0s
[21:23:21.766] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:18:0)
[21:23:21.766] Timer tick, asking Buildbucket for the build status
[21:23:22.304] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:22.304] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:19:0) after 1m0s
[21:24:22.320] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:19:0)
[21:24:22.320] Timer tick, asking Buildbucket for the build status
[21:24:22.682] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:22.682] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:20:0) after 1m0s
[21:25:22.723] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:20:0)
[21:25:22.723] Timer tick, asking Buildbucket for the build status
[21:25:22.932] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:22.932] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:21:0) after 1m0s
[21:26:22.954] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:21:0)
[21:26:22.954] Timer tick, asking Buildbucket for the build status
[21:26:23.120] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:23.120] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:22:0) after 1m0s
[21:27:23.144] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:22:0)
[21:27:23.144] Timer tick, asking Buildbucket for the build status
[21:27:23.250] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:23.250] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:23:0) after 1m0s
[21:28:23.267] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:23:0)
[21:28:23.267] Timer tick, asking Buildbucket for the build status
[21:28:23.426] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:23.426] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:24:0) after 1m0s
[21:29:23.443] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:24:0)
[21:29:23.443] Timer tick, asking Buildbucket for the build status
[21:29:23.581] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:23.581] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:25:0) after 1m0s
[21:30:23.609] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:25:0)
[21:30:23.609] Timer tick, asking Buildbucket for the build status
[21:30:24.512] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:24.512] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:26:0) after 1m0s
[21:31:24.533] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:26:0)
[21:31:24.533] Timer tick, asking Buildbucket for the build status
[21:31:24.681] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:24.681] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:27:0) after 1m0s
[21:32:24.707] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:27:0)
[21:32:24.707] Timer tick, asking Buildbucket for the build status
[21:32:25.177] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:25.177] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:28:0) after 1m0s
[21:33:25.198] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:28:0)
[21:33:25.198] Timer tick, asking Buildbucket for the build status
[21:33:25.768] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:25.768] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:29:0) after 1m0s
[21:34:25.752] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:29:0)
[21:34:25.752] Timer tick, asking Buildbucket for the build status
[21:34:26.619] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:26.619] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:30:0) after 1m0s
[21:35:26.611] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:30:0)
[21:35:26.611] Timer tick, asking Buildbucket for the build status
[21:35:26.729] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:26.729] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:31:0) after 1m0s
[21:36:26.751] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:31:0)
[21:36:26.752] Timer tick, asking Buildbucket for the build status
[21:36:26.963] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:26.963] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:32:0) after 1m0s
[21:37:26.967] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:32:0)
[21:37:26.967] Timer tick, asking Buildbucket for the build status
[21:37:27.254] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:27.254] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:33:0) after 1m0s
[21:38:27.281] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:33:0)
[21:38:27.281] Timer tick, asking Buildbucket for the build status
[21:38:28.197] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:28.197] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:34:0) after 1m0s
[21:39:28.228] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:34:0)
[21:39:28.228] Timer tick, asking Buildbucket for the build status
[21:39:29.450] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:29.450] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:35:0) after 1m0s
[21:40:29.495] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:35:0)
[21:40:29.495] Timer tick, asking Buildbucket for the build status
[21:40:30.813] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:30.813] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:36:0) after 1m0s
[21:41:30.859] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:36:0)
[21:41:30.859] Timer tick, asking Buildbucket for the build status
[21:41:31.280] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:31.280] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:37:0) after 1m0s
[21:42:31.304] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:37:0)
[21:42:31.304] Timer tick, asking Buildbucket for the build status
[21:42:31.456] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:31.456] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:38:0) after 1m0s
[21:43:31.477] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:38:0)
[21:43:31.478] Timer tick, asking Buildbucket for the build status
[21:43:31.594] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:31.595] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:39:0) after 1m0s
[21:44:31.580] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:39:0)
[21:44:31.580] Timer tick, asking Buildbucket for the build status
[21:44:31.780] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:31.780] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:40:0) after 1m0s
[21:45:31.803] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:40:0)
[21:45:31.803] Timer tick, asking Buildbucket for the build status
[21:45:32.253] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:32.253] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:41:0) after 1m0s
[21:46:32.244] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:41:0)
[21:46:32.244] Timer tick, asking Buildbucket for the build status
[21:46:33.966] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:33.966] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:42:0) after 1m0s
[21:47:33.989] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:42:0)
[21:47:33.989] Timer tick, asking Buildbucket for the build status
[21:47:35.296] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:35.296] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:43:0) after 1m0s
[21:48:35.275] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:43:0)
[21:48:35.275] Timer tick, asking Buildbucket for the build status
[21:48:35.475] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:35.475] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:44:0) after 1m0s
[21:49:35.497] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:44:0)
[21:49:35.497] Timer tick, asking Buildbucket for the build status
[21:49:36.046] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:36.046] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:45:0) after 1m0s
[21:50:36.051] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:45:0)
[21:50:36.052] Timer tick, asking Buildbucket for the build status
[21:50:36.271] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:36.271] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:46:0) after 1m0s
[21:51:36.292] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:46:0)
[21:51:36.292] Timer tick, asking Buildbucket for the build status
[21:51:36.412] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:36.412] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:47:0) after 1m0s
[21:52:36.402] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:47:0)
[21:52:36.402] Timer tick, asking Buildbucket for the build status
[21:52:37.083] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:37.083] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:48:0) after 1m0s
[21:53:37.064] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:48:0)
[21:53:37.064] Timer tick, asking Buildbucket for the build status
[21:53:37.504] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:37.504] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:49:0) after 1m0s
[21:54:37.528] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:49:0)
[21:54:37.528] Timer tick, asking Buildbucket for the build status
[21:54:37.965] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:37.965] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:50:0) after 1m0s
[21:55:37.937] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:50:0)
[21:55:37.937] Timer tick, asking Buildbucket for the build status
[21:55:38.079] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:38.079] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:51:0) after 1m0s
[21:56:38.103] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:51:0)
[21:56:38.103] Timer tick, asking Buildbucket for the build status
[21:56:39.844] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:39.844] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:52:0) after 1m0s
[21:57:39.863] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:52:0)
[21:57:39.863] Timer tick, asking Buildbucket for the build status
[21:57:39.966] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:39.966] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:53:0) after 1m0s
[21:58:40.012] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:53:0)
[21:58:40.012] Timer tick, asking Buildbucket for the build status
[21:58:41.887] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:41.887] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:54:0) after 1m0s
[21:59:41.905] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:54:0)
[21:59:41.905] Timer tick, asking Buildbucket for the build status
[21:59:42.099] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:42.099] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:55:0) after 1m0s
[22:00:42.186] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:55:0)
[22:00:42.186] Timer tick, asking Buildbucket for the build status
[22:00:42.313] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:42.313] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:56:0) after 1m0s
[22:01:42.332] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:56:0)
[22:01:42.332] Timer tick, asking Buildbucket for the build status
[22:01:43.072] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:43.072] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:57:0) after 1m0s
[22:02:43.069] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:57:0)
[22:02:43.069] Timer tick, asking Buildbucket for the build status
[22:02:43.181] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:43.181] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:58:0) after 1m0s
[22:03:43.204] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:58:0)
[22:03:43.204] Timer tick, asking Buildbucket for the build status
[22:03:43.492] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:43.492] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:59:0) after 1m0s
[22:04:43.519] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:59:0)
[22:04:43.519] Timer tick, asking Buildbucket for the build status
[22:04:45.576] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:45.577] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:60:0) after 1m0s
[22:05:45.572] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:60:0)
[22:05:45.572] Timer tick, asking Buildbucket for the build status
[22:05:45.915] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:45.915] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:61:0) after 1m0s
[22:06:45.950] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:61:0)
[22:06:45.950] Timer tick, asking Buildbucket for the build status
[22:06:46.056] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:46.056] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:62:0) after 1m0s
[22:07:46.060] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:62:0)
[22:07:46.060] Timer tick, asking Buildbucket for the build status
[22:07:46.529] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:46.529] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:63:0) after 1m0s
[22:08:46.550] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:63:0)
[22:08:46.550] Timer tick, asking Buildbucket for the build status
[22:08:48.910] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:48.910] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:64:0) after 1m0s
[22:09:48.916] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:64:0)
[22:09:48.916] Timer tick, asking Buildbucket for the build status
[22:09:49.018] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:49.018] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:65:0) after 1m0s
[22:10:49.017] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:65:0)
[22:10:49.017] Timer tick, asking Buildbucket for the build status
[22:10:50.234] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:50.234] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:66:0) after 1m0s
[22:11:50.261] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:66:0)
[22:11:50.261] Timer tick, asking Buildbucket for the build status
[22:11:51.285] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:51.285] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:67:0) after 1m0s
[22:12:51.313] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:67:0)
[22:12:51.313] Timer tick, asking Buildbucket for the build status
[22:12:51.420] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:51.421] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:68:0) after 1m0s
[22:13:51.443] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:68:0)
[22:13:51.443] Timer tick, asking Buildbucket for the build status
[22:13:53.710] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:53.710] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:69:0) after 1m0s
[22:14:53.741] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:69:0)
[22:14:53.741] Timer tick, asking Buildbucket for the build status
[22:14:54.021] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:54.021] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:70:0) after 1m0s
[22:15:54.033] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:70:0)
[22:15:54.033] Timer tick, asking Buildbucket for the build status
[22:15:54.787] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:54.787] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:71:0) after 1m0s
[22:16:54.822] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:71:0)
[22:16:54.822] Timer tick, asking Buildbucket for the build status
[22:16:54.998] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:54.998] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:72:0) after 1m0s
[22:17:55.023] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:72:0)
[22:17:55.023] Timer tick, asking Buildbucket for the build status
[22:17:55.356] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:55.356] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:73:0) after 1m0s
[22:18:55.379] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:73:0)
[22:18:55.379] Timer tick, asking Buildbucket for the build status
[22:18:55.612] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:55.612] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:74:0) after 1m0s
[22:19:55.688] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:74:0)
[22:19:55.688] Timer tick, asking Buildbucket for the build status
[22:19:56.671] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:56.671] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:75:0) after 1m0s
[22:20:56.693] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:75:0)
[22:20:56.693] Timer tick, asking Buildbucket for the build status
[22:20:56.796] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:56.796] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:76:0) after 1m0s
[22:21:56.822] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:76:0)
[22:21:56.822] Timer tick, asking Buildbucket for the build status
[22:21:56.932] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:56.932] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:77:0) after 1m0s
[22:22:56.954] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:77:0)
[22:22:56.954] Timer tick, asking Buildbucket for the build status
[22:22:58.312] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:58.312] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:78:0) after 1m0s
[22:23:58.313] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:78:0)
[22:23:58.313] Timer tick, asking Buildbucket for the build status
[22:23:58.577] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:58.577] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:79:0) after 1m0s
[22:24:58.581] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:79:0)
[22:24:58.581] Timer tick, asking Buildbucket for the build status
[22:24:58.710] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:58.710] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:80:0) after 1m0s
[22:25:58.703] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:80:0)
[22:25:58.703] Timer tick, asking Buildbucket for the build status
[22:25:58.920] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:58.920] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:81:0) after 1m0s
[22:26:58.938] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:81:0)
[22:26:58.938] Timer tick, asking Buildbucket for the build status
[22:26:59.367] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:59.367] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:82:0) after 1m0s
[22:27:59.460] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:82:0)
[22:27:59.460] Timer tick, asking Buildbucket for the build status
[22:27:59.819] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:59.819] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:83:0) after 1m0s
[22:28:59.841] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:83:0)
[22:28:59.841] Timer tick, asking Buildbucket for the build status
[22:29:00.205] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:00.205] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:84:0) after 1m0s
[22:30:00.227] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:84:0)
[22:30:00.227] Timer tick, asking Buildbucket for the build status
[22:30:00.676] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:00.676] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:85:0) after 1m0s
[22:31:00.706] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:85:0)
[22:31:00.706] Timer tick, asking Buildbucket for the build status
[22:31:01.272] Build 8913722479471377632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:01.272] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9079152541532696432:86:0) after 1m0s
[22:31:48.683] Received PubSub notification, asking Buildbucket for the build status
[22:31:49.576] Build 8913722479471377632: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[22:31:49.576] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1557613907844566",
"created_by": "project:chromium",
"created_ts": "1557608829963327",
"failure_reason": "BUILD_FAILURE",
"id": "8913722479471377632",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"4464158ace883b09236303909cbecd2687d97e5e\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"2 tests failed: ['site_per_process_unit_tests 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\": 33851, \"got_angle_revision\": \"3dbe480b559e7919f303a15b4d4b0c788229c3ee\", \"got_dawn_revision\": \"97f08fa2e68f3505384395a8b6594fb70bdec6a3\", \"got_nacl_revision\": \"ca374f5112fca6b36fc5d73e117a60b82f394993\", \"got_revision\": \"4464158ace883b09236303909cbecd2687d97e5e\", \"got_revision_cp\": \"refs/heads/master@{#658905}\", \"got_swarming_client_revision\": \"1b65f4e862045f3ba430a4cbd0643f5b1b66c230\", \"got_v8_revision\": \"512e74e0bdb9d98c4d729551c294127fff4b0434\", \"got_v8_revision_cp\": \"refs/heads/7.6.160@{#1}\", \"got_webrtc_revision\": \"46afbf9481fbcc939c998c898ca1031ce41cc6b1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27917}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"4464158ace883b09236303909cbecd2687d97e5e\", \"swarm_hashes\": {\"components_browsertests\": \"ab3118cb237434f98da96187deebd2d0fb06b165\", \"components_unittests\": \"4fbb9b77f8b253f38089da963b8f8ed96413a53e\", \"content_browsertests\": \"af6de2da015085cc782c648a2fe6897c3b614939\", \"content_unittests\": \"029ec0557181d214cdf7f75e304c01043a47f5cc\", \"unit_tests\": \"44858b9bc3b48ca86335fbff697a280cf937ae8c\"}, \"swarm_hashes_refs/heads/master(at){#658905}_without_patch\": {\"components_browsertests\": \"ab3118cb237434f98da96187deebd2d0fb06b165\", \"components_unittests\": \"4fbb9b77f8b253f38089da963b8f8ed96413a53e\", \"content_browsertests\": \"af6de2da015085cc782c648a2fe6897c3b614939\", \"content_unittests\": \"029ec0557181d214cdf7f75e304c01043a47f5cc\", \"unit_tests\": \"44858b9bc3b48ca86335fbff697a280cf937ae8c\"}}, \"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\": [\"4292-d8c05fa\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-f\"]}}, \"ui\": {\"info\": \"2 tests failed: ['site_per_process_unit_tests 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": "1557608960377887",
"status": "COMPLETED",
"status_changed_ts": "1557608961601190",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/33851",
"builder:Site Isolation Android",
"buildset:commit/git/4464158ace883b09236303909cbecd2687d97e5e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4464158ace883b09236303909cbecd2687d97e5e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079152541532696432",
"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/8913722479471377632/+/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:44c18a2e4be07210",
"user_agent:luci-scheduler"
],
"updated_ts": "1557613907970117",
"url": "https://ci.chromium.org/b/8913722479471377632",
"utcnow_ts": "1557613909512234"
}
}
[22:31:49.576] Invocation finished in 1h24m40.96309479s with status FAILED