[18:20:38.381] New invocation is queued and will start shortly
[18:20:39.451] Starting the invocation (attempt 1)
[18:20:39.489] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:20:39.489] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:20:39.489] Buildbucket request:
{
"bucket": "luci.celab.ci",
"client_operation_id": "9082152716957846784",
"parameters_json": "{\"builder_name\":\"Linux\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/enterprise/cel.git\",\"revision\":\"35ff6ff537649348e8a0786b215a9ab1fd221650\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux",
"scheduler_invocation_id:9082152716957846784",
"scheduler_job_id:celab/Linux",
"user_agent:luci-scheduler",
"buildset:commit/git/35ff6ff537649348e8a0786b215a9ab1fd221650",
"buildset:commit/gitiles/chromium.googlesource.com/enterprise/cel/+/35ff6ff537649348e8a0786b215a9ab1fd221650",
"gitiles_ref:refs/heads/master"
]
}
[18:20:39.897] Buildbucket response:
{
"build": {
"bucket": "luci.celab.ci",
"canary_preference": "AUTO",
"created_by": "project:celab",
"created_ts": "1554747639565514",
"id": "8916722655053824960",
"parameters_json": "{\"builder_name\": \"Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/enterprise/cel.git\", \"revision\": \"35ff6ff537649348e8a0786b215a9ab1fd221650\"}}",
"project": "celab",
"result_details_json": "{\"properties\": {}}",
"service_account": "celab-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554747639777695",
"tags": [
"builder:Linux",
"buildset:commit/git/35ff6ff537649348e8a0786b215a9ab1fd221650",
"buildset:commit/gitiles/chromium.googlesource.com/enterprise/cel/+/35ff6ff537649348e8a0786b215a9ab1fd221650",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082152716957846784",
"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/8916722655053824960/+/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:",
"user_agent:luci-scheduler"
],
"updated_ts": "1554747639777789",
"url": "https://ci.chromium.org/b/8916722655053824960",
"utcnow_ts": "1554747639888329"
}
}
[18:20:39.897] Task URL: https://ci.chromium.org/b/8916722655053824960
[18:20:39.897] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:2:0) after 1m0s
[18:21:39.197] Received PubSub notification, asking Buildbucket for the build status
[18:21:39.255] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:39.994] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:2:0)
[18:21:39.994] Timer tick, asking Buildbucket for the build status
[18:21:40.084] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:40.084] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:4:0) after 1m0s
[18:22:40.104] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:4:0)
[18:22:40.104] Timer tick, asking Buildbucket for the build status
[18:22:40.182] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:40.182] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:5:0) after 1m0s
[18:23:40.199] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:5:0)
[18:23:40.199] Timer tick, asking Buildbucket for the build status
[18:23:40.295] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:40.295] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:6:0) after 1m0s
[18:24:40.332] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:6:0)
[18:24:40.332] Timer tick, asking Buildbucket for the build status
[18:24:40.412] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:40.412] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:7:0) after 1m0s
[18:25:40.430] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:7:0)
[18:25:40.431] Timer tick, asking Buildbucket for the build status
[18:25:40.606] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:40.606] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:8:0) after 1m0s
[18:26:40.626] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:8:0)
[18:26:40.626] Timer tick, asking Buildbucket for the build status
[18:26:40.716] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:40.716] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:9:0) after 1m0s
[18:27:40.724] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:9:0)
[18:27:40.724] Timer tick, asking Buildbucket for the build status
[18:27:40.830] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:40.830] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:10:0) after 1m0s
[18:28:40.847] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:10:0)
[18:28:40.847] Timer tick, asking Buildbucket for the build status
[18:28:40.890] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:40.890] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:11:0) after 1m0s
[18:29:40.967] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:11:0)
[18:29:40.967] Timer tick, asking Buildbucket for the build status
[18:29:41.083] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:41.083] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:12:0) after 1m0s
[18:30:41.104] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:12:0)
[18:30:41.104] Timer tick, asking Buildbucket for the build status
[18:30:41.163] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:41.163] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:13:0) after 1m0s
[18:31:41.145] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:13:0)
[18:31:41.145] Timer tick, asking Buildbucket for the build status
[18:31:41.295] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:41.295] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:14:0) after 1m0s
[18:32:41.296] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:14:0)
[18:32:41.296] Timer tick, asking Buildbucket for the build status
[18:32:41.350] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:41.350] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:15:0) after 1m0s
[18:33:41.369] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:15:0)
[18:33:41.369] Timer tick, asking Buildbucket for the build status
[18:33:41.407] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:41.407] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:16:0) after 1m0s
[18:34:41.479] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:16:0)
[18:34:41.479] Timer tick, asking Buildbucket for the build status
[18:34:41.660] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:41.660] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:17:0) after 1m0s
[18:35:41.720] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:17:0)
[18:35:41.720] Timer tick, asking Buildbucket for the build status
[18:35:41.787] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:41.787] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:18:0) after 1m0s
[18:36:41.777] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:18:0)
[18:36:41.777] Timer tick, asking Buildbucket for the build status
[18:36:41.820] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:41.820] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:19:0) after 1m0s
[18:37:41.838] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:19:0)
[18:37:41.838] Timer tick, asking Buildbucket for the build status
[18:37:41.971] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:41.972] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:20:0) after 1m0s
[18:38:41.991] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:20:0)
[18:38:41.991] Timer tick, asking Buildbucket for the build status
[18:38:42.176] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:42.176] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:21:0) after 1m0s
[18:39:42.212] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:21:0)
[18:39:42.212] Timer tick, asking Buildbucket for the build status
[18:39:42.252] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:42.252] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:22:0) after 1m0s
[18:40:42.271] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:22:0)
[18:40:42.271] Timer tick, asking Buildbucket for the build status
[18:40:42.392] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:42.392] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:23:0) after 1m0s
[18:41:42.384] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:23:0)
[18:41:42.384] Timer tick, asking Buildbucket for the build status
[18:41:42.513] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:42.513] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:24:0) after 1m0s
[18:42:42.521] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:24:0)
[18:42:42.521] Timer tick, asking Buildbucket for the build status
[18:42:42.592] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:42.592] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:25:0) after 1m0s
[18:43:42.617] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:25:0)
[18:43:42.617] Timer tick, asking Buildbucket for the build status
[18:43:42.715] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:42.715] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:26:0) after 1m0s
[18:44:42.750] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:26:0)
[18:44:42.750] Timer tick, asking Buildbucket for the build status
[18:44:42.790] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:42.790] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:27:0) after 1m0s
[18:45:42.811] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:27:0)
[18:45:42.811] Timer tick, asking Buildbucket for the build status
[18:45:42.931] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:42.931] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:28:0) after 1m0s
[18:46:42.928] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:28:0)
[18:46:42.928] Timer tick, asking Buildbucket for the build status
[18:46:43.012] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:43.012] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:29:0) after 1m0s
[18:47:43.015] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:29:0)
[18:47:43.015] Timer tick, asking Buildbucket for the build status
[18:47:43.052] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:43.052] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:30:0) after 1m0s
[18:48:43.073] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:30:0)
[18:48:43.074] Timer tick, asking Buildbucket for the build status
[18:48:43.145] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:43.145] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:31:0) after 1m0s
[18:49:43.153] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:31:0)
[18:49:43.153] Timer tick, asking Buildbucket for the build status
[18:49:43.218] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:43.218] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:32:0) after 1m0s
[18:50:43.236] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:32:0)
[18:50:43.236] Timer tick, asking Buildbucket for the build status
[18:50:43.348] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:43.348] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:33:0) after 1m0s
[18:51:43.319] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:33:0)
[18:51:43.319] Timer tick, asking Buildbucket for the build status
[18:51:43.362] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:43.362] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:34:0) after 1m0s
[18:52:43.390] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:34:0)
[18:52:43.390] Timer tick, asking Buildbucket for the build status
[18:52:43.541] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:43.541] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:35:0) after 1m0s
[18:53:43.559] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:35:0)
[18:53:43.559] Timer tick, asking Buildbucket for the build status
[18:53:43.603] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:43.603] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:36:0) after 1m0s
[18:54:43.612] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:36:0)
[18:54:43.612] Timer tick, asking Buildbucket for the build status
[18:54:43.715] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:43.715] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:37:0) after 1m0s
[18:55:43.740] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:37:0)
[18:55:43.740] Timer tick, asking Buildbucket for the build status
[18:55:43.791] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:43.791] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:38:0) after 1m0s
[18:56:43.816] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:38:0)
[18:56:43.816] Timer tick, asking Buildbucket for the build status
[18:56:43.891] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:43.891] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:39:0) after 1m0s
[18:57:43.913] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:39:0)
[18:57:43.913] Timer tick, asking Buildbucket for the build status
[18:57:43.971] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:43.971] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:40:0) after 1m0s
[18:58:44.005] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:40:0)
[18:58:44.005] Timer tick, asking Buildbucket for the build status
[18:58:44.208] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:44.208] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:41:0) after 1m0s
[18:59:44.222] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:41:0)
[18:59:44.222] Timer tick, asking Buildbucket for the build status
[18:59:44.261] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:44.261] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:42:0) after 1m0s
[19:00:44.265] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:42:0)
[19:00:44.265] Timer tick, asking Buildbucket for the build status
[19:00:44.316] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:44.316] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:43:0) after 1m0s
[19:01:44.341] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:43:0)
[19:01:44.341] Timer tick, asking Buildbucket for the build status
[19:01:44.396] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:44.396] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:44:0) after 1m0s
[19:02:44.422] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:44:0)
[19:02:44.422] Timer tick, asking Buildbucket for the build status
[19:02:44.555] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:44.555] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:45:0) after 1m0s
[19:03:44.573] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:45:0)
[19:03:44.573] Timer tick, asking Buildbucket for the build status
[19:03:44.700] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:44.700] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:46:0) after 1m0s
[19:04:44.680] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:46:0)
[19:04:44.680] Timer tick, asking Buildbucket for the build status
[19:04:44.850] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:44.850] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:47:0) after 1m0s
[19:05:44.872] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:47:0)
[19:05:44.872] Timer tick, asking Buildbucket for the build status
[19:05:44.912] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:44.912] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:48:0) after 1m0s
[19:06:44.935] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:48:0)
[19:06:44.935] Timer tick, asking Buildbucket for the build status
[19:06:44.976] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:44.976] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:49:0) after 1m0s
[19:07:44.999] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:49:0)
[19:07:44.999] Timer tick, asking Buildbucket for the build status
[19:07:45.148] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:45.148] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:50:0) after 1m0s
[19:08:45.174] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:50:0)
[19:08:45.174] Timer tick, asking Buildbucket for the build status
[19:08:45.304] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:45.304] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:51:0) after 1m0s
[19:09:45.325] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:51:0)
[19:09:45.325] Timer tick, asking Buildbucket for the build status
[19:09:45.389] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:45.389] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:52:0) after 1m0s
[19:10:45.426] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:52:0)
[19:10:45.426] Timer tick, asking Buildbucket for the build status
[19:10:45.477] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:45.477] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:53:0) after 1m0s
[19:11:45.497] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:53:0)
[19:11:45.497] Timer tick, asking Buildbucket for the build status
[19:11:45.555] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:45.555] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:54:0) after 1m0s
[19:12:45.573] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:54:0)
[19:12:45.573] Timer tick, asking Buildbucket for the build status
[19:12:45.631] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:45.631] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:55:0) after 1m0s
[19:13:45.628] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:55:0)
[19:13:45.628] Timer tick, asking Buildbucket for the build status
[19:13:45.751] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:45.751] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:56:0) after 1m0s
[19:14:45.773] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:56:0)
[19:14:45.773] Timer tick, asking Buildbucket for the build status
[19:14:45.818] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:45.818] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:57:0) after 1m0s
[19:15:45.855] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:57:0)
[19:15:45.855] Timer tick, asking Buildbucket for the build status
[19:15:45.987] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:45.987] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:58:0) after 1m0s
[19:16:45.984] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:58:0)
[19:16:45.984] Timer tick, asking Buildbucket for the build status
[19:16:46.162] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:46.162] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:59:0) after 1m0s
[19:17:46.188] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:59:0)
[19:17:46.188] Timer tick, asking Buildbucket for the build status
[19:17:46.276] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:46.276] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:60:0) after 1m0s
[19:18:46.320] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:60:0)
[19:18:46.320] Timer tick, asking Buildbucket for the build status
[19:18:46.371] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:46.371] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:61:0) after 1m0s
[19:19:46.394] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:61:0)
[19:19:46.395] Timer tick, asking Buildbucket for the build status
[19:19:46.429] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:46.430] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:62:0) after 1m0s
[19:20:46.450] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:62:0)
[19:20:46.450] Timer tick, asking Buildbucket for the build status
[19:20:46.509] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:46.509] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:63:0) after 1m0s
[19:21:46.566] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:63:0)
[19:21:46.566] Timer tick, asking Buildbucket for the build status
[19:21:46.678] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:46.678] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:64:0) after 1m0s
[19:22:46.701] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:64:0)
[19:22:46.701] Timer tick, asking Buildbucket for the build status
[19:22:46.750] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:46.750] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:65:0) after 1m0s
[19:23:46.769] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:65:0)
[19:23:46.769] Timer tick, asking Buildbucket for the build status
[19:23:46.807] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:46.807] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:66:0) after 1m0s
[19:24:46.839] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:66:0)
[19:24:46.839] Timer tick, asking Buildbucket for the build status
[19:24:46.874] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:46.874] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:67:0) after 1m0s
[19:25:46.897] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:67:0)
[19:25:46.897] Timer tick, asking Buildbucket for the build status
[19:25:47.049] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:47.049] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:68:0) after 1m0s
[19:26:47.103] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:68:0)
[19:26:47.103] Timer tick, asking Buildbucket for the build status
[19:26:47.507] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:47.507] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:69:0) after 1m0s
[19:27:47.529] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:69:0)
[19:27:47.529] Timer tick, asking Buildbucket for the build status
[19:27:47.587] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:47.587] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:70:0) after 1m0s
[19:28:47.574] Handling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:70:0)
[19:28:47.574] Timer tick, asking Buildbucket for the build status
[19:28:47.764] Build 8916722655053824960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:47.764] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082152716957846784:71:0) after 1m0s
[19:29:03.456] Received PubSub notification, asking Buildbucket for the build status
[19:29:03.502] Build 8916722655053824960: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:29:03.502] Buildbucket build:
{
"build": {
"bucket": "luci.celab.ci",
"canary_preference": "AUTO",
"completed_ts": "1554751742786073",
"created_by": "project:celab",
"created_ts": "1554747639565514",
"id": "8916722655053824960",
"parameters_json": "{\"builder_name\": \"Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/enterprise/cel.git\", \"revision\": \"35ff6ff537649348e8a0786b215a9ab1fd221650\"}}",
"project": "celab",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/mnt/data/b/s/w/ir/cache\", \"temp_dir\": \"/mnt/data/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"vm78-m1\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux\", \"got_revision\": \"35ff6ff537649348e8a0786b215a9ab1fd221650\", \"path_config\": \"generic\", \"recipe\": \"celab\", \"repository\": \"https://chromium.googlesource.com/enterprise/cel.git\", \"revision\": \"35ff6ff537649348e8a0786b215a9ab1fd221650\", \"target_cpu\": \"x64\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"git\", \"goma_v2\", \"infra_gclient_with_go\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-E5-2670\"], \"gce\": [\"0\"], \"gpu\": [\"none\"], \"id\": [\"vm78-m1\"], \"inside_docker\": [\"0\"], \"kvm\": [\"0\"], \"locale\": [\"en_US.ISO8859-1\"], \"machine_type\": [\"n1-highcpu-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.flex.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4243-f38882f\"], \"zone\": [\"us\", \"us-golo\", \"us-golo-1\"]}}}",
"service_account": "celab-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1554747698672596",
"status": "COMPLETED",
"status_changed_ts": "1554751743205823",
"tags": [
"builder:Linux",
"buildset:commit/git/35ff6ff537649348e8a0786b215a9ab1fd221650",
"buildset:commit/gitiles/chromium.googlesource.com/enterprise/cel/+/35ff6ff537649348e8a0786b215a9ab1fd221650",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082152716957846784",
"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/8916722655053824960/+/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:4416ffd20f28b910",
"user_agent:luci-scheduler"
],
"updated_ts": "1554751743209950",
"url": "https://ci.chromium.org/b/8916722655053824960",
"utcnow_ts": "1554751743493646"
}
}
[19:29:03.502] Invocation finished in 1h8m25.136553929s with status SUCCEEDED