[00:44:02.612] New invocation is queued and will start shortly
[00:44:03.686] Starting the invocation (attempt 1)
[00:44:03.725] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:44:03.725] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:44:03.725] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9049604284336988272",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"344e034cc5da9425d3623ea0e9c954fc26023e86\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9049604284336988272",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/344e034cc5da9425d3623ea0e9c954fc26023e86",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/344e034cc5da9425d3623ea0e9c954fc26023e86",
"gitiles_ref:refs/heads/master"
]
}
[00:44:04.707] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1585788243849335",
"id": "8884174222375578112",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"344e034cc5da9425d3623ea0e9c954fc26023e86\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1585788244453300",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/170509",
"builder:Linux Builder (dbg)",
"buildset:commit/git/344e034cc5da9425d3623ea0e9c954fc26023e86",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/344e034cc5da9425d3623ea0e9c954fc26023e86",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9049604284336988272",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8884174222375578112/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1585788244453751",
"url": "https://ci.chromium.org/b/8884174222375578112",
"utcnow_ts": "1585788244692725"
}
}
[00:44:04.707] Task URL: https://ci.chromium.org/b/8884174222375578112
[00:44:04.707] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:2:0) after 1m0s
[00:45:04.769] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:2:0)
[00:45:04.769] Timer tick, asking Buildbucket for the build status
[00:45:06.048] Build 8884174222375578112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:06.048] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:3:0) after 1m0s
[00:46:06.196] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:3:0)
[00:46:06.197] Timer tick, asking Buildbucket for the build status
[00:46:06.450] Build 8884174222375578112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:06.450] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:4:0) after 1m0s
[00:47:06.475] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:4:0)
[00:47:06.475] Timer tick, asking Buildbucket for the build status
[00:47:06.770] Build 8884174222375578112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:06.770] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:5:0) after 1m0s
[00:48:06.826] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:5:0)
[00:48:06.826] Timer tick, asking Buildbucket for the build status
[00:48:07.761] Build 8884174222375578112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:07.761] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:6:0) after 1m0s
[00:49:03.538] Received PubSub notification, asking Buildbucket for the build status
[00:49:03.697] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:07.815] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:6:0)
[00:49:07.815] Timer tick, asking Buildbucket for the build status
[00:49:07.946] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:07.946] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:8:0) after 1m0s
[00:50:08.060] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:8:0)
[00:50:08.060] Timer tick, asking Buildbucket for the build status
[00:50:08.250] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:08.250] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:9:0) after 1m0s
[00:51:08.367] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:9:0)
[00:51:08.367] Timer tick, asking Buildbucket for the build status
[00:51:08.488] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:08.488] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:10:0) after 1m0s
[00:52:08.697] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:10:0)
[00:52:08.698] Timer tick, asking Buildbucket for the build status
[00:52:08.846] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:08.846] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:11:0) after 1m0s
[00:53:08.973] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:11:0)
[00:53:08.973] Timer tick, asking Buildbucket for the build status
[00:53:09.473] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:09.473] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:12:0) after 1m0s
[00:54:09.497] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:12:0)
[00:54:09.497] Timer tick, asking Buildbucket for the build status
[00:54:09.738] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:09.738] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:13:0) after 1m0s
[00:55:09.793] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:13:0)
[00:55:09.793] Timer tick, asking Buildbucket for the build status
[00:55:10.273] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:10.273] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:14:0) after 1m0s
[00:56:10.372] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:14:0)
[00:56:10.372] Timer tick, asking Buildbucket for the build status
[00:56:10.653] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:10.653] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:15:0) after 1m0s
[00:57:10.735] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:15:0)
[00:57:10.735] Timer tick, asking Buildbucket for the build status
[00:57:10.961] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:10.961] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:16:0) after 1m0s
[00:58:10.981] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:16:0)
[00:58:10.981] Timer tick, asking Buildbucket for the build status
[00:58:11.290] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:11.290] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:17:0) after 1m0s
[00:59:11.374] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:17:0)
[00:59:11.374] Timer tick, asking Buildbucket for the build status
[00:59:11.563] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:11.563] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:18:0) after 1m0s
[01:00:11.661] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:18:0)
[01:00:11.661] Timer tick, asking Buildbucket for the build status
[01:00:11.817] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:11.817] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:19:0) after 1m0s
[01:01:11.903] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:19:0)
[01:01:11.903] Timer tick, asking Buildbucket for the build status
[01:01:12.079] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:12.079] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:20:0) after 1m0s
[01:02:12.135] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:20:0)
[01:02:12.135] Timer tick, asking Buildbucket for the build status
[01:02:12.376] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:12.376] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:21:0) after 1m0s
[01:03:12.399] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:21:0)
[01:03:12.399] Timer tick, asking Buildbucket for the build status
[01:03:12.569] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:12.569] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:22:0) after 1m0s
[01:04:12.628] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:22:0)
[01:04:12.628] Timer tick, asking Buildbucket for the build status
[01:04:13.603] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:13.603] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:23:0) after 1m0s
[01:05:13.632] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:23:0)
[01:05:13.632] Timer tick, asking Buildbucket for the build status
[01:05:14.005] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:14.005] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:24:0) after 1m0s
[01:06:14.033] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:24:0)
[01:06:14.033] Timer tick, asking Buildbucket for the build status
[01:06:14.308] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:14.308] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:25:0) after 1m0s
[01:07:14.328] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:25:0)
[01:07:14.328] Timer tick, asking Buildbucket for the build status
[01:07:14.754] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:14.754] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:26:0) after 1m0s
[01:08:14.813] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:26:0)
[01:08:14.813] Timer tick, asking Buildbucket for the build status
[01:08:15.527] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:15.527] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:27:0) after 1m0s
[01:09:15.565] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:27:0)
[01:09:15.566] Timer tick, asking Buildbucket for the build status
[01:09:15.726] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:15.726] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:28:0) after 1m0s
[01:10:15.756] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:28:0)
[01:10:15.756] Timer tick, asking Buildbucket for the build status
[01:10:16.116] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:16.116] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:29:0) after 1m0s
[01:11:16.161] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:29:0)
[01:11:16.161] Timer tick, asking Buildbucket for the build status
[01:11:16.283] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:16.283] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:30:0) after 1m0s
[01:12:16.387] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:30:0)
[01:12:16.387] Timer tick, asking Buildbucket for the build status
[01:12:16.597] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:16.597] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:31:0) after 1m0s
[01:13:16.596] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:31:0)
[01:13:16.596] Timer tick, asking Buildbucket for the build status
[01:13:16.774] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:16.774] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:32:0) after 1m0s
[01:14:16.799] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:32:0)
[01:14:16.799] Timer tick, asking Buildbucket for the build status
[01:14:16.939] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:16.939] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:33:0) after 1m0s
[01:15:16.991] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:33:0)
[01:15:16.991] Timer tick, asking Buildbucket for the build status
[01:15:17.305] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:17.305] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:34:0) after 1m0s
[01:16:17.401] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:34:0)
[01:16:17.402] Timer tick, asking Buildbucket for the build status
[01:16:17.569] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:17.569] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:35:0) after 1m0s
[01:17:17.589] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:35:0)
[01:17:17.589] Timer tick, asking Buildbucket for the build status
[01:17:17.710] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:17.710] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:36:0) after 1m0s
[01:18:17.831] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:36:0)
[01:18:17.831] Timer tick, asking Buildbucket for the build status
[01:18:17.934] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:17.934] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:37:0) after 1m0s
[01:19:17.955] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:37:0)
[01:19:17.955] Timer tick, asking Buildbucket for the build status
[01:19:18.297] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:18.297] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:38:0) after 1m0s
[01:20:18.358] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:38:0)
[01:20:18.358] Timer tick, asking Buildbucket for the build status
[01:20:18.528] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:18.528] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:39:0) after 1m0s
[01:21:18.596] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:39:0)
[01:21:18.596] Timer tick, asking Buildbucket for the build status
[01:21:18.747] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:18.747] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:40:0) after 1m0s
[01:22:18.971] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:40:0)
[01:22:18.971] Timer tick, asking Buildbucket for the build status
[01:22:19.141] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:19.141] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:41:0) after 1m0s
[01:23:19.205] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:41:0)
[01:23:19.205] Timer tick, asking Buildbucket for the build status
[01:23:19.478] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:19.478] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:42:0) after 1m0s
[01:24:19.565] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:42:0)
[01:24:19.565] Timer tick, asking Buildbucket for the build status
[01:24:19.815] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:19.815] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:43:0) after 1m0s
[01:25:19.908] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:43:0)
[01:25:19.908] Timer tick, asking Buildbucket for the build status
[01:25:20.038] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:20.038] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:44:0) after 1m0s
[01:26:20.043] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:44:0)
[01:26:20.043] Timer tick, asking Buildbucket for the build status
[01:26:20.286] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:20.286] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:45:0) after 1m0s
[01:27:20.455] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:45:0)
[01:27:20.455] Timer tick, asking Buildbucket for the build status
[01:27:20.624] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:20.624] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:46:0) after 1m0s
[01:28:20.694] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:46:0)
[01:28:20.694] Timer tick, asking Buildbucket for the build status
[01:28:21.094] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:21.094] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:47:0) after 1m0s
[01:29:21.241] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:47:0)
[01:29:21.241] Timer tick, asking Buildbucket for the build status
[01:29:21.613] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:21.613] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:48:0) after 1m0s
[01:30:21.638] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:48:0)
[01:30:21.638] Timer tick, asking Buildbucket for the build status
[01:30:21.920] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:21.920] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:49:0) after 1m0s
[01:31:21.993] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:49:0)
[01:31:21.993] Timer tick, asking Buildbucket for the build status
[01:31:23.593] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:23.593] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:50:0) after 1m0s
[01:32:23.669] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:50:0)
[01:32:23.669] Timer tick, asking Buildbucket for the build status
[01:32:23.851] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:23.851] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:51:0) after 1m0s
[01:33:23.887] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:51:0)
[01:33:23.887] Timer tick, asking Buildbucket for the build status
[01:33:24.075] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:24.075] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:52:0) after 1m0s
[01:34:24.131] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:52:0)
[01:34:24.131] Timer tick, asking Buildbucket for the build status
[01:34:24.405] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:24.405] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:53:0) after 1m0s
[01:35:24.494] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:53:0)
[01:35:24.494] Timer tick, asking Buildbucket for the build status
[01:35:24.727] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:24.727] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:54:0) after 1m0s
[01:36:24.776] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:54:0)
[01:36:24.776] Timer tick, asking Buildbucket for the build status
[01:36:25.045] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:25.045] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:55:0) after 1m0s
[01:37:25.066] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:55:0)
[01:37:25.066] Timer tick, asking Buildbucket for the build status
[01:37:25.306] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:25.306] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:56:0) after 1m0s
[01:38:25.356] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:56:0)
[01:38:25.356] Timer tick, asking Buildbucket for the build status
[01:38:25.815] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:25.816] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:57:0) after 1m0s
[01:39:25.975] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:57:0)
[01:39:25.975] Timer tick, asking Buildbucket for the build status
[01:39:26.211] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:26.211] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:58:0) after 1m0s
[01:40:26.294] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:58:0)
[01:40:26.294] Timer tick, asking Buildbucket for the build status
[01:40:26.394] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:26.394] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:59:0) after 1m0s
[01:41:26.460] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:59:0)
[01:41:26.460] Timer tick, asking Buildbucket for the build status
[01:41:26.599] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:26.599] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:60:0) after 1m0s
[01:42:26.698] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:60:0)
[01:42:26.698] Timer tick, asking Buildbucket for the build status
[01:42:26.963] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:26.963] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:61:0) after 1m0s
[01:43:27.034] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:61:0)
[01:43:27.034] Timer tick, asking Buildbucket for the build status
[01:43:27.266] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:27.266] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:62:0) after 1m0s
[01:44:27.324] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:62:0)
[01:44:27.324] Timer tick, asking Buildbucket for the build status
[01:44:27.546] Build 8884174222375578112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:27.546] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049604284336988272:63:0) after 1m0s
[01:44:33.723] Received PubSub notification, asking Buildbucket for the build status
[01:44:33.837] Build 8884174222375578112: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:44:33.837] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1585791872770288",
"created_by": "project:chromium",
"created_ts": "1585788243849335",
"id": "8884174222375578112",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"344e034cc5da9425d3623ea0e9c954fc26023e86\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/chromium_tests\": {\"bucketed_triggers\": true}, \"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-81-40kp\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 170509, \"got_angle_revision\": \"d30da07270b6a1d0d68ce45d8728dd838fa9ae98\", \"got_dawn_revision\": \"373a3ff26ec7e6fa0f54595c34e35e3fab5af577\", \"got_nacl_revision\": \"ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\", \"got_revision\": \"344e034cc5da9425d3623ea0e9c954fc26023e86\", \"got_revision_cp\": \"refs/heads/master@{#755636}\", \"got_swarming_client_revision\": \"cc958279ffd6853e0a1b227a7e957ca334fe56af\", \"got_swiftshader_revision\": \"cb58662302c8e73845116fc5f14acaa08c037cb2\", \"got_v8_revision\": \"16df139bf0b555ee0d5264eb9a9c7c0c71cf4eda\", \"got_v8_revision_cp\": \"refs/heads/8.3.125@{#1}\", \"got_webrtc_revision\": \"486232025b0bbb67c2f04e9bb4261206f68eefea\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30961}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"344e034cc5da9425d3623ea0e9c954fc26023e86\", \"swarm_hashes_refs/heads/master(at){#755636}_without_patch\": {\"accessibility_unittests\": \"126aaba7a1b8acb117d3a2aeb42024e336914738\", \"angle_unittests\": \"8b1a53e5c8ffd29c6c4acfbbffe4f5b40b680ccd\", \"app_shell_unittests\": \"7b33a29c469a59c99a3ac34fb4d37f3a84094f48\", \"aura_unittests\": \"4e629f0f38d942bd2974fbcd41fe864f0f42fe74\", \"base_unittests\": \"34d62dc95a7e55d45622d46030898534fbc8d16a\", \"base_util_unittests\": \"f3c30c045aa94eb0393764767a48f823a00a1e37\", \"blink_common_unittests\": \"635de669b3f209106aab1aef7ae6ca8b0283214c\", \"blink_fuzzer_unittests\": \"66cb420767adff040e32deaf8eabc34ca633ecb8\", \"blink_heap_unittests\": \"eacb538d062401cc7519396f44ff22533c7b05ab\", \"blink_platform_unittests\": \"7524b4186c81bb7dc41d50aa66f4f25e46843e7f\", \"blink_python_tests\": \"029265b29560764ad46f331ff54874dd92aa73cb\", \"blink_unittests\": \"035a65cf024602c306fd3666274a495f707c0fca\", \"blink_web_tests\": \"3092e74fbdb286f16f8279c383d1a1268f3fde21\", \"boringssl_crypto_tests\": \"98cb90dcfa2ce01c9bb812c80d2b0949a4d1103a\", \"boringssl_ssl_tests\": \"433c71a9d21925607764cee7649d9c91e5126bb1\", \"browser_tests\": \"3986a7ecb4f7b4f7bc126200a0c91ffe4c3403c0\", \"cacheinvalidation_unittests\": \"f2c8eb1a73cc36edc5c6930ddf3283b88d713cdc\", \"capture_unittests\": \"11945161e39d6f0c0d06bd8a914a6b254e2db135\", \"cast_unittests\": \"1e56a0e2a91925022921b87164bc9f3d58da0a23\", \"cc_unittests\": \"11f0aed000f92dd405e5c2673c49c7e73579cec7\", \"chrome_app_unittests\": \"5abfd2bb34464444656055acdcb57952fb870836\", \"chromedriver_unittests\": \"5a958bc399c36d73be2a73c043e4a9612dd6b96b\", \"components_browsertests\": \"81bdd30bc6c4f1d74f26d5598ce9b4c99dd76e2b\", \"components_unittests\": \"d8f9e40b216681e4d053800420ea67de5064ea37\", \"compositor_unittests\": \"6d03f7723c6aaced0ef71d0dd7a5853cda2942e8\", \"content_browsertests\": \"1474b8186461b6ad027230c7f508b202df173831\", \"content_shell_crash_test\": \"b4ecbeb44d84dac302f56f3831b07508a8c1b586\", \"content_unittests\": \"a6198f04eef70e014d87b46d3956c2cbf6bcff12\", \"crashpad_tests\": \"5aad1cdc75f300252e973779ea800f796726ce0e\", \"cronet_tests\": \"7d50ccacaa6d98de211ec979babb3be639db7647\", \"cronet_unittests\": \"82a1eebe00579d0af1325834c9fcb0d1feb66566\", \"crypto_unittests\": \"f7307e05f94f9aeb92233f60dfb4c47fd9e146d4\", \"dbus_unittests\": \"26b061d5a280361f5c6848e168aff54e48501b12\", \"device_unittests\": \"fbe414183d9748a909f0d819a59ed0ce72a92616\", \"display_unittests\": \"e851cbb6669d433df1e5ef5f6dc6ceea969ebd08\", \"events_unittests\": \"b269f92d7acba4110dd21ab3aa337eaacc713a02\", \"extensions_browsertests\": \"c62ae6afac105fea4bafded05d8a44304f25e5f6\", \"extensions_unittests\": \"7dab24256793611debd34b4907f508b7602c3896\", \"filesystem_service_unittests\": \"a4474b24e3b1b00fc9d246de301cb2b70a406ebd\", \"flatbuffers_unittests\": \"72ac4f314db80fab790f3135fe10fde26e7da08e\", \"gcm_unit_tests\": \"9adec761f0c7021f93ca82ef68a7ab57eb4fcba0\", \"gfx_unittests\": \"24ebb236c4ae94591add19863250534d871e59a1\", \"gin_unittests\": \"954f95a607cba9f299341f09780d415723afe3d0\", \"google_apis_unittests\": \"21a035291956079bfefcd4f6a2777bdedc6de0fb\", \"gpu_unittests\": \"a8fee47e53749f130388283523945dcdd83da020\", \"grit_python_unittests\": \"8c3aba08684a4851b7eb5e55316c1cdda2ef1701\", \"gwp_asan_unittests\": \"06323065388513f4803e0307de5b9136b0f8bfc7\", \"headless_browsertests\": \"56aae7c7ec5cc0d92ba7061db889b9ad162875fd\", \"headless_unittests\": \"25f5c1ef95835dc1b4c79b95e128e40ccadf0412\", \"interactive_ui_tests\": \"b66c01384287406b52ff67ce8ce8dd9dcf7877d8\", \"ipc_tests\": \"ce03247a5382f134ccf4ba5814fbde8ad4d7dcf8\", \"jingle_unittests\": \"3ce4fbe3cedc1fdfebde4a4610418bd7b1908900\", \"latency_unittests\": \"6f0d86918f887c6ef51f03b221e925ab991bfe14\", \"libjingle_xmpp_unittests\": \"76d87231a47c24c05b10c9c74532d8e36fd0fd12\", \"media_blink_unittests\": \"a56739dc44241254573aaa7e826351bd8ac7c50c\", \"media_unittests\": \"a86b228ce0f015308fb5923ff8b3cd59ffea7f4f\", \"message_center_unittests\": \"cdb13c52b9388e4ec83a6dda44d80a4bce1b4111\", \"metrics_python_tests\": \"c5459c8e87b46d1dc9c93c7e0768ce27e362b85e\", \"midi_unittests\": \"612d8b7d3a7fcb753cb5ef74b7de44fe80d24c02\", \"mojo_core_unittests\": \"31ec167af5cdabfeed6e6346d33a20c178c66eb5\", \"mojo_unittests\": \"f22e5db3473caebf474e98fce6eff5a362be9c94\", \"nacl_helper_nonsfi_unittests\": \"73ddca0a5c29665d329a74c7a0b60b85bce6e85d\", \"nacl_loader_unittests\": \"c079613408ce6090e6da52fe64f15343989254cb\", \"native_theme_unittests\": \"ff64b42edab9cbe758ebb7ee6b87984601fcf50c\", \"net_unittests\": \"65a1c0895a352a8b7dc24458cadfa796f856a346\", \"pdf_unittests\": \"96cb0ba6c72cb3e3fdfab1defa879a27199c3d78\", \"perfetto_unittests\": \"7171853255627a2bcd6e01a8cb09e18fa5b253f4\", \"ppapi_unittests\": \"656b2582b6c54afa4b9bb40aeef1d2364a2b9331\", \"printing_unittests\": \"69b3b49ea74386c714c2a1daef9eda6189275816\", \"remoting_unittests\": \"0a4dedf7ebe07863dfa4a8f29174780337e81838\", \"sandbox_linux_unittests\": \"82a4e621de460743abef31e97983a319d8679049\", \"service_manager_unittests\": \"ac60feb99deb88e4843194bfd102a376b4a1880d\", \"services_unittests\": \"b10bb30f67bbfc6510af2edcafaafd5bbfa904af\", \"shell_dialogs_unittests\": \"56a29c1eada5b55fb29284ff30c3e48be9aa0ed8\", \"skia_unittests\": \"4794824355d48f6193854c8e0f25eb417fdbbe1a\", \"snapshot_unittests\": \"3048ad3e40e376f60f379803d63f6d740a444f8c\", \"sql_unittests\": \"5bed183ab3048fda75f2684fe9d129f17424a584\", \"storage_unittests\": \"a5c7a5f61abfc61d8bc514683e5c01c6b5ff8a8a\", \"sync_integration_tests\": \"06483e2ab4d60428deb1eec7c47684f2a9eb50b2\", \"telemetry_gpu_unittests\": \"80578d5bbb5a7e27a71760d3df69f2be1165f29f\", \"telemetry_perf_unittests\": \"2f72ee3f747fd5771df36d6decbe7052d8a0d7e7\", \"telemetry_unittests\": \"f0551c9af38316a84affbb3d3cf992c4efde9beb\", \"traffic_annotation_auditor_unittests\": \"dc8c79c09847ecb7326b276d2b07ad790617e324\", \"ui_base_unittests\": \"aa1850f54be51c33d1ca6001944278e8b9151268\", \"ui_touch_selection_unittests\": \"de919db7b916d9ad0f45af13f010941dbe505f65\", \"unit_tests\": \"4165f0a8a852315d186de30205caab7b7021d8b0\", \"url_unittests\": \"67d7241b606796f9efe1f8457a98a859da6e0e65\", \"views_perftests\": \"f93ae6da219b9b1e103affc10426091e824612a1\", \"views_unittests\": \"98e1e0f2c2835dcea61d8d9f54e25b6a0e0daaf6\", \"viz_unittests\": \"3ee934eb535ff1e32b653f08a2293c13a2b06e9e\", \"vr_common_unittests\": \"8db1266453a37de174b6f7cedc750dcc9395171b\", \"vr_pixeltests\": \"f65a451e212de24c617b931ae5736adfc1962273\", \"webdriver_wpt_tests\": \"b812bca07815546bd0dc449d58f962677745f660\", \"weblayer_browsertests\": \"6e18dbafcf5c8392b7511016b06998d810de7562\", \"wm_unittests\": \"83dcb4496058ab44f75ccb4e8c9089795c33f375\", \"wtf_unittests\": \"54fa0be7ac3bd257f03fbc763bb45935226e3532\", \"xr_browser_tests\": \"c74eb19efcca7c5a1cffb60d7ef632396c1e6d2e\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2b15148fa2a471165482335bd523b5eee69ace70a2c04528f88245080a969046_v2\", \"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_662609c3a18ad850d61ba5f58749e60478bc4d9d576475e0c738a1c9e2d8d890_v2\", \"builder_8851890e994e29b56fba2042f90feba99073c8e56d0ea67af5ee13ebe9f87cf6_v2\", \"builder_b9373cfdcc5f43b5e07cc4931fc34027757a424c7183ae2b31ae65ec7718ca12_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-81-40kp\"], \"image\": [\"chrome-xenial-20032900-6802ab9ce34\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4916-b2318d4\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1585788542323493",
"status": "COMPLETED",
"status_changed_ts": "1585791873333517",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/170509",
"builder:Linux Builder (dbg)",
"buildset:commit/git/344e034cc5da9425d3623ea0e9c954fc26023e86",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/344e034cc5da9425d3623ea0e9c954fc26023e86",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9049604284336988272",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8884174222375578112/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4b5129dbc0f2ce10",
"user_agent:luci-scheduler"
],
"updated_ts": "1585791873347416",
"url": "https://ci.chromium.org/b/8884174222375578112",
"utcnow_ts": "1585791873826422"
}
}
[01:44:33.837] Invocation finished in 1h0m31.242264562s with status SUCCEEDED