[18:52:46.305] New invocation is queued and will start shortly
[18:52:46.306] Triggered by user:mbinette@google.com
[18:52:47.390] Starting the invocation (attempt 1)
[18:52:47.453] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:52:47.453] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:52:47.454] Buildbucket request:
{
"bucket": "luci.celab.ci",
"client_operation_id": "9082784874148796640",
"parameters_json": "{\"builder_name\":\"Linux\",\"properties\":{}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux",
"scheduler_invocation_id:9082784874148796640",
"scheduler_job_id:celab/Linux",
"user_agent:luci-scheduler"
]
}
[18:52:48.011] Buildbucket response:
{
"build": {
"bucket": "luci.celab.ci",
"canary_preference": "AUTO",
"created_by": "project:celab",
"created_ts": "1554144767613647",
"id": "8917354812113766848",
"parameters_json": "{\"builder_name\": \"Linux\", \"properties\": {}}",
"project": "celab",
"result_details_json": "{\"properties\": {}}",
"service_account": "celab-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554144767848804",
"tags": [
"builder:Linux",
"scheduler_invocation_id:9082784874148796640",
"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/8917354812113766848/+/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": "1554144767848961",
"url": "https://ci.chromium.org/b/8917354812113766848",
"utcnow_ts": "1554144768002883"
}
}
[18:52:48.011] Task URL: https://ci.chromium.org/b/8917354812113766848
[18:52:48.011] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:2:0) after 1m0s
[18:53:48.019] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:2:0)
[18:53:48.019] Timer tick, asking Buildbucket for the build status
[18:53:48.182] Build 8917354812113766848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:53:48.182] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:3:0) after 1m0s
[18:54:16.973] Received PubSub notification, asking Buildbucket for the build status
[18:54:17.029] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:48.199] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:3:0)
[18:54:48.199] Timer tick, asking Buildbucket for the build status
[18:54:48.302] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:48.302] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:5:0) after 1m0s
[18:55:48.316] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:5:0)
[18:55:48.316] Timer tick, asking Buildbucket for the build status
[18:55:48.404] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:48.404] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:6:0) after 1m0s
[18:56:48.399] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:6:0)
[18:56:48.399] Timer tick, asking Buildbucket for the build status
[18:56:48.504] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:48.504] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:7:0) after 1m0s
[18:57:48.523] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:7:0)
[18:57:48.523] Timer tick, asking Buildbucket for the build status
[18:57:48.723] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:48.723] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:8:0) after 1m0s
[18:58:48.721] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:8:0)
[18:58:48.721] Timer tick, asking Buildbucket for the build status
[18:58:48.828] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:48.828] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:9:0) after 1m0s
[18:59:48.851] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:9:0)
[18:59:48.851] Timer tick, asking Buildbucket for the build status
[18:59:49.067] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:49.067] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:10:0) after 1m0s
[19:00:49.075] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:10:0)
[19:00:49.075] Timer tick, asking Buildbucket for the build status
[19:00:49.129] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:49.129] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:11:0) after 1m0s
[19:01:49.149] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:11:0)
[19:01:49.149] Timer tick, asking Buildbucket for the build status
[19:01:49.231] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:49.231] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:12:0) after 1m0s
[19:02:49.262] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:12:0)
[19:02:49.262] Timer tick, asking Buildbucket for the build status
[19:02:49.436] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:49.436] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:13:0) after 1m0s
[19:03:49.421] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:13:0)
[19:03:49.421] Timer tick, asking Buildbucket for the build status
[19:03:50.293] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:50.293] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:14:0) after 1m0s
[19:04:50.305] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:14:0)
[19:04:50.305] Timer tick, asking Buildbucket for the build status
[19:04:50.478] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:50.478] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:15:0) after 1m0s
[19:05:50.481] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:15:0)
[19:05:50.481] Timer tick, asking Buildbucket for the build status
[19:05:50.542] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:50.542] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:16:0) after 1m0s
[19:06:50.520] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:16:0)
[19:06:50.520] Timer tick, asking Buildbucket for the build status
[19:06:50.561] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:50.561] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:17:0) after 1m0s
[19:07:50.557] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:17:0)
[19:07:50.557] Timer tick, asking Buildbucket for the build status
[19:07:50.609] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:50.609] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:18:0) after 1m0s
[19:08:50.585] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:18:0)
[19:08:50.585] Timer tick, asking Buildbucket for the build status
[19:08:50.840] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:50.840] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:19:0) after 1m0s
[19:09:50.839] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:19:0)
[19:09:50.839] Timer tick, asking Buildbucket for the build status
[19:09:50.895] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:50.895] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:20:0) after 1m0s
[19:10:50.969] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:20:0)
[19:10:50.969] Timer tick, asking Buildbucket for the build status
[19:10:51.015] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:51.015] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:21:0) after 1m0s
[19:11:51.002] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:21:0)
[19:11:51.002] Timer tick, asking Buildbucket for the build status
[19:11:51.195] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:51.195] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:22:0) after 1m0s
[19:12:51.215] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:22:0)
[19:12:51.215] Timer tick, asking Buildbucket for the build status
[19:12:51.279] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:51.279] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:23:0) after 1m0s
[19:13:51.303] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:23:0)
[19:13:51.303] Timer tick, asking Buildbucket for the build status
[19:13:51.339] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:51.339] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:24:0) after 1m0s
[19:14:51.358] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:24:0)
[19:14:51.358] Timer tick, asking Buildbucket for the build status
[19:14:51.408] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:51.408] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:25:0) after 1m0s
[19:15:51.426] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:25:0)
[19:15:51.426] Timer tick, asking Buildbucket for the build status
[19:15:51.590] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:51.590] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:26:0) after 1m0s
[19:16:51.575] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:26:0)
[19:16:51.575] Timer tick, asking Buildbucket for the build status
[19:16:51.619] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:51.619] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:27:0) after 1m0s
[19:17:51.639] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:27:0)
[19:17:51.639] Timer tick, asking Buildbucket for the build status
[19:17:51.708] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:51.708] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:28:0) after 1m0s
[19:18:51.727] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:28:0)
[19:18:51.727] Timer tick, asking Buildbucket for the build status
[19:18:51.765] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:51.765] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:29:0) after 1m0s
[19:19:51.785] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:29:0)
[19:19:51.785] Timer tick, asking Buildbucket for the build status
[19:19:51.888] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:51.888] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:30:0) after 1m0s
[19:20:51.919] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:30:0)
[19:20:51.919] Timer tick, asking Buildbucket for the build status
[19:20:52.229] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:52.229] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:31:0) after 1m0s
[19:21:52.277] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:31:0)
[19:21:52.277] Timer tick, asking Buildbucket for the build status
[19:21:52.342] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:52.342] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:32:0) after 1m0s
[19:22:52.374] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:32:0)
[19:22:52.374] Timer tick, asking Buildbucket for the build status
[19:22:52.435] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:52.435] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:33:0) after 1m0s
[19:23:52.446] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:33:0)
[19:23:52.446] Timer tick, asking Buildbucket for the build status
[19:23:52.525] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:52.525] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:34:0) after 1m0s
[19:24:52.551] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:34:0)
[19:24:52.551] Timer tick, asking Buildbucket for the build status
[19:24:52.590] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:52.590] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:35:0) after 1m0s
[19:25:52.607] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:35:0)
[19:25:52.607] Timer tick, asking Buildbucket for the build status
[19:25:52.807] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:52.807] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:36:0) after 1m0s
[19:26:52.825] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:36:0)
[19:26:52.825] Timer tick, asking Buildbucket for the build status
[19:26:52.884] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:52.884] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:37:0) after 1m0s
[19:27:52.902] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:37:0)
[19:27:52.902] Timer tick, asking Buildbucket for the build status
[19:27:53.085] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:53.085] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:38:0) after 1m0s
[19:28:53.083] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:38:0)
[19:28:53.083] Timer tick, asking Buildbucket for the build status
[19:28:53.350] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:53.350] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:39:0) after 1m0s
[19:29:53.370] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:39:0)
[19:29:53.370] Timer tick, asking Buildbucket for the build status
[19:29:53.426] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:53.426] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:40:0) after 1m0s
[19:30:53.427] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:40:0)
[19:30:53.428] Timer tick, asking Buildbucket for the build status
[19:30:53.481] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:53.481] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:41:0) after 1m0s
[19:31:53.508] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:41:0)
[19:31:53.508] Timer tick, asking Buildbucket for the build status
[19:31:53.766] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:53.766] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:42:0) after 1m0s
[19:32:53.786] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:42:0)
[19:32:53.786] Timer tick, asking Buildbucket for the build status
[19:32:53.867] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:53.867] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:43:0) after 1m0s
[19:33:53.896] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:43:0)
[19:33:53.896] Timer tick, asking Buildbucket for the build status
[19:33:53.945] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:53.945] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:44:0) after 1m0s
[19:34:53.961] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:44:0)
[19:34:53.961] Timer tick, asking Buildbucket for the build status
[19:34:54.081] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:54.081] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:45:0) after 1m0s
[19:35:54.177] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:45:0)
[19:35:54.177] Timer tick, asking Buildbucket for the build status
[19:35:54.273] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:54.273] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:46:0) after 1m0s
[19:36:54.293] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:46:0)
[19:36:54.293] Timer tick, asking Buildbucket for the build status
[19:36:54.376] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:54.376] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:47:0) after 1m0s
[19:37:54.394] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:47:0)
[19:37:54.394] Timer tick, asking Buildbucket for the build status
[19:37:54.448] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:54.448] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:48:0) after 1m0s
[19:38:54.426] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:48:0)
[19:38:54.426] Timer tick, asking Buildbucket for the build status
[19:38:54.481] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:54.481] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:49:0) after 1m0s
[19:39:54.523] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:49:0)
[19:39:54.523] Timer tick, asking Buildbucket for the build status
[19:39:54.591] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:54.591] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:50:0) after 1m0s
[19:40:54.613] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:50:0)
[19:40:54.613] Timer tick, asking Buildbucket for the build status
[19:40:54.723] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:54.723] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:51:0) after 1m0s
[19:41:54.719] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:51:0)
[19:41:54.719] Timer tick, asking Buildbucket for the build status
[19:41:54.784] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:54.784] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:52:0) after 1m0s
[19:42:54.811] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:52:0)
[19:42:54.811] Timer tick, asking Buildbucket for the build status
[19:42:54.884] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:54.884] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:53:0) after 1m0s
[19:43:54.866] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:53:0)
[19:43:54.866] Timer tick, asking Buildbucket for the build status
[19:43:55.073] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:55.073] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:54:0) after 1m0s
[19:44:55.120] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:54:0)
[19:44:55.120] Timer tick, asking Buildbucket for the build status
[19:44:55.306] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:55.306] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:55:0) after 1m0s
[19:45:55.332] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:55:0)
[19:45:55.332] Timer tick, asking Buildbucket for the build status
[19:45:55.479] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:55.479] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:56:0) after 1m0s
[19:46:55.497] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:56:0)
[19:46:55.497] Timer tick, asking Buildbucket for the build status
[19:46:55.549] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:55.549] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:57:0) after 1m0s
[19:47:55.570] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:57:0)
[19:47:55.570] Timer tick, asking Buildbucket for the build status
[19:47:55.755] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:55.755] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:58:0) after 1m0s
[19:48:55.778] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:58:0)
[19:48:55.778] Timer tick, asking Buildbucket for the build status
[19:48:55.822] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:55.822] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:59:0) after 1m0s
[19:49:55.843] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:59:0)
[19:49:55.843] Timer tick, asking Buildbucket for the build status
[19:49:55.985] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:55.985] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:60:0) after 1m0s
[19:50:56.008] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:60:0)
[19:50:56.008] Timer tick, asking Buildbucket for the build status
[19:50:56.097] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:56.097] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:61:0) after 1m0s
[19:51:56.083] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:61:0)
[19:51:56.083] Timer tick, asking Buildbucket for the build status
[19:51:56.186] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:56.186] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:62:0) after 1m0s
[19:52:56.195] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:62:0)
[19:52:56.195] Timer tick, asking Buildbucket for the build status
[19:52:56.234] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:56.234] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:63:0) after 1m0s
[19:53:56.269] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:63:0)
[19:53:56.269] Timer tick, asking Buildbucket for the build status
[19:53:56.409] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:56.409] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:64:0) after 1m0s
[19:54:56.430] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:64:0)
[19:54:56.430] Timer tick, asking Buildbucket for the build status
[19:54:56.529] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:56.529] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:65:0) after 1m0s
[19:55:56.564] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:65:0)
[19:55:56.564] Timer tick, asking Buildbucket for the build status
[19:55:56.620] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:56.620] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:66:0) after 1m0s
[19:56:56.643] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:66:0)
[19:56:56.643] Timer tick, asking Buildbucket for the build status
[19:56:56.812] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:56.812] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:67:0) after 1m0s
[19:57:56.836] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:67:0)
[19:57:56.836] Timer tick, asking Buildbucket for the build status
[19:57:56.886] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:56.886] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:68:0) after 1m0s
[19:58:56.918] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:68:0)
[19:58:56.918] Timer tick, asking Buildbucket for the build status
[19:58:57.018] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:57.018] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:69:0) after 1m0s
[19:59:57.021] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:69:0)
[19:59:57.021] Timer tick, asking Buildbucket for the build status
[19:59:57.057] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:57.057] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:70:0) after 1m0s
[20:00:57.078] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:70:0)
[20:00:57.078] Timer tick, asking Buildbucket for the build status
[20:00:57.140] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:57.140] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:71:0) after 1m0s
[20:01:57.144] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:71:0)
[20:01:57.144] Timer tick, asking Buildbucket for the build status
[20:01:57.353] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:57.353] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:72:0) after 1m0s
[20:02:57.374] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:72:0)
[20:02:57.374] Timer tick, asking Buildbucket for the build status
[20:02:57.624] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:57.624] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:73:0) after 1m0s
[20:03:57.647] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:73:0)
[20:03:57.647] Timer tick, asking Buildbucket for the build status
[20:03:57.685] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:57.685] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:74:0) after 1m0s
[20:04:57.705] Handling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:74:0)
[20:04:57.705] Timer tick, asking Buildbucket for the build status
[20:04:57.825] Build 8917354812113766848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:57.825] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9082784874148796640:75:0) after 1m0s
[20:05:28.079] Received PubSub notification, asking Buildbucket for the build status
[20:05:28.216] Build 8917354812113766848: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[20:05:28.216] Buildbucket build:
{
"build": {
"bucket": "luci.celab.ci",
"canary_preference": "AUTO",
"completed_ts": "1554149127604944",
"created_by": "project:celab",
"created_ts": "1554144767613647",
"failure_reason": "BUILD_FAILURE",
"id": "8917354812113766848",
"parameters_json": "{\"builder_name\": \"Linux\", \"properties\": {}}",
"project": "celab",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('run all tests') failed with return_code 1\"}, \"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\", \"buildername\": \"Linux\", \"got_revision\": \"59a842aa4f29a1ab97e690ddb4ccec2cb808965b\", \"path_config\": \"generic\", \"recipe\": \"celab\", \"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\": [\"4229-6d56481\"], \"zone\": [\"us\", \"us-golo\", \"us-golo-1\"]}}, \"ui\": {\"info\": \"Step('run all tests') failed with return_code 1\"}}",
"service_account": "celab-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1554144856150639",
"status": "COMPLETED",
"status_changed_ts": "1554144856682092",
"tags": [
"builder:Linux",
"scheduler_invocation_id:9082784874148796640",
"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/8917354812113766848/+/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:43f310b9d8148010",
"user_agent:luci-scheduler"
],
"updated_ts": "1554149127789689",
"url": "https://ci.chromium.org/b/8917354812113766848",
"utcnow_ts": "1554149128206117"
}
}
[20:05:28.216] Invocation finished in 1h12m41.926441462s with status FAILED