[01:04:28.180] New invocation is queued and will start shortly
[01:04:29.293] Starting the invocation (attempt 1)
[01:04:29.333] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:04:29.333] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:04:29.333] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054042250598987344",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7e6122a30da5d3a0ffded6610e526934c0fa21b7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054042250598987344",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/7e6122a30da5d3a0ffded6610e526934c0fa21b7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7e6122a30da5d3a0ffded6610e526934c0fa21b7",
"gitiles_ref:refs/heads/master"
]
}
[01:04:30.456] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581555869724602",
"id": "8888612188306058560",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7e6122a30da5d3a0ffded6610e526934c0fa21b7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581555870285700",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168535",
"builder:Linux Builder (dbg)",
"buildset:commit/git/7e6122a30da5d3a0ffded6610e526934c0fa21b7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7e6122a30da5d3a0ffded6610e526934c0fa21b7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054042250598987344",
"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/8888612188306058560/+/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": "1581555870285801",
"url": "https://ci.chromium.org/b/8888612188306058560",
"utcnow_ts": "1581555870433949"
}
}
[01:04:30.456] Task URL: https://ci.chromium.org/b/8888612188306058560
[01:04:30.456] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:2:0) after 1m0s
[01:05:30.481] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:2:0)
[01:05:30.481] Timer tick, asking Buildbucket for the build status
[01:05:32.644] Build 8888612188306058560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:32.644] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:3:0) after 1m0s
[01:06:32.687] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:3:0)
[01:06:32.687] Timer tick, asking Buildbucket for the build status
[01:06:32.888] Build 8888612188306058560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:32.888] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:4:0) after 1m0s
[01:07:32.958] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:4:0)
[01:07:32.958] Timer tick, asking Buildbucket for the build status
[01:07:33.145] Build 8888612188306058560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:33.145] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:5:0) after 1m0s
[01:08:33.227] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:5:0)
[01:08:33.227] Timer tick, asking Buildbucket for the build status
[01:08:33.536] Build 8888612188306058560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:33.536] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:6:0) after 1m0s
[01:09:20.121] Received PubSub notification, asking Buildbucket for the build status
[01:09:20.316] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:33.656] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:6:0)
[01:09:33.656] Timer tick, asking Buildbucket for the build status
[01:09:33.803] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:33.803] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:8:0) after 1m0s
[01:10:33.890] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:8:0)
[01:10:33.890] Timer tick, asking Buildbucket for the build status
[01:10:34.158] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:34.158] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:9:0) after 1m0s
[01:11:34.388] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:9:0)
[01:11:34.388] Timer tick, asking Buildbucket for the build status
[01:11:34.670] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:34.670] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:10:0) after 1m0s
[01:12:34.760] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:10:0)
[01:12:34.760] Timer tick, asking Buildbucket for the build status
[01:12:34.940] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:34.940] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:11:0) after 1m0s
[01:13:35.033] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:11:0)
[01:13:35.033] Timer tick, asking Buildbucket for the build status
[01:13:35.308] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:35.308] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:12:0) after 1m0s
[01:14:35.325] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:12:0)
[01:14:35.325] Timer tick, asking Buildbucket for the build status
[01:14:35.654] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:35.654] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:13:0) after 1m0s
[01:15:35.642] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:13:0)
[01:15:35.642] Timer tick, asking Buildbucket for the build status
[01:15:35.942] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:35.942] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:14:0) after 1m0s
[01:16:36.022] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:14:0)
[01:16:36.022] Timer tick, asking Buildbucket for the build status
[01:16:36.448] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:36.448] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:15:0) after 1m0s
[01:17:36.506] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:15:0)
[01:17:36.506] Timer tick, asking Buildbucket for the build status
[01:17:36.775] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:36.775] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:16:0) after 1m0s
[01:18:36.794] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:16:0)
[01:18:36.794] Timer tick, asking Buildbucket for the build status
[01:18:37.698] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:37.698] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:17:0) after 1m0s
[01:19:37.751] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:17:0)
[01:19:37.751] Timer tick, asking Buildbucket for the build status
[01:19:38.320] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:38.320] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:18:0) after 1m0s
[01:20:38.503] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:18:0)
[01:20:38.503] Timer tick, asking Buildbucket for the build status
[01:20:39.064] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:39.064] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:19:0) after 1m0s
[01:21:39.158] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:19:0)
[01:21:39.158] Timer tick, asking Buildbucket for the build status
[01:21:39.484] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:39.485] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:20:0) after 1m0s
[01:22:39.565] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:20:0)
[01:22:39.565] Timer tick, asking Buildbucket for the build status
[01:22:41.300] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:41.300] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:21:0) after 1m0s
[01:23:41.389] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:21:0)
[01:23:41.389] Timer tick, asking Buildbucket for the build status
[01:23:42.255] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:42.255] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:22:0) after 1m0s
[01:24:42.318] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:22:0)
[01:24:42.318] Timer tick, asking Buildbucket for the build status
[01:24:42.513] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:42.514] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:23:0) after 1m0s
[01:25:42.722] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:23:0)
[01:25:42.723] Timer tick, asking Buildbucket for the build status
[01:25:42.932] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:42.932] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:24:0) after 1m0s
[01:26:42.999] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:24:0)
[01:26:42.999] Timer tick, asking Buildbucket for the build status
[01:26:43.622] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:43.622] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:25:0) after 1m0s
[01:27:43.771] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:25:0)
[01:27:43.771] Timer tick, asking Buildbucket for the build status
[01:27:43.987] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:43.987] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:26:0) after 1m0s
[01:28:44.207] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:26:0)
[01:28:44.207] Timer tick, asking Buildbucket for the build status
[01:28:44.680] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:44.680] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:27:0) after 1m0s
[01:29:44.796] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:27:0)
[01:29:44.796] Timer tick, asking Buildbucket for the build status
[01:29:45.546] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:45.546] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:28:0) after 1m0s
[01:30:45.606] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:28:0)
[01:30:45.606] Timer tick, asking Buildbucket for the build status
[01:30:46.571] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:46.571] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:29:0) after 1m0s
[01:31:46.624] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:29:0)
[01:31:46.624] Timer tick, asking Buildbucket for the build status
[01:31:46.804] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:46.804] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:30:0) after 1m0s
[01:32:46.778] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:30:0)
[01:32:46.778] Timer tick, asking Buildbucket for the build status
[01:32:47.273] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:47.273] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:31:0) after 1m0s
[01:33:47.457] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:31:0)
[01:33:47.457] Timer tick, asking Buildbucket for the build status
[01:33:48.220] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:48.220] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:32:0) after 1m0s
[01:34:48.498] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:32:0)
[01:34:48.498] Timer tick, asking Buildbucket for the build status
[01:34:48.879] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:48.879] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:33:0) after 1m0s
[01:35:49.038] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:33:0)
[01:35:49.038] Timer tick, asking Buildbucket for the build status
[01:35:49.713] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:49.713] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:34:0) after 1m0s
[01:36:49.770] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:34:0)
[01:36:49.770] Timer tick, asking Buildbucket for the build status
[01:36:50.870] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:50.870] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:35:0) after 1m0s
[01:37:51.032] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:35:0)
[01:37:51.032] Timer tick, asking Buildbucket for the build status
[01:37:51.207] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:51.207] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:36:0) after 1m0s
[01:38:51.379] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:36:0)
[01:38:51.379] Timer tick, asking Buildbucket for the build status
[01:38:51.601] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:51.601] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:37:0) after 1m0s
[01:39:51.614] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:37:0)
[01:39:51.614] Timer tick, asking Buildbucket for the build status
[01:39:52.034] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:52.034] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:38:0) after 1m0s
[01:40:52.085] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:38:0)
[01:40:52.085] Timer tick, asking Buildbucket for the build status
[01:40:53.084] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:53.084] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:39:0) after 1m0s
[01:41:53.209] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:39:0)
[01:41:53.209] Timer tick, asking Buildbucket for the build status
[01:41:53.436] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:53.436] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:40:0) after 1m0s
[01:42:53.567] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:40:0)
[01:42:53.567] Timer tick, asking Buildbucket for the build status
[01:42:53.759] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:53.759] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:41:0) after 1m0s
[01:43:54.049] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:41:0)
[01:43:54.050] Timer tick, asking Buildbucket for the build status
[01:43:54.791] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:54.791] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:42:0) after 1m0s
[01:44:55.009] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:42:0)
[01:44:55.009] Timer tick, asking Buildbucket for the build status
[01:44:55.823] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:55.823] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:43:0) after 1m0s
[01:45:55.878] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:43:0)
[01:45:55.878] Timer tick, asking Buildbucket for the build status
[01:45:57.347] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:57.347] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:44:0) after 1m0s
[01:46:57.482] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:44:0)
[01:46:57.482] Timer tick, asking Buildbucket for the build status
[01:46:57.748] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:57.748] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:45:0) after 1m0s
[01:47:57.862] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:45:0)
[01:47:57.862] Timer tick, asking Buildbucket for the build status
[01:48:00.079] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:00.079] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:46:0) after 1m0s
[01:49:00.165] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:46:0)
[01:49:00.165] Timer tick, asking Buildbucket for the build status
[01:49:00.916] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:00.917] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:47:0) after 1m0s
[01:50:01.106] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:47:0)
[01:50:01.106] Timer tick, asking Buildbucket for the build status
[01:50:01.314] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:01.314] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:48:0) after 1m0s
[01:51:01.506] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:48:0)
[01:51:01.506] Timer tick, asking Buildbucket for the build status
[01:51:01.776] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:01.776] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:49:0) after 1m0s
[01:52:01.812] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:49:0)
[01:52:01.812] Timer tick, asking Buildbucket for the build status
[01:52:02.431] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:02.431] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:50:0) after 1m0s
[01:53:02.477] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:50:0)
[01:53:02.477] Timer tick, asking Buildbucket for the build status
[01:53:03.105] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:03.105] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:51:0) after 1m0s
[01:54:03.224] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:51:0)
[01:54:03.224] Timer tick, asking Buildbucket for the build status
[01:54:03.703] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:03.703] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:52:0) after 1m0s
[01:55:03.761] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:52:0)
[01:55:03.761] Timer tick, asking Buildbucket for the build status
[01:55:03.937] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:03.937] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:53:0) after 1m0s
[01:56:04.977] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:53:0)
[01:56:04.977] Timer tick, asking Buildbucket for the build status
[01:56:05.825] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:05.825] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:54:0) after 1m0s
[01:57:05.862] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:54:0)
[01:57:05.862] Timer tick, asking Buildbucket for the build status
[01:57:06.478] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:06.478] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:55:0) after 1m0s
[01:58:06.592] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:55:0)
[01:58:06.592] Timer tick, asking Buildbucket for the build status
[01:58:06.795] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:06.795] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:56:0) after 1m0s
[01:59:06.820] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:56:0)
[01:59:06.820] Timer tick, asking Buildbucket for the build status
[01:59:07.173] Build 8888612188306058560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:07.173] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054042250598987344:57:0) after 1m0s
[01:59:42.628] Received PubSub notification, asking Buildbucket for the build status
[01:59:42.926] Build 8888612188306058560: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:59:42.926] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581559135732087",
"created_by": "project:chromium",
"created_ts": "1581555869724602",
"id": "8888612188306058560",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7e6122a30da5d3a0ffded6610e526934c0fa21b7\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$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-9-dwkh\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168535, \"got_angle_revision\": \"593706bcc0663639b6bb79949b769b71a59e56a4\", \"got_dawn_revision\": \"2fd618192985122aafb494ac5e9a39f572ef46fc\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"7e6122a30da5d3a0ffded6610e526934c0fa21b7\", \"got_revision_cp\": \"refs/heads/master@{#740913}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"ac4e1d236088f90e3bc97aa67002ffe56042e4f2\", \"got_v8_revision\": \"acf9c0def690e10a2dc454d0c0aab1ff73ec7cee\", \"got_v8_revision_cp\": \"refs/heads/8.2.67@{#1}\", \"got_webrtc_revision\": \"d4c3c3a454215b21f02d2a772a729b4c87d6b33e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30486}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7e6122a30da5d3a0ffded6610e526934c0fa21b7\", \"swarm_hashes_refs/heads/master(at){#740913}_without_patch\": {\"accessibility_unittests\": \"eb64006123df14df747a5dc6c0a32b5404c57f54\", \"angle_unittests\": \"5011038d70216b155c006cbf05506c9baeac0850\", \"app_shell_unittests\": \"aeb25334bf79914738c71c9dc84a354794aed3a7\", \"aura_unittests\": \"548c18e781d0ef39d2ded7955b8710daeeeea052\", \"base_unittests\": \"332a8c1152d938253fd148faa2041e501f37261e\", \"base_util_unittests\": \"e17668784acbfa58b724b1c3f35cf3c631b506e7\", \"blink_common_unittests\": \"7f1773b86d2ec492ef2adf47753a32adf9af52ed\", \"blink_fuzzer_unittests\": \"eb41683ba5bb1b91681b8a9966d2eeee5545a0e3\", \"blink_heap_unittests\": \"2cdd316d35ba6c9b55fb2446128efb42294298d1\", \"blink_platform_unittests\": \"0a870c870acf37f51ae31251f2bfcb56a04dce35\", \"blink_python_tests\": \"a07d60ef8c0d3f4cc4896468f874388642a0b611\", \"blink_unittests\": \"5b550be72deba617c3f5b54f215bdf4d60e679f0\", \"blink_web_tests\": \"241d376bb3f6dacd55e589e029de3d870c53fbc8\", \"boringssl_crypto_tests\": \"b87a055c98d697df135e48b0c23327df3468e283\", \"boringssl_ssl_tests\": \"98d469f6f38158412495b377357c0d4ac7d11c97\", \"browser_tests\": \"eb5e18f80a92c76005c2befe968c3991feb8a88e\", \"cacheinvalidation_unittests\": \"63312ec567db8c629f743fe998eddfda58183bcb\", \"capture_unittests\": \"85924b6249aad8173a6a50955c0b25f4423808ee\", \"cast_unittests\": \"63639831ab7840a21e6411ef9ac384e926e283fc\", \"cc_unittests\": \"9449e1d3813942c8814c05b3171dcac2f4479443\", \"chrome_app_unittests\": \"fbaa1681bd38a8f8e858a7ac3e678d7a3cadf573\", \"chromedriver_unittests\": \"7a060b781f20bbf1f97199fbcc8d8b2419c9580d\", \"components_browsertests\": \"a0ad987eb302c6b3c97b1bbf0efca1a209da90a7\", \"components_unittests\": \"f3d125c7aea4274bc677639c01f599ecf07e7706\", \"compositor_unittests\": \"9e082a3bdf355a4d9b078495cc52e974bc9852e7\", \"content_browsertests\": \"d81d419e61f97377a2a7d6dc0d7274b19071cd4b\", \"content_shell_crash_test\": \"966368785866eedb2fa44308380e4fcc765a9f6a\", \"content_unittests\": \"8f77f76715791a9632c7e17d70366727a061dbfe\", \"crashpad_tests\": \"e12d70617f5414d5734e54cf7cf7e76a1c138961\", \"cronet_tests\": \"eb52b23d7c0946e0060642d6d9a5c19850c7ca0c\", \"cronet_unittests\": \"b81e2cb96c1f3b90d2adaecbd160af4be0bcbcfb\", \"crypto_unittests\": \"1924ae41beaaf91a8b7d97335bdd1bbe1751d70e\", \"dbus_unittests\": \"bab92fd98e2699ee4ede963aac0c36b8ee990add\", \"device_unittests\": \"deb8797bc6551f60031c4f3e7330c3495b7b5f5a\", \"display_unittests\": \"bccc39dd1355b9c803ddc4df018e27528c1b1c7d\", \"events_unittests\": \"d42ad287dab7a3ca4e648af79d9820ab2de6b0f3\", \"extensions_browsertests\": \"a6013ee8356754bb71b4abe6f219eb3433a86cd6\", \"extensions_unittests\": \"03e77fd7c83d5be68732b17da7220b1e1345764d\", \"filesystem_service_unittests\": \"490a0d28c1b5e7de9d2b9d64644c21dce796ea31\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"92d563aa7c1cf9195758e56396e210c8ae2e7a7e\", \"gfx_unittests\": \"772d7d42178998bc43d223cd76335e126df919c2\", \"gin_unittests\": \"210e2288bf878bc7c25cf7874870ca57085e579f\", \"google_apis_unittests\": \"080da0fb1b5cf8580ea9051dd4eaf8ba2897e4d4\", \"gpu_unittests\": \"3be818dd7c5a79259e6c19fb34070765f67ac3f3\", \"grit_python_unittests\": \"07488c096d20bb2a4e6d9280813fca6afbee6dc0\", \"gwp_asan_unittests\": \"fab8b58188083ab0d7ea803f315934d46c509def\", \"headless_browsertests\": \"ce5d9a527edd87c61eee96a32f053eb8bdf25378\", \"headless_unittests\": \"e967ee39a90050f35ed39afd6f7d88e9ef40ce09\", \"interactive_ui_tests\": \"648a93e82a894e71ade03a543afb654acc44ad55\", \"ipc_tests\": \"fdb899441b621aeb761451dca3c40a81118fd1e7\", \"jingle_unittests\": \"3d5e7f96062d31ec74902231b50c4a73a9973046\", \"latency_unittests\": \"fa1e96d87d10834047125b5e782318bd40ac0319\", \"libjingle_xmpp_unittests\": \"3470d7d77992d659dc720d8bce96b5fd0e7edb14\", \"media_blink_unittests\": \"d5252355ecc5ad8aa276e914bdebac88d5f11afa\", \"media_service_unittests\": \"5e85a444b3f42b75b7f06367f3d958e6f48cbc4b\", \"media_unittests\": \"8c361526461e71aa2b7c5a1cc9d007f6349b8b89\", \"message_center_unittests\": \"9d3b0045f394a1f4568d99257289705ffefed720\", \"metrics_python_tests\": \"d3d03b409d4c8a56289764ff548be91eaea1cec6\", \"midi_unittests\": \"553f69e0a9d43fbb61bd58ec9169cec77f251889\", \"mojo_core_unittests\": \"37d934fe119ab7e718daeca692ae912773e6bf51\", \"mojo_unittests\": \"2c34f38989898c7967efe6d7d55b0c090be7f0d5\", \"nacl_helper_nonsfi_unittests\": \"1b7c37e43aa25cebc010dee06effda0066402797\", \"nacl_loader_unittests\": \"6e67104fd490470bc97c10c08e13bbe9564b3d38\", \"native_theme_unittests\": \"4961ac2553c17222cea929a4e0adeb147dc180ea\", \"net_unittests\": \"d68327a537d89cb0b2bd69588907f9070d217610\", \"pdf_unittests\": \"4b12595b3dc31e2634b9d703b7fa976137349e20\", \"perfetto_unittests\": \"0f93b3731a0153693ce3cb7e8c33dce71004662d\", \"ppapi_unittests\": \"2f8c2b4916f9a04a82c63b9c6d375556ad908a1c\", \"printing_unittests\": \"4b643b24e272b0c6ebe077fc6fdae5287d58aff6\", \"remoting_unittests\": \"09e160006d587a0728389d2050737d48196e7143\", \"sandbox_linux_unittests\": \"c954623bc4b652987c727c1e551e31948f025985\", \"service_manager_unittests\": \"23d7c0d9170e2db10848eac64be339cbcd414b12\", \"services_unittests\": \"3ea41eec447dc518938b1384ab4f7d25449a1cc5\", \"shell_dialogs_unittests\": \"b7000de5f1a9f36c8b79ec036494d584be886a5b\", \"skia_unittests\": \"ce62f8015c666d030ed9cac8cc392023d6ab1216\", \"snapshot_unittests\": \"5dc63a00c16bf68cc8f173c53f8d9aed8ebb2142\", \"sql_unittests\": \"8b93b52ade2ec6ac586908db0d2057e13fc6e8ae\", \"storage_unittests\": \"b63a41d008edd8700e2a5a002e7bb91ff0ab5b3a\", \"sync_integration_tests\": \"3cdb5f06838677c9562c9ffc97c39cdf9e1b2ec6\", \"telemetry_gpu_unittests\": \"f7978585c72c10f2480c78dbf546fdc78523b0a3\", \"telemetry_perf_unittests\": \"d4585e5b3f27587be646c84a2cb743609c558e94\", \"telemetry_unittests\": \"3711bf28a86045e2be974193506dd1e1796d786a\", \"traffic_annotation_auditor_unittests\": \"a88862794e0672dce1e4f5f9039cfdd5ce635821\", \"ui_base_unittests\": \"c86e3a659fdf1d7d57d519414f0151c6f87510ea\", \"ui_touch_selection_unittests\": \"134f7effc1eeb71fad077ca203813023ba28bfae\", \"unit_tests\": \"d424e8705a71c6d982ac34b3e3738d6d52d201bf\", \"url_unittests\": \"18df327d5be57610d0865e78bcac4f6d80710092\", \"views_perftests\": \"e85398c6ecfe1a9af81e75dea371e03de874d673\", \"views_unittests\": \"01543cfe41d0fcb1a214c423253fa78c3ff85c59\", \"viz_unittests\": \"ce7757e35935663ddea235fc35beb7a0ecd7f9f3\", \"vr_common_unittests\": \"67f8d6963a8e37fbf50f0bbf41a14dec31b1162b\", \"vr_pixeltests\": \"55dc5266b7ef153fca1455872eb871e499a8a0ee\", \"webdriver_wpt_tests\": \"8617b9d9e8222ebcf8758324f8bfbbea989808a3\", \"weblayer_browsertests\": \"9c0394e569aa0665b2984c2c8a20359daf5ac86d\", \"wm_unittests\": \"283a4a21d7fe9646a5a1df97b1df109c6de23cc0\", \"wtf_unittests\": \"eb9903a41bb7076287ee9ec00cd46744e8cc4fd9\", \"xr_browser_tests\": \"9dce5891a32090324b9bba4e14cc99c555f6e7fc\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3d74f5cea4cbf91e4b26f92a522c5c74e61b738440ef29ba16f5c9a596a5b201_v2\", \"builder_65ed874bd033a5984c3cbb528261ef1b136b40627f077f473aaaca27b3fa3caf_v2\", \"builder_662609c3a18ad850d61ba5f58749e60478bc4d9d576475e0c738a1c9e2d8d890_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-9-dwkh\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"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\": [\"4812-ea7e8fc\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581556143193118",
"status": "COMPLETED",
"status_changed_ts": "1581559182006754",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168535",
"builder:Linux Builder (dbg)",
"buildset:commit/git/7e6122a30da5d3a0ffded6610e526934c0fa21b7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7e6122a30da5d3a0ffded6610e526934c0fa21b7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054042250598987344",
"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/8888612188306058560/+/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:4a54e4f5ae415b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1581559182009973",
"url": "https://ci.chromium.org/b/8888612188306058560",
"utcnow_ts": "1581559182917794"
}
}
[01:59:42.926] Invocation finished in 55m14.772016952s with status SUCCEEDED