[17:38:56.224] New invocation is queued and will start shortly
[17:38:57.504] Starting the invocation (attempt 1)
[17:38:57.542] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:38:57.542] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:38:57.543] Buildbucket request:
{
"bucket": "luci.celab.ci",
"client_operation_id": "9086322801120437264",
"parameters_json": "{\"builder_name\":\"Linux\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/enterprise/cel.git\",\"revision\":\"4f1947e8830f6f22147344d7f1d3b3318aad9e75\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux",
"scheduler_invocation_id:9086322801120437264",
"scheduler_job_id:celab/Linux",
"user_agent:luci-scheduler",
"buildset:commit/git/4f1947e8830f6f22147344d7f1d3b3318aad9e75",
"buildset:commit/gitiles/chromium.googlesource.com/enterprise/cel/+/4f1947e8830f6f22147344d7f1d3b3318aad9e75",
"gitiles_ref:refs/heads/master"
]
}
[17:38:59.370] Buildbucket response:
{
"build": {
"bucket": "luci.celab.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550770737667416",
"id": "8920892738937929712",
"lease_expiration_ts": "1550889539020714",
"lease_key": "988835160",
"parameters_json": "{\"builder_name\": \"Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/enterprise/cel.git\", \"revision\": \"4f1947e8830f6f22147344d7f1d3b3318aad9e75\"}}",
"project": "celab",
"result_details_json": "{\"properties\": {}}",
"service_account": "celab-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1550770739239745",
"tags": [
"builder:Linux",
"buildset:commit/git/4f1947e8830f6f22147344d7f1d3b3318aad9e75",
"buildset:commit/gitiles/chromium.googlesource.com/enterprise/cel/+/4f1947e8830f6f22147344d7f1d3b3318aad9e75",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9086322801120437264",
"scheduler_job_id:celab/Linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/celab/buildbucket/cr-buildbucket.appspot.com/8920892738937929712/+/annotations",
"swarming_tag:luci_project:celab",
"swarming_tag:recipe_name:celab",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4329f51dfa99d510",
"user_agent:luci-scheduler"
],
"updated_ts": "1550770739239883",
"url": "https://ci.chromium.org/b/8920892738937929712",
"utcnow_ts": "1550770739357560"
}
}
[17:38:59.370] Task URL: https://ci.chromium.org/b/8920892738937929712
[17:38:59.370] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:2:0) after 1m0s
[17:39:15.446] Received PubSub notification, asking Buildbucket for the build status
[17:39:15.507] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:59.423] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:2:0)
[17:39:59.423] Timer tick, asking Buildbucket for the build status
[17:39:59.555] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:59.555] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:4:0) after 1m0s
[17:40:59.573] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:4:0)
[17:40:59.573] Timer tick, asking Buildbucket for the build status
[17:40:59.619] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:59.620] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:5:0) after 1m0s
[17:41:59.635] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:5:0)
[17:41:59.635] Timer tick, asking Buildbucket for the build status
[17:41:59.722] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:59.722] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:6:0) after 1m0s
[17:42:59.761] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:6:0)
[17:42:59.761] Timer tick, asking Buildbucket for the build status
[17:42:59.854] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:59.854] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:7:0) after 1m0s
[17:43:59.871] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:7:0)
[17:43:59.871] Timer tick, asking Buildbucket for the build status
[17:43:59.931] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:59.931] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:8:0) after 1m0s
[17:44:59.948] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:8:0)
[17:44:59.948] Timer tick, asking Buildbucket for the build status
[17:44:59.995] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:59.995] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:9:0) after 1m0s
[17:46:00.014] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:9:0)
[17:46:00.014] Timer tick, asking Buildbucket for the build status
[17:46:00.083] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:00.083] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:10:0) after 1m0s
[17:47:00.108] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:10:0)
[17:47:00.108] Timer tick, asking Buildbucket for the build status
[17:47:00.151] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:00.151] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:11:0) after 1m0s
[17:48:00.169] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:11:0)
[17:48:00.169] Timer tick, asking Buildbucket for the build status
[17:48:00.225] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:00.225] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:12:0) after 1m0s
[17:49:00.241] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:12:0)
[17:49:00.241] Timer tick, asking Buildbucket for the build status
[17:49:00.289] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:00.289] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:13:0) after 1m0s
[17:50:00.342] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:13:0)
[17:50:00.342] Timer tick, asking Buildbucket for the build status
[17:50:00.425] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:00.425] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:14:0) after 1m0s
[17:51:00.453] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:14:0)
[17:51:00.453] Timer tick, asking Buildbucket for the build status
[17:51:00.566] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:00.566] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:15:0) after 1m0s
[17:52:00.584] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:15:0)
[17:52:00.584] Timer tick, asking Buildbucket for the build status
[17:52:00.629] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:00.629] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:16:0) after 1m0s
[17:53:00.645] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:16:0)
[17:53:00.645] Timer tick, asking Buildbucket for the build status
[17:53:00.771] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:00.771] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:17:0) after 1m0s
[17:54:00.786] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:17:0)
[17:54:00.786] Timer tick, asking Buildbucket for the build status
[17:54:00.854] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:00.854] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:18:0) after 1m0s
[17:55:00.869] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:18:0)
[17:55:00.869] Timer tick, asking Buildbucket for the build status
[17:55:00.917] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:00.917] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:19:0) after 1m0s
[17:56:00.938] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:19:0)
[17:56:00.938] Timer tick, asking Buildbucket for the build status
[17:56:00.991] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:00.992] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:20:0) after 1m0s
[17:57:01.015] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:20:0)
[17:57:01.015] Timer tick, asking Buildbucket for the build status
[17:57:01.088] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:01.088] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:21:0) after 1m0s
[17:58:01.106] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:21:0)
[17:58:01.106] Timer tick, asking Buildbucket for the build status
[17:58:01.318] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:01.318] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:22:0) after 1m0s
[17:59:01.338] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:22:0)
[17:59:01.338] Timer tick, asking Buildbucket for the build status
[17:59:01.590] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:01.590] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:23:0) after 1m0s
[18:00:01.615] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:23:0)
[18:00:01.615] Timer tick, asking Buildbucket for the build status
[18:00:01.681] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:01.681] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:24:0) after 1m0s
[18:01:01.699] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:24:0)
[18:01:01.699] Timer tick, asking Buildbucket for the build status
[18:01:02.017] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:02.017] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:25:0) after 1m0s
[18:02:02.059] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:25:0)
[18:02:02.059] Timer tick, asking Buildbucket for the build status
[18:02:02.134] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:02.134] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:26:0) after 1m0s
[18:03:02.160] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:26:0)
[18:03:02.160] Timer tick, asking Buildbucket for the build status
[18:03:02.298] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:02.298] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:27:0) after 1m0s
[18:04:02.319] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:27:0)
[18:04:02.319] Timer tick, asking Buildbucket for the build status
[18:04:02.392] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:02.392] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:28:0) after 1m0s
[18:05:02.413] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:28:0)
[18:05:02.413] Timer tick, asking Buildbucket for the build status
[18:05:02.516] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:02.516] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:29:0) after 1m0s
[18:06:02.537] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:29:0)
[18:06:02.537] Timer tick, asking Buildbucket for the build status
[18:06:02.622] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:02.622] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:30:0) after 1m0s
[18:07:02.646] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:30:0)
[18:07:02.646] Timer tick, asking Buildbucket for the build status
[18:07:02.759] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:02.759] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:31:0) after 1m0s
[18:08:02.781] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:31:0)
[18:08:02.781] Timer tick, asking Buildbucket for the build status
[18:08:02.861] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:02.861] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:32:0) after 1m0s
[18:09:02.893] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:32:0)
[18:09:02.893] Timer tick, asking Buildbucket for the build status
[18:09:03.114] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:03.114] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:33:0) after 1m0s
[18:10:03.152] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:33:0)
[18:10:03.152] Timer tick, asking Buildbucket for the build status
[18:10:03.386] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:03.386] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:34:0) after 1m0s
[18:11:03.495] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:34:0)
[18:11:03.495] Timer tick, asking Buildbucket for the build status
[18:11:03.575] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:03.575] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:35:0) after 1m0s
[18:12:03.604] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:35:0)
[18:12:03.604] Timer tick, asking Buildbucket for the build status
[18:12:03.665] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:03.665] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:36:0) after 1m0s
[18:13:03.682] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:36:0)
[18:13:03.682] Timer tick, asking Buildbucket for the build status
[18:13:03.730] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:03.730] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:37:0) after 1m0s
[18:14:03.754] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:37:0)
[18:14:03.754] Timer tick, asking Buildbucket for the build status
[18:14:03.908] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:03.908] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:38:0) after 1m0s
[18:15:03.941] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:38:0)
[18:15:03.941] Timer tick, asking Buildbucket for the build status
[18:15:03.992] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:03.992] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:39:0) after 1m0s
[18:16:04.013] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:39:0)
[18:16:04.013] Timer tick, asking Buildbucket for the build status
[18:16:04.087] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:04.087] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:40:0) after 1m0s
[18:17:04.159] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:40:0)
[18:17:04.159] Timer tick, asking Buildbucket for the build status
[18:17:04.210] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:04.210] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:41:0) after 1m0s
[18:18:04.247] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:41:0)
[18:18:04.247] Timer tick, asking Buildbucket for the build status
[18:18:04.297] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:04.297] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:42:0) after 1m0s
[18:19:04.323] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:42:0)
[18:19:04.323] Timer tick, asking Buildbucket for the build status
[18:19:04.377] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:04.377] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:43:0) after 1m0s
[18:20:04.434] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:43:0)
[18:20:04.434] Timer tick, asking Buildbucket for the build status
[18:20:04.791] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:04.791] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:44:0) after 1m0s
[18:21:04.810] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:44:0)
[18:21:04.810] Timer tick, asking Buildbucket for the build status
[18:21:04.892] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:04.892] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:45:0) after 1m0s
[18:22:04.910] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:45:0)
[18:22:04.910] Timer tick, asking Buildbucket for the build status
[18:22:05.094] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:05.094] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:46:0) after 1m0s
[18:23:05.202] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:46:0)
[18:23:05.202] Timer tick, asking Buildbucket for the build status
[18:23:05.260] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:05.260] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:47:0) after 1m0s
[18:24:05.302] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:47:0)
[18:24:05.302] Timer tick, asking Buildbucket for the build status
[18:24:05.360] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:05.360] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:48:0) after 1m0s
[18:25:05.384] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:48:0)
[18:25:05.384] Timer tick, asking Buildbucket for the build status
[18:25:05.452] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:05.452] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:49:0) after 1m0s
[18:26:05.472] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:49:0)
[18:26:05.473] Timer tick, asking Buildbucket for the build status
[18:26:05.541] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:05.541] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:50:0) after 1m0s
[18:27:05.560] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:50:0)
[18:27:05.560] Timer tick, asking Buildbucket for the build status
[18:27:05.618] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:05.618] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:51:0) after 1m0s
[18:28:05.642] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:51:0)
[18:28:05.642] Timer tick, asking Buildbucket for the build status
[18:28:05.721] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:05.721] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:52:0) after 1m0s
[18:29:05.746] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:52:0)
[18:29:05.746] Timer tick, asking Buildbucket for the build status
[18:29:05.981] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:05.981] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:53:0) after 1m0s
[18:30:05.999] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:53:0)
[18:30:05.999] Timer tick, asking Buildbucket for the build status
[18:30:06.145] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:06.145] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:54:0) after 1m0s
[18:31:06.169] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:54:0)
[18:31:06.169] Timer tick, asking Buildbucket for the build status
[18:31:06.504] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:06.505] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:55:0) after 1m0s
[18:32:06.522] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:55:0)
[18:32:06.522] Timer tick, asking Buildbucket for the build status
[18:32:06.584] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:06.584] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:56:0) after 1m0s
[18:33:06.640] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:56:0)
[18:33:06.640] Timer tick, asking Buildbucket for the build status
[18:33:06.805] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:06.805] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:57:0) after 1m0s
[18:34:06.846] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:57:0)
[18:34:06.846] Timer tick, asking Buildbucket for the build status
[18:34:06.927] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:06.927] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:58:0) after 1m0s
[18:35:06.947] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:58:0)
[18:35:06.947] Timer tick, asking Buildbucket for the build status
[18:35:07.005] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:07.005] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:59:0) after 1m0s
[18:36:07.049] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:59:0)
[18:36:07.049] Timer tick, asking Buildbucket for the build status
[18:36:07.122] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:07.122] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:60:0) after 1m0s
[18:37:07.139] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:60:0)
[18:37:07.139] Timer tick, asking Buildbucket for the build status
[18:37:07.195] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:07.195] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:61:0) after 1m0s
[18:38:07.223] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:61:0)
[18:38:07.223] Timer tick, asking Buildbucket for the build status
[18:38:07.452] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:07.452] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:62:0) after 1m0s
[18:39:07.475] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:62:0)
[18:39:07.475] Timer tick, asking Buildbucket for the build status
[18:39:07.521] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:07.521] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:63:0) after 1m0s
[18:40:07.562] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:63:0)
[18:40:07.562] Timer tick, asking Buildbucket for the build status
[18:40:07.635] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:07.635] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:64:0) after 1m0s
[18:41:07.664] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:64:0)
[18:41:07.664] Timer tick, asking Buildbucket for the build status
[18:41:07.720] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:07.720] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:65:0) after 1m0s
[18:42:07.750] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:65:0)
[18:42:07.750] Timer tick, asking Buildbucket for the build status
[18:42:07.871] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:07.871] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:66:0) after 1m0s
[18:43:07.890] Handling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:66:0)
[18:43:07.890] Timer tick, asking Buildbucket for the build status
[18:43:07.940] Build 8920892738937929712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:07.940] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9086322801120437264:67:0) after 1m0s
[18:43:59.048] Received PubSub notification, asking Buildbucket for the build status
[18:43:59.099] Build 8920892738937929712: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[18:43:59.099] Buildbucket build:
{
"build": {
"bucket": "luci.celab.ci",
"canary_preference": "AUTO",
"completed_ts": "1550774638144479",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550770737667416",
"failure_reason": "BUILD_FAILURE",
"id": "8920892738937929712",
"parameters_json": "{\"builder_name\": \"Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/enterprise/cel.git\", \"revision\": \"4f1947e8830f6f22147344d7f1d3b3318aad9e75\"}}",
"project": "celab",
"result": "FAILURE",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"0\"], \"zone\": [\"us\", \"us-golo\", \"us-golo-1\"], \"inside_docker\": [\"0\"], \"locale\": [\"en_US.ISO8859-1\"], \"kvm\": [\"0\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-E5-2670\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4115-b99fdd5\"], \"machine_type\": [\"n1-highcpu-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"id\": [\"vm78-m1\"], \"pool\": [\"luci.flex.ci\"], \"caches\": [\"git\", \"goma_v2\", \"infra_gclient_with_go\", \"vpython\"]}}, \"ui\": {\"info\": \"Failure run all tests\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/mnt/data/b/s/w/ir/cache\", \"temp_dir\": \"/mnt/data/b/s/w/ir/tmp/rt\"}, \"target_cpu\": \"x64\", \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Linux\", \"key\": \"builder\"}, {\"value\": \"commit/git/4f1947e8830f6f22147344d7f1d3b3318aad9e75\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/enterprise/cel/+/4f1947e8830f6f22147344d7f1d3b3318aad9e75\", \"key\": \"buildset\"}, {\"value\": \"9086322801120437264\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"celab/Linux\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"celab\", \"builder\": \"Linux\", \"bucket\": \"ci\"}, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"3dab7a70bce848a6486670afc32d65ade65b8058\"}, \"recipe\": {\"name\": \"celab\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8920892738937929712\", \"input\": {\"gitilesCommit\": {\"project\": \"enterprise/cel\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"4f1947e8830f6f22147344d7f1d3b3318aad9e75\"}}, \"createTime\": \"2019-02-21T17:38:57.667416Z\"}}, \"repository\": \"https://chromium.googlesource.com/enterprise/cel.git\", \"buildername\": \"Linux\", \"got_revision\": \"4f1947e8830f6f22147344d7f1d3b3318aad9e75\", \"recipe\": \"celab\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1550770737667416, \"tags\": [\"builder:Linux\", \"buildset:commit/git/4f1947e8830f6f22147344d7f1d3b3318aad9e75\", \"buildset:commit/gitiles/chromium.googlesource.com/enterprise/cel/+/4f1947e8830f6f22147344d7f1d3b3318aad9e75\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9086322801120437264\", \"scheduler_job_id:celab/Linux\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.celab.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"celab\", \"id\": \"8920892738937929712\"}}, \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"4f1947e8830f6f22147344d7f1d3b3318aad9e75\", \"bot_id\": \"vm78-m1\"}}",
"service_account": "celab-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1550770754304750",
"status": "COMPLETED",
"status_changed_ts": "1550774638765623",
"tags": [
"builder:Linux",
"buildset:commit/git/4f1947e8830f6f22147344d7f1d3b3318aad9e75",
"buildset:commit/gitiles/chromium.googlesource.com/enterprise/cel/+/4f1947e8830f6f22147344d7f1d3b3318aad9e75",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9086322801120437264",
"scheduler_job_id:celab/Linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/celab/buildbucket/cr-buildbucket.appspot.com/8920892738937929712/+/annotations",
"swarming_tag:luci_project:celab",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:celab",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4329f51dfa99d510",
"user_agent:luci-scheduler"
],
"updated_ts": "1550774638766779",
"url": "https://ci.chromium.org/b/8920892738937929712",
"utcnow_ts": "1550774639087252"
}
}
[18:43:59.099] Invocation finished in 1h5m2.897033081s with status FAILED