[01:22:29.221] New invocation is queued and will start shortly
[01:22:30.323] Starting the invocation (attempt 1)
[01:22:30.418] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:22:30.418] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:22:30.418] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054131714000844112",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"40e9d3e4a8126ceaf4d27ef463eeae7ce6ad9254\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054131714000844112",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/40e9d3e4a8126ceaf4d27ef463eeae7ce6ad9254",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/40e9d3e4a8126ceaf4d27ef463eeae7ce6ad9254",
"gitiles_ref:refs/heads/master"
]
}
[01:22:31.290] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581470550547337",
"id": "8888701651947559920",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"40e9d3e4a8126ceaf4d27ef463eeae7ce6ad9254\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581470550967147",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168499",
"builder:Linux Builder (dbg)",
"buildset:commit/git/40e9d3e4a8126ceaf4d27ef463eeae7ce6ad9254",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/40e9d3e4a8126ceaf4d27ef463eeae7ce6ad9254",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054131714000844112",
"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/8888701651947559920/+/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": "1581470550967276",
"url": "https://ci.chromium.org/b/8888701651947559920",
"utcnow_ts": "1581470551270764"
}
}
[01:22:31.290] Task URL: https://ci.chromium.org/b/8888701651947559920
[01:22:31.290] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:2:0) after 1m0s
[01:23:31.310] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:2:0)
[01:23:31.310] Timer tick, asking Buildbucket for the build status
[01:23:36.587] Build 8888701651947559920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:36.587] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:3:0) after 1m0s
[01:24:36.721] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:3:0)
[01:24:36.721] Timer tick, asking Buildbucket for the build status
[01:24:37.015] Build 8888701651947559920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:24:37.015] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:4:0) after 1m0s
[01:25:37.072] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:4:0)
[01:25:37.072] Timer tick, asking Buildbucket for the build status
[01:25:41.276] Build 8888701651947559920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:25:41.276] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:5:0) after 1m0s
[01:26:41.312] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:5:0)
[01:26:41.313] Timer tick, asking Buildbucket for the build status
[01:26:41.476] Build 8888701651947559920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:26:41.476] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:6:0) after 1m0s
[01:27:22.246] Received PubSub notification, asking Buildbucket for the build status
[01:27:22.344] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:41.487] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:6:0)
[01:27:41.487] Timer tick, asking Buildbucket for the build status
[01:27:41.768] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:41.768] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:8:0) after 1m0s
[01:28:41.972] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:8:0)
[01:28:41.972] Timer tick, asking Buildbucket for the build status
[01:28:42.933] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:42.933] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:9:0) after 1m0s
[01:29:42.983] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:9:0)
[01:29:42.983] Timer tick, asking Buildbucket for the build status
[01:29:43.363] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:43.363] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:10:0) after 1m0s
[01:30:43.440] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:10:0)
[01:30:43.440] Timer tick, asking Buildbucket for the build status
[01:30:43.635] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:43.635] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:11:0) after 1m0s
[01:31:43.663] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:11:0)
[01:31:43.663] Timer tick, asking Buildbucket for the build status
[01:31:44.239] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:44.239] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:12:0) after 1m0s
[01:32:44.308] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:12:0)
[01:32:44.308] Timer tick, asking Buildbucket for the build status
[01:32:44.647] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:44.647] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:13:0) after 1m0s
[01:33:44.699] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:13:0)
[01:33:44.699] Timer tick, asking Buildbucket for the build status
[01:33:44.848] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:44.848] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:14:0) after 1m0s
[01:34:44.894] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:14:0)
[01:34:44.894] Timer tick, asking Buildbucket for the build status
[01:34:45.150] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:45.150] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:15:0) after 1m0s
[01:35:45.188] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:15:0)
[01:35:45.188] Timer tick, asking Buildbucket for the build status
[01:35:45.512] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:45.512] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:16:0) after 1m0s
[01:36:45.543] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:16:0)
[01:36:45.543] Timer tick, asking Buildbucket for the build status
[01:36:45.773] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:45.773] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:17:0) after 1m0s
[01:37:45.796] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:17:0)
[01:37:45.796] Timer tick, asking Buildbucket for the build status
[01:37:46.019] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:46.019] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:18:0) after 1m0s
[01:38:46.052] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:18:0)
[01:38:46.052] Timer tick, asking Buildbucket for the build status
[01:38:46.509] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:46.509] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:19:0) after 1m0s
[01:39:46.606] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:19:0)
[01:39:46.606] Timer tick, asking Buildbucket for the build status
[01:39:46.764] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:46.764] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:20:0) after 1m0s
[01:40:46.805] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:20:0)
[01:40:46.805] Timer tick, asking Buildbucket for the build status
[01:40:48.227] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:48.227] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:21:0) after 1m0s
[01:41:48.284] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:21:0)
[01:41:48.284] Timer tick, asking Buildbucket for the build status
[01:41:48.463] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:48.463] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:22:0) after 1m0s
[01:42:48.496] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:22:0)
[01:42:48.496] Timer tick, asking Buildbucket for the build status
[01:42:48.638] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:48.638] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:23:0) after 1m0s
[01:43:48.666] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:23:0)
[01:43:48.666] Timer tick, asking Buildbucket for the build status
[01:43:49.001] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:49.001] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:24:0) after 1m0s
[01:44:49.093] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:24:0)
[01:44:49.093] Timer tick, asking Buildbucket for the build status
[01:44:49.274] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:49.274] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:25:0) after 1m0s
[01:45:49.335] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:25:0)
[01:45:49.335] Timer tick, asking Buildbucket for the build status
[01:45:49.460] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:49.460] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:26:0) after 1m0s
[01:46:49.544] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:26:0)
[01:46:49.544] Timer tick, asking Buildbucket for the build status
[01:46:49.691] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:49.691] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:27:0) after 1m0s
[01:47:49.813] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:27:0)
[01:47:49.813] Timer tick, asking Buildbucket for the build status
[01:47:50.384] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:50.384] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:28:0) after 1m0s
[01:48:50.526] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:28:0)
[01:48:50.527] Timer tick, asking Buildbucket for the build status
[01:48:50.898] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:50.898] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:29:0) after 1m0s
[01:49:50.984] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:29:0)
[01:49:50.984] Timer tick, asking Buildbucket for the build status
[01:49:51.120] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:51.121] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:30:0) after 1m0s
[01:50:51.166] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:30:0)
[01:50:51.166] Timer tick, asking Buildbucket for the build status
[01:50:51.789] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:51.789] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:31:0) after 1m0s
[01:51:51.940] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:31:0)
[01:51:51.940] Timer tick, asking Buildbucket for the build status
[01:51:52.110] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:52.110] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:32:0) after 1m0s
[01:52:52.200] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:32:0)
[01:52:52.200] Timer tick, asking Buildbucket for the build status
[01:52:52.494] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:52.494] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:33:0) after 1m0s
[01:53:52.555] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:33:0)
[01:53:52.555] Timer tick, asking Buildbucket for the build status
[01:53:52.713] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:52.713] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:34:0) after 1m0s
[01:54:52.700] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:34:0)
[01:54:52.700] Timer tick, asking Buildbucket for the build status
[01:54:52.882] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:52.882] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:35:0) after 1m0s
[01:55:52.960] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:35:0)
[01:55:52.960] Timer tick, asking Buildbucket for the build status
[01:55:53.129] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:53.129] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:36:0) after 1m0s
[01:56:53.115] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:36:0)
[01:56:53.115] Timer tick, asking Buildbucket for the build status
[01:56:53.311] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:53.311] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:37:0) after 1m0s
[01:57:53.382] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:37:0)
[01:57:53.382] Timer tick, asking Buildbucket for the build status
[01:57:53.552] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:53.552] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:38:0) after 1m0s
[01:58:53.574] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:38:0)
[01:58:53.574] Timer tick, asking Buildbucket for the build status
[01:58:53.706] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:53.706] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:39:0) after 1m0s
[01:59:53.697] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:39:0)
[01:59:53.697] Timer tick, asking Buildbucket for the build status
[01:59:53.864] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:53.864] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:40:0) after 1m0s
[02:00:53.883] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:40:0)
[02:00:53.883] Timer tick, asking Buildbucket for the build status
[02:00:54.114] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:54.114] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:41:0) after 1m0s
[02:01:54.132] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:41:0)
[02:01:54.132] Timer tick, asking Buildbucket for the build status
[02:01:54.537] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:54.537] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:42:0) after 1m0s
[02:02:54.535] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:42:0)
[02:02:54.535] Timer tick, asking Buildbucket for the build status
[02:02:55.797] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:55.797] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:43:0) after 1m0s
[02:03:56.119] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:43:0)
[02:03:56.119] Timer tick, asking Buildbucket for the build status
[02:03:56.955] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:56.955] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:44:0) after 1m0s
[02:04:56.981] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:44:0)
[02:04:56.981] Timer tick, asking Buildbucket for the build status
[02:04:57.654] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:57.654] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:45:0) after 1m0s
[02:05:57.782] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:45:0)
[02:05:57.782] Timer tick, asking Buildbucket for the build status
[02:05:58.007] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:58.007] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:46:0) after 1m0s
[02:06:58.027] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:46:0)
[02:06:58.027] Timer tick, asking Buildbucket for the build status
[02:06:58.765] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:58.765] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:47:0) after 1m0s
[02:07:58.807] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:47:0)
[02:07:58.807] Timer tick, asking Buildbucket for the build status
[02:07:58.988] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:58.988] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:48:0) after 1m0s
[02:08:59.090] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:48:0)
[02:08:59.090] Timer tick, asking Buildbucket for the build status
[02:08:59.787] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:59.787] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:49:0) after 1m0s
[02:09:59.813] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:49:0)
[02:09:59.813] Timer tick, asking Buildbucket for the build status
[02:10:00.086] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:00.086] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:50:0) after 1m0s
[02:11:00.112] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:50:0)
[02:11:00.112] Timer tick, asking Buildbucket for the build status
[02:11:00.473] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:00.473] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:51:0) after 1m0s
[02:12:00.459] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:51:0)
[02:12:00.459] Timer tick, asking Buildbucket for the build status
[02:12:00.607] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:00.607] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:52:0) after 1m0s
[02:13:00.662] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:52:0)
[02:13:00.662] Timer tick, asking Buildbucket for the build status
[02:13:00.931] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:00.931] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:53:0) after 1m0s
[02:14:00.903] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:53:0)
[02:14:00.903] Timer tick, asking Buildbucket for the build status
[02:14:01.099] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:01.099] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:54:0) after 1m0s
[02:15:01.159] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:54:0)
[02:15:01.159] Timer tick, asking Buildbucket for the build status
[02:15:01.332] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:01.332] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:55:0) after 1m0s
[02:16:01.320] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:55:0)
[02:16:01.320] Timer tick, asking Buildbucket for the build status
[02:16:01.474] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:01.474] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:56:0) after 1m0s
[02:17:01.544] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:56:0)
[02:17:01.544] Timer tick, asking Buildbucket for the build status
[02:17:01.699] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:01.699] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:57:0) after 1m0s
[02:18:01.874] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:57:0)
[02:18:01.874] Timer tick, asking Buildbucket for the build status
[02:18:02.085] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:02.085] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:58:0) after 1m0s
[02:19:02.194] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:58:0)
[02:19:02.194] Timer tick, asking Buildbucket for the build status
[02:19:02.474] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:02.474] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:59:0) after 1m0s
[02:20:02.565] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:59:0)
[02:20:02.565] Timer tick, asking Buildbucket for the build status
[02:20:02.714] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:02.714] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:60:0) after 1m0s
[02:21:02.777] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:60:0)
[02:21:02.777] Timer tick, asking Buildbucket for the build status
[02:21:04.996] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:04.996] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:61:0) after 1m0s
[02:22:05.019] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:61:0)
[02:22:05.019] Timer tick, asking Buildbucket for the build status
[02:22:05.181] Build 8888701651947559920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:05.181] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054131714000844112:62:0) after 1m0s
[02:22:07.012] Received PubSub notification, asking Buildbucket for the build status
[02:22:07.166] Build 8888701651947559920: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:22:07.166] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581474126407703",
"created_by": "project:chromium",
"created_ts": "1581470550547337",
"id": "8888701651947559920",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"40e9d3e4a8126ceaf4d27ef463eeae7ce6ad9254\"}}",
"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-east1-d-8-46-wqjd\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168499, \"got_angle_revision\": \"72e9f4d73f656b6955202d31c9f235f8a7fc7ebe\", \"got_dawn_revision\": \"2fd618192985122aafb494ac5e9a39f572ef46fc\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"40e9d3e4a8126ceaf4d27ef463eeae7ce6ad9254\", \"got_revision_cp\": \"refs/heads/master@{#740505}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"6cbb02e0dc934ac1e21b182f0f632b4e806449a6\", \"got_v8_revision\": \"56ab990e69987df228757938e23a9bafccab2fe5\", \"got_v8_revision_cp\": \"refs/heads/8.2.57@{#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\": \"40e9d3e4a8126ceaf4d27ef463eeae7ce6ad9254\", \"swarm_hashes_refs/heads/master(at){#740505}_without_patch\": {\"accessibility_unittests\": \"b9a631900410ec9b405b79091f744bc7a641d24f\", \"angle_unittests\": \"2204ed59755a4f08ef878bcdc13be75cfcb0daa3\", \"app_shell_unittests\": \"75c60fd71f3fb37189449942002706bb6777d883\", \"aura_unittests\": \"ed5b705c9014c1c15f81b264666244a2e631e30c\", \"base_unittests\": \"f717748ac3f63a871ef318dec407f680ba0f4beb\", \"base_util_unittests\": \"74042a8d363256e8e45437af6b2b7cf667f3044c\", \"blink_common_unittests\": \"579bde98ef0d57742571e2eccd14f5f85c5890de\", \"blink_fuzzer_unittests\": \"306f86c556bf50ecf5e1eb9b0eeb2ad73f8003c1\", \"blink_heap_unittests\": \"a0161348b63ea524036591120dc2839456065f59\", \"blink_platform_unittests\": \"1c857305285beef9ed7faaf2dd18d9522fad2235\", \"blink_python_tests\": \"920ab191bc92b0aa3c783d828e67c7f1cb101071\", \"blink_unittests\": \"aa39f7231e19903f345c9542502fe76120057bdc\", \"blink_web_tests\": \"c38f619daf0887e5092d24d44424a6f461bed904\", \"boringssl_crypto_tests\": \"60cfd6f6d968c11bf815a112934f52c9ba8acced\", \"boringssl_ssl_tests\": \"523f6d6b5a38170f8ae28820c3b6f7138adb87ba\", \"browser_tests\": \"6b8dfffd8fa2de0cb541656f65cb98ba2a8bc389\", \"cacheinvalidation_unittests\": \"311698b85a7ef57e58bd5beb9cd18783e06bcafe\", \"capture_unittests\": \"b943d35571ac522e17d4c4b09b53230b9491e9e2\", \"cast_unittests\": \"1fde784201ab6ed263d580c5fb19a6bcd3f76ca8\", \"cc_unittests\": \"8a251766c30a6a29f2f6edf28c5bbf9f6a8d2e1a\", \"chrome_app_unittests\": \"012803e8974db479eb91b3140dc90d6c1f41b9ca\", \"chromedriver_unittests\": \"df485a5802cfb66c35ef4722a7d48079cee87324\", \"components_browsertests\": \"8c831cac6471b0e36306977995599de32548512b\", \"components_unittests\": \"40ad3e7bbee784966d67f364a34ce8eec720a388\", \"compositor_unittests\": \"a7016a2231fa7a7d3bf533a1af115dbc59fdcadf\", \"content_browsertests\": \"fd1f5d1168c8b34acf1fcaa4157b22832f2997ee\", \"content_shell_crash_test\": \"b16e81f58a233a73810a72f2886ba259f80bbd1a\", \"content_unittests\": \"0ec378070ee19e843493dfb4b653353fc31829f7\", \"crashpad_tests\": \"e54076a009d63d4191413a4e782050416c2ac726\", \"cronet_tests\": \"244cee5571280069c979b5532bf2ba2183584047\", \"cronet_unittests\": \"2edaf00a0be659ccaf5b3a7923ff78c959b5a34d\", \"crypto_unittests\": \"bb6a24422c3c82d3892c2877204e1c1cf8c44227\", \"dbus_unittests\": \"a56018a2fd525afe17e30bec818920e6039b914e\", \"device_unittests\": \"4d86e5ab7cf161739c61859ededac73f0a1b0f16\", \"display_unittests\": \"6b62c3bb10fd5bd556653d730403d0f6ba4aa2af\", \"events_unittests\": \"4948b72b84366389703f80b8730ef7c7ffa6a902\", \"extensions_browsertests\": \"188ad1465bb9185f97d55eb7ae490850996f6f69\", \"extensions_unittests\": \"d2f9a8279bc7bf7e33fd6800198573d3794648c8\", \"filesystem_service_unittests\": \"6128808a6dbf5abb2128a44f76d74f513dc17cc5\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"b3ca7050934d11cc527f4b266b592b4f4957eacf\", \"gfx_unittests\": \"c0ccd10c330b43efff3548e594e045717be5b50d\", \"gin_unittests\": \"0b546d390466dcccc4b92c543ca33ece56cc4560\", \"google_apis_unittests\": \"ebe1e204e74b4ca76b27c624a5b573730f039c1d\", \"gpu_unittests\": \"7623ccd24051f36265b7c6425c92a79b5e42ba61\", \"grit_python_unittests\": \"07488c096d20bb2a4e6d9280813fca6afbee6dc0\", \"gwp_asan_unittests\": \"0a44c745c4606a3e2565f247bbc6eb332d6034a5\", \"headless_browsertests\": \"0dfc02b3d0dc2f74d69aca18838aefbc2803cd4c\", \"headless_unittests\": \"88bd76c19f19a14ac412614e34f5b0384a5b3f3c\", \"interactive_ui_tests\": \"08b11501d5d40d1127d66dd9b75bafc1f38e88e7\", \"ipc_tests\": \"4ef3eaf95796bc10d691fd5f0ada337525215bd3\", \"jingle_unittests\": \"03c9f8ec22de79924e12c721cfd6917204ff181a\", \"latency_unittests\": \"c18bb9ea4707dabd6476134cedae1298bdd7aa9e\", \"libjingle_xmpp_unittests\": \"83eecb36bca3f9e7c7e5b0da7440c24c6aad6e5a\", \"media_blink_unittests\": \"3fa49609825697502483d4600e3185b8687f326f\", \"media_service_unittests\": \"a940aa42eb41ee2ec10923ce1efda572ce42517c\", \"media_unittests\": \"4707eca1a11eac4307d3e2d2fd43153922f13a8c\", \"message_center_unittests\": \"c63801f49bf3b78242fb9152e6ab13997c5a4576\", \"metrics_python_tests\": \"bc5c79b205f44af28dcfb328d4347122a8b861a4\", \"midi_unittests\": \"6bf1dc599164c619c52c48cb48b5fbe09b400c4e\", \"mojo_core_unittests\": \"8d41e070d6e38fd76ffd1deae3d5307b51a5a6a3\", \"mojo_unittests\": \"2d744eb8939a36299c7850dc35c507da857f2e42\", \"nacl_helper_nonsfi_unittests\": \"d444deddf6ed5b04db450f949a94dc7134afed97\", \"nacl_loader_unittests\": \"d423bfe65c68a0673f573d035bde330f2e018e6b\", \"native_theme_unittests\": \"7eca07df02d2f74917086d1aa097b016bf995396\", \"net_unittests\": \"3bbfb6c62809a758707f11c64b3e9153cf0e557f\", \"pdf_unittests\": \"84bc6750bb1ac5652694e300e10c56226e589d64\", \"perfetto_unittests\": \"81923ca016b5fd6abd9aa8a2616b8e52e75e16d7\", \"ppapi_unittests\": \"f8ee83e85f228ebc5dc2719aac0b6c3e30c1989c\", \"printing_unittests\": \"3afe02e64e2e5fe4c070c7b0fedebd7320f486a1\", \"remoting_unittests\": \"faf900590353e7eec739d72341b69a112f3f4ede\", \"sandbox_linux_unittests\": \"3d117347cefae28a408b5c2645847fd4c5641aa7\", \"service_manager_unittests\": \"9e2ad47f1ad89207a88a7d8b2c127fb74fcbb74d\", \"services_unittests\": \"bece262209df1c0865f32f23eecc7c081e386f85\", \"shell_dialogs_unittests\": \"6ae1d3541b422115689b48838be46d4cb33475f0\", \"skia_unittests\": \"fb89b00ffdb16997c0ab97601149ec2329968b54\", \"snapshot_unittests\": \"abcc7874ab0144c16cc680a50e6a913d0693f89d\", \"sql_unittests\": \"31ffabbdc1075fdd1aaeb3395a7493af66539f61\", \"storage_unittests\": \"e6b592ffffb7056541d48f42ffd7c02a9a509435\", \"sync_integration_tests\": \"93b10d9bac3fdd19823f50b9ff9b132b06cb4127\", \"telemetry_gpu_unittests\": \"46e0006accdf1dfa7497608c9b51b959f501cbea\", \"telemetry_perf_unittests\": \"8dadc7e0becfd9844ebf508a56100a2b955c82e2\", \"telemetry_unittests\": \"1952d3a0b28effd32fdc443bc5b4b6a3d66ae682\", \"traffic_annotation_auditor_unittests\": \"f4d127a0072bb0eff56b84f7160b8eb106338512\", \"ui_base_unittests\": \"5309b9590ffc817dda608d3a38e8225eaead91e0\", \"ui_touch_selection_unittests\": \"930716dd4c2df73db07df9f1cf65e0fa8e020a69\", \"unit_tests\": \"13728cb49e5dfcf57b5b3a9cb6cb6a96efec6286\", \"url_unittests\": \"914caa414937cdeb32952b5296b686f6ee0e9f90\", \"views_perftests\": \"dbb6ef858a597b625d6c7834c307b86905c05293\", \"views_unittests\": \"13ec4c8c3fa7040221b6a5f37783540bdcf45e3b\", \"viz_unittests\": \"b23845535e8862226d4fef2c04a18103130f9fce\", \"vr_common_unittests\": \"4ee6f2d0b08ad01385626399e1c29dc6104ce400\", \"vr_pixeltests\": \"bdc0637e95ce101b5958fbd5b754f7c2d4fb6d36\", \"webdriver_wpt_tests\": \"f3c4bc0a79c04c95030d72efec71f0a7f0c41494\", \"weblayer_browsertests\": \"e5eedcf1cc999e55e3f2bad112d0e49a8d98d956\", \"wm_unittests\": \"9164c4a2cb35f81fdb96fce4a757e026369aa7f4\", \"wtf_unittests\": \"e36ada7e9a2e6c7b1dd89ff26e193f6c6a202bfb\", \"xr_browser_tests\": \"5245b754aaefc500ba3e4d7f44e361c7ae6d1963\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_43913798ffe4aacc9db317de63924a4a199b83672551513d069405cf6f3bc584_v2\", \"builder_47c3ec4238d3b3d0448e41826f5b2c1435e77a4255b8c93aa27077a59a7a5a67_v2\", \"builder_69f5bab4a885266166e099964d6d1498f6d3761c92bc1d5b083a29cb822fe90c_v2\", \"builder_7d75f6999c07e42a7f7fde2313383264fbd8ed871e2008bde33b278973c1f4c8_v2\", \"builder_ce52aa3ab9d9a450d9b5f3fb151dc5a055ee991845b7d9f6a360be4cd1eb07f1_v2\", \"builder_e5bbff72983945f6847543519ce1052420cedd06fb8495f4080bfa2e6bcfd13f_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-east1-d-8-46-wqjd\"], \"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-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581470841563755",
"status": "COMPLETED",
"status_changed_ts": "1581474126698332",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168499",
"builder:Linux Builder (dbg)",
"buildset:commit/git/40e9d3e4a8126ceaf4d27ef463eeae7ce6ad9254",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/40e9d3e4a8126ceaf4d27ef463eeae7ce6ad9254",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054131714000844112",
"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/8888701651947559920/+/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:4a4fcf152a00c110",
"user_agent:luci-scheduler"
],
"updated_ts": "1581474126701685",
"url": "https://ci.chromium.org/b/8888701651947559920",
"utcnow_ts": "1581474127158525"
}
}
[02:22:07.166] Invocation finished in 59m37.959858648s with status SUCCEEDED