[16:54:44.294] New invocation is queued and will start shortly
[16:54:45.527] Starting the invocation (attempt 1)
[16:54:45.592] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:54:45.592] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:54:45.592] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021639347857301232",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"df4efece97f69cc22c3018015ae753373d250379\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9021639347857301232",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/df4efece97f69cc22c3018015ae753373d250379",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/df4efece97f69cc22c3018015ae753373d250379",
"gitiles_ref:refs/heads/master"
]
}
[16:54:46.285] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612457685665874",
"id": "8856209285754482688",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"df4efece97f69cc22c3018015ae753373d250379\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612457686029958",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180275",
"builder:Linux Builder (dbg)",
"buildset:commit/git/df4efece97f69cc22c3018015ae753373d250379",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/df4efece97f69cc22c3018015ae753373d250379",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021639347857301232",
"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/8856209285754482688/+/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": "1612457686030833",
"url": "https://ci.chromium.org/b/8856209285754482688",
"utcnow_ts": "1612457686255654"
}
}
[16:54:46.285] Task URL: https://ci.chromium.org/b/8856209285754482688
[16:54:46.285] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:2:0) after 1m0s
[16:54:49.822] Received PubSub notification, asking Buildbucket for the build status
[16:54:49.944] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:46.303] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:2:0)
[16:55:46.303] Timer tick, asking Buildbucket for the build status
[16:55:46.449] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:46.449] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:4:0) after 1m0s
[16:56:46.699] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:4:0)
[16:56:46.699] Timer tick, asking Buildbucket for the build status
[16:56:46.767] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:46.767] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:5:0) after 1m0s
[16:57:46.916] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:5:0)
[16:57:46.916] Timer tick, asking Buildbucket for the build status
[16:57:47.026] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:47.026] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:6:0) after 1m0s
[16:58:47.218] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:6:0)
[16:58:47.218] Timer tick, asking Buildbucket for the build status
[16:58:47.326] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:47.326] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:7:0) after 1m0s
[16:59:47.491] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:7:0)
[16:59:47.491] Timer tick, asking Buildbucket for the build status
[16:59:47.583] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:47.583] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:8:0) after 1m0s
[17:00:47.644] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:8:0)
[17:00:47.644] Timer tick, asking Buildbucket for the build status
[17:00:47.904] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:47.904] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:9:0) after 1m0s
[17:01:48.100] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:9:0)
[17:01:48.100] Timer tick, asking Buildbucket for the build status
[17:01:48.163] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:48.163] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:10:0) after 1m0s
[17:02:48.469] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:10:0)
[17:02:48.469] Timer tick, asking Buildbucket for the build status
[17:02:48.557] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:48.557] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:11:0) after 1m0s
[17:03:48.717] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:11:0)
[17:03:48.717] Timer tick, asking Buildbucket for the build status
[17:03:48.796] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:48.796] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:12:0) after 1m0s
[17:04:48.966] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:12:0)
[17:04:48.966] Timer tick, asking Buildbucket for the build status
[17:04:49.230] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:49.230] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:13:0) after 1m0s
[17:05:49.283] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:13:0)
[17:05:49.283] Timer tick, asking Buildbucket for the build status
[17:05:49.615] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:49.615] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:14:0) after 1m0s
[17:06:49.822] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:14:0)
[17:06:49.822] Timer tick, asking Buildbucket for the build status
[17:06:49.878] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:49.878] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:15:0) after 1m0s
[17:07:50.018] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:15:0)
[17:07:50.018] Timer tick, asking Buildbucket for the build status
[17:07:50.130] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:50.130] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:16:0) after 1m0s
[17:08:50.281] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:16:0)
[17:08:50.281] Timer tick, asking Buildbucket for the build status
[17:08:50.478] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:50.478] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:17:0) after 1m0s
[17:09:50.595] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:17:0)
[17:09:50.595] Timer tick, asking Buildbucket for the build status
[17:09:50.696] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:50.696] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:18:0) after 1m0s
[17:10:50.806] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:18:0)
[17:10:50.806] Timer tick, asking Buildbucket for the build status
[17:10:50.886] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:50.886] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:19:0) after 1m0s
[17:11:50.998] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:19:0)
[17:11:50.998] Timer tick, asking Buildbucket for the build status
[17:11:51.125] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:51.125] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:20:0) after 1m0s
[17:12:51.241] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:20:0)
[17:12:51.241] Timer tick, asking Buildbucket for the build status
[17:12:51.367] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:51.367] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:21:0) after 1m0s
[17:13:51.628] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:21:0)
[17:13:51.628] Timer tick, asking Buildbucket for the build status
[17:13:51.731] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:51.732] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:22:0) after 1m0s
[17:14:51.853] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:22:0)
[17:14:51.853] Timer tick, asking Buildbucket for the build status
[17:14:52.130] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:52.130] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:23:0) after 1m0s
[17:15:52.254] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:23:0)
[17:15:52.254] Timer tick, asking Buildbucket for the build status
[17:15:52.574] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:52.574] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:24:0) after 1m0s
[17:16:52.661] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:24:0)
[17:16:52.661] Timer tick, asking Buildbucket for the build status
[17:16:52.727] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:52.727] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:25:0) after 1m0s
[17:17:52.831] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:25:0)
[17:17:52.831] Timer tick, asking Buildbucket for the build status
[17:17:52.906] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:52.906] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:26:0) after 1m0s
[17:18:53.180] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:26:0)
[17:18:53.180] Timer tick, asking Buildbucket for the build status
[17:18:53.412] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:53.412] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:27:0) after 1m0s
[17:19:53.590] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:27:0)
[17:19:53.590] Timer tick, asking Buildbucket for the build status
[17:19:53.793] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:53.793] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:28:0) after 1m0s
[17:20:53.939] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:28:0)
[17:20:53.939] Timer tick, asking Buildbucket for the build status
[17:20:54.124] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:54.124] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:29:0) after 1m0s
[17:21:54.255] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:29:0)
[17:21:54.255] Timer tick, asking Buildbucket for the build status
[17:21:54.509] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:54.509] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:30:0) after 1m0s
[17:22:54.773] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:30:0)
[17:22:54.773] Timer tick, asking Buildbucket for the build status
[17:22:54.875] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:54.875] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:31:0) after 1m0s
[17:23:54.982] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:31:0)
[17:23:54.982] Timer tick, asking Buildbucket for the build status
[17:23:55.143] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:55.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:32:0) after 1m0s
[17:24:55.342] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:32:0)
[17:24:55.342] Timer tick, asking Buildbucket for the build status
[17:24:55.467] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:55.467] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:33:0) after 1m0s
[17:25:55.619] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:33:0)
[17:25:55.619] Timer tick, asking Buildbucket for the build status
[17:25:55.687] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:55.687] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:34:0) after 1m0s
[17:26:55.819] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:34:0)
[17:26:55.819] Timer tick, asking Buildbucket for the build status
[17:26:55.892] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:55.892] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:35:0) after 1m0s
[17:27:56.026] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:35:0)
[17:27:56.026] Timer tick, asking Buildbucket for the build status
[17:27:56.100] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:56.100] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:36:0) after 1m0s
[17:28:56.226] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:36:0)
[17:28:56.226] Timer tick, asking Buildbucket for the build status
[17:28:56.279] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:56.279] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:37:0) after 1m0s
[17:29:56.399] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:37:0)
[17:29:56.399] Timer tick, asking Buildbucket for the build status
[17:29:56.818] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:56.818] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:38:0) after 1m0s
[17:30:56.925] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:38:0)
[17:30:56.925] Timer tick, asking Buildbucket for the build status
[17:30:57.152] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:57.152] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:39:0) after 1m0s
[17:31:57.219] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:39:0)
[17:31:57.219] Timer tick, asking Buildbucket for the build status
[17:31:57.328] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:57.328] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:40:0) after 1m0s
[17:32:57.377] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:40:0)
[17:32:57.377] Timer tick, asking Buildbucket for the build status
[17:32:57.650] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:57.650] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:41:0) after 1m0s
[17:33:57.650] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:41:0)
[17:33:57.650] Timer tick, asking Buildbucket for the build status
[17:33:57.748] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:57.748] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:42:0) after 1m0s
[17:34:57.766] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:42:0)
[17:34:57.766] Timer tick, asking Buildbucket for the build status
[17:34:58.023] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:58.023] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:43:0) after 1m0s
[17:35:58.043] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:43:0)
[17:35:58.043] Timer tick, asking Buildbucket for the build status
[17:35:58.144] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:58.144] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:44:0) after 1m0s
[17:36:58.147] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:44:0)
[17:36:58.147] Timer tick, asking Buildbucket for the build status
[17:36:58.221] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:58.221] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:45:0) after 1m0s
[17:37:58.413] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:45:0)
[17:37:58.413] Timer tick, asking Buildbucket for the build status
[17:37:58.467] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:58.467] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:46:0) after 1m0s
[17:38:58.547] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:46:0)
[17:38:58.547] Timer tick, asking Buildbucket for the build status
[17:38:58.897] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:58.897] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:47:0) after 1m0s
[17:39:58.943] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:47:0)
[17:39:58.943] Timer tick, asking Buildbucket for the build status
[17:39:59.122] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:59.122] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:48:0) after 1m0s
[17:40:59.220] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:48:0)
[17:40:59.220] Timer tick, asking Buildbucket for the build status
[17:40:59.280] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:59.280] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:49:0) after 1m0s
[17:41:59.398] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:49:0)
[17:41:59.398] Timer tick, asking Buildbucket for the build status
[17:41:59.514] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:59.514] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:50:0) after 1m0s
[17:42:59.663] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:50:0)
[17:42:59.663] Timer tick, asking Buildbucket for the build status
[17:43:00.298] Build 8856209285754482688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:00.298] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021639347857301232:51:0) after 1m0s
[17:43:47.240] Received PubSub notification, asking Buildbucket for the build status
[17:43:47.638] Build 8856209285754482688: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:43:47.638] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612460626023775",
"created_by": "project:chromium",
"created_ts": "1612457685665874",
"id": "8856209285754482688",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"df4efece97f69cc22c3018015ae753373d250379\"}}",
"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\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"df4efece97f69cc22c3018015ae753373d250379\", \"project\": \"chromium/src\"}], \"$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-174-h5w3\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 180275, \"got_angle_revision\": \"ad985fcdd806a26343b9b5f8408e551b515493e5\", \"got_dawn_revision\": \"d5a0728b67c8328edaed3d9cfee7ee44fcb893e8\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"df4efece97f69cc22c3018015ae753373d250379\", \"got_revision_cp\": \"refs/heads/master@{#850613}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"14dcbed947ce755af1fcbfb3ba8e643b4e769860\", \"got_v8_revision\": \"fbfec9c75680031d0e58a22ea4471f2375fe25be\", \"got_v8_revision_cp\": \"refs/heads/9.0.130@{#1}\", \"got_webrtc_revision\": \"1d71fd9c61db8f1dcf30b5d0625e514b91200926\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33155}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.linux:linux-clang-tidy-dbg\", \"tryserver.chromium.linux:linux_chromium_dbg_ng\", \"tryserver.chromium.linux:linux_chromium_compile_dbg_ng\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"df4efece97f69cc22c3018015ae753373d250379\", \"swarm_hashes_refs/heads/master(at){#850613}_without_patch\": {\"absl_hardening_tests\": \"e74652223da3bb512bb5bb71bea11765ab89a776\", \"accessibility_unittests\": \"4146f5998c2a5fbe0eedb1a7863e5d60e74773f0\", \"angle_unittests\": \"80c1c3db5c8c26d5d3c1ee076d6bb00db435e597\", \"app_shell_unittests\": \"2d9b594f5418196f8424c8e4d5373af379126e57\", \"aura_unittests\": \"6766f23e85670944eb7de4475be760e6b7905911\", \"base_unittests\": \"09b24a22ea567b790421f70fb0610c7c2b720294\", \"base_util_unittests\": \"72acf5303a996658b5932d2be3ed46746101fe43\", \"blink_common_unittests\": \"5463784f25b274103e3a867b41a88bedeb2ad440\", \"blink_fuzzer_unittests\": \"239faa4c7088ec4ee292ed4550b0bf9d3cecaa16\", \"blink_heap_unittests\": \"656760d9e0ff571f10a63d751e9cf0e2eed48d0a\", \"blink_platform_unittests\": \"cba32ba9b8db97c6163395d0fb48e0a0a20a302c\", \"blink_python_tests\": \"0542aa4aa1c38adb3544c4dd4af7f63ed3f72ee9\", \"blink_unittests\": \"d2393efaf6032422ff52655fdb7699fdb2de99fe\", \"blink_web_tests\": \"dcbf7049a10f50605e89204e9c3be683964a46d6\", \"boringssl_crypto_tests\": \"10724f75486b36658142790f0f366da9fa89420f\", \"boringssl_ssl_tests\": \"06d23954664909338b29bc225643aac1a6cbb727\", \"browser_tests\": \"edad755ece93c68769bd83d6def6d0b83e602fca\", \"capture_unittests\": \"458c0eb6024ad578845dc342ee472858c349bf4f\", \"cast_unittests\": \"4c259bd59c89f5918bc4ffec4bfbbf30a0d60ecf\", \"cc_unittests\": \"e19955b10254fc9b935a6c6828108babb905ab60\", \"chrome_app_unittests\": \"dbf33f37979a8540349ecd49b06671b1b5b8265a\", \"chromedriver_unittests\": \"5536e1bd336eb8424e4ea3b8a74bdc8e12486152\", \"components_browsertests\": \"da65055e97d7509afc73cc632ba52aad9700ca52\", \"components_unittests\": \"6fdca1cba5ee18b017cf06204effa3428d9819a2\", \"compositor_unittests\": \"dce121150998742ce3d24364b56ddeeddae39461\", \"content_browsertests\": \"2fe12447503056943ac807b1bbf78254cab67c07\", \"content_shell_crash_test\": \"47f2cdaa56e13c25cdd0005b2a5b1a7f0c30d66e\", \"content_unittests\": \"b1bcf7bfb4630befe7c45c8b0bd8cd6e17c9ad22\", \"crashpad_tests\": \"5d924583192e65deba23405f58b81b427d1b38ed\", \"cronet_tests\": \"f01c2c5903f742c4ac2edcb01ed73e2a6732b4b3\", \"cronet_unittests\": \"f25d180ba6e65976d85dd5faa525915c0a655508\", \"crypto_unittests\": \"13532adcf5e0780c5947709b760bc28a928e34eb\", \"dbus_unittests\": \"4f394bdfc55ea8d761dc9fe4d2d6fba93ad73d77\", \"device_unittests\": \"0217392fa7e77fd74f253d4bce88726aa14f6673\", \"display_unittests\": \"526620b6ec395c08735a1cd27fa12ff9324ca701\", \"events_unittests\": \"c3078e16b24f0b02dfe71006a2fcce537e50226d\", \"extensions_browsertests\": \"f200f4260b8aa45e9c9151a2e083a1c7df5bc76f\", \"extensions_unittests\": \"ca922fb86c5f28d7b9a44356be56ab29a879bcbc\", \"filesystem_service_unittests\": \"8c732fad64980c339d5298409a823e45fa3b7f07\", \"flatbuffers_unittests\": \"abe7737f42a55ed672142d98ab3b938d8384c781\", \"gcm_unit_tests\": \"1b101ea91c9b0f74e320ad6a235d42c3c379dab7\", \"gfx_unittests\": \"4b8fa13920d18f1e6d15cc36c51aa89038bd684a\", \"gin_unittests\": \"dcb8df8a1871db478fde78a4d912be4e9fb756a0\", \"google_apis_unittests\": \"9f705f56e10a64daa344272aab7fe03e2b730fdd\", \"gpu_unittests\": \"6d3fb9610018cd2d51e8755f53e7cf9f06c3fe0b\", \"grit_python_unittests\": \"09d11eaebf65aefde13fcafc2a12fe42d6476e6b\", \"gwp_asan_unittests\": \"b231c760b98b1368c5326e88c17d8de1b1bbaa5b\", \"headless_browsertests\": \"3af20fee95d7b0a614ef1eea2d1214d5a720660c\", \"headless_unittests\": \"d8771981e6b614fbc73106abbe92ec5c97cd8a02\", \"interactive_ui_tests\": \"528a85a6136a76542391a06573a2c69d40e93ecd\", \"ipc_tests\": \"226a2b77fce62d2c163d10128ef37d7a0b147449\", \"jingle_unittests\": \"283a031f534d62bb124dfd8d86ba9e9f4417d100\", \"latency_unittests\": \"b882510ee69955f3f9f76bffdb75f5c1e282777a\", \"libjingle_xmpp_unittests\": \"e361eac8b733de2b4582b55af31d0793c2036d8d\", \"liburlpattern_unittests\": \"84d70b4b3433ea067ea394f22d5765bd164a895b\", \"media_blink_unittests\": \"ca4d02bd1e9e5e2095a72c9fb3d90a31dc8df838\", \"media_unittests\": \"5cd00c1bf056d981940b318866597f0646c6718b\", \"message_center_unittests\": \"04f8f56bd24b7891e88444d651921485ab71d653\", \"metrics_python_tests\": \"494e624294b805f5c7a0a99baf9266114a378ba1\", \"midi_unittests\": \"a033e3ba5881a8a844211cebaa8b511b4d965357\", \"mojo_core_unittests\": \"15ae68ee3234a2b3fa7592f85007760d8d1dc2b1\", \"mojo_unittests\": \"079fb5d923ee1a774b7fe882f09bf866daab8c47\", \"nacl_helper_nonsfi_unittests\": \"0aa09d10a698c8ef0039761b6b2e0925d400ccb2\", \"nacl_loader_unittests\": \"c2055a1cead2ec3c99f674f686bb4439ef635f2f\", \"native_theme_unittests\": \"0640d1f322676da08043d3af34ab9aa87eb4cc1d\", \"net_unittests\": \"dcc3a182d03800ab4ccae240bf97df501e43fa73\", \"openscreen_unittests\": \"e20c07f271618b4a37d54f021a17493a75a4f8a5\", \"pdf_unittests\": \"4d5c7f1c1f9007f33d338e4d4780aa76f56a7437\", \"perfetto_unittests\": \"ff47e71366679eef42af8ba1119bc50332b5083a\", \"ppapi_unittests\": \"5c4d282250ce0cb8a728577c100bcb03014128a8\", \"printing_unittests\": \"cba703d7d69506ab494c7e38bc8878c134101c41\", \"remoting_unittests\": \"057ac4c9083d05f641a8e9c6bc0a02c71854fbd7\", \"sandbox_linux_unittests\": \"04fe0a60907c7686e27a9415cdef53e20ded92ea\", \"service_manager_unittests\": \"d82217f41ee66f77ab0a342d881f77a49b72873e\", \"services_unittests\": \"82fe718ba89a8b8cf16397c3ee746b0aecfa4a29\", \"shell_dialogs_unittests\": \"0efdd52deccfa1aa8f4463f0f7581e9c871f3ab8\", \"skia_unittests\": \"e4c7a8841d86b7c48ae452ac60e2797cacc2db90\", \"snapshot_unittests\": \"423031b5d6d5a5bef8afb06af3bf27b7c5c3625f\", \"sql_unittests\": \"8324cdea70230116867d9e0fead7f44602287e00\", \"storage_unittests\": \"4824d36d1c3a35f9c2402edd3b6052dfc69578aa\", \"sync_integration_tests\": \"5a8c3379c5f829e29a054b7cf7ab82fd65db8d8b\", \"telemetry_gpu_unittests\": \"8c4fff99f2072e6f006965ab477169e37e721332\", \"telemetry_perf_unittests\": \"0d9f2c0580aac4e625ad87a7a40cf24ea51cb839\", \"telemetry_unittests\": \"9a7cd993a30cb56df42369478153d4afa92fa268\", \"traffic_annotation_auditor_unittests\": \"9bd1b9cebd81130c882cf99bfdb6042afea0ab23\", \"ui_base_unittests\": \"768d7ef7b2414b864a7fcfe991639d7ef3c9fbf3\", \"ui_touch_selection_unittests\": \"06078dbe1e4334836ca5d39831a1d4fa8dff8632\", \"unit_tests\": \"3c572be79819449dac9e073193908234a6aa61fd\", \"url_unittests\": \"3f8bf5029a0ed905f39feb7768655db2f00b4ce9\", \"views_perftests\": \"939bea0ab8b2aa7153534db19f49620d91e01ce9\", \"views_unittests\": \"015a34a0ea186a3a9c195ede3a0b0c64de22aa2d\", \"viz_unittests\": \"77b3a54e3f280eaed377cde83abddfb4f8184a2e\", \"vr_common_unittests\": \"19c3388af53a4065c4faf22c9004d4f7bb460b07\", \"vr_pixeltests\": \"f6e9a7232a7a12f347a3befeb0f808e516c6c4e8\", \"webdriver_wpt_tests\": \"3946d0811e0014e06da3ed8e68c8fafe04f65369\", \"weblayer_browsertests\": \"4283e68ab07123788f908efc026e3b66e04a9170\", \"weblayer_unittests\": \"0e049da356ab73e02399911ad4e2e332d3cf340c\", \"wm_unittests\": \"50b0c17a26da1b749b07bf02fb205a4ca8da0771\", \"wtf_unittests\": \"5b358cbc13c25daa9138bb68fcd2d68dcb58a58d\", \"x11_unittests\": \"f41a7414e709e51d85e7fd856b81fae8bf7ad4dd\", \"xr_browser_tests\": \"cc23bc081c8237563ccb496b4dd68a25ec8b39f1\", \"zlib_unittests\": \"0fd50b0f500724c6ccf4937ac81b8f16d2a1a9f9\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_9ab392df551d6d5935f78898dbc2168b32a5d8764371ea104d9b75846e7336d7_v2\", \"builder_a72d480ca48f57bfd3a2a0028361f62534d8cf527dbb841fd2259fc00e36dedb_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"builder_ea4e7feff896b82ff6619a3b9c5371d5e74ebdd508befd295e2f5ab02f5eefaf_v2\", \"builder_eb9e31050ddbebce4ba6cbaea9a0445903ee5f3e90bdb319e3517a7851b1b7f7_v2\", \"builder_f950d00180feebb913a151f4148df44a669def32bdb4d2b9a63ead64ebacc911_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-174-h5w3\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5676-c76598c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612457688843647",
"status": "COMPLETED",
"status_changed_ts": "1612460626824351",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180275",
"builder:Linux Builder (dbg)",
"buildset:commit/git/df4efece97f69cc22c3018015ae753373d250379",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/df4efece97f69cc22c3018015ae753373d250379",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021639347857301232",
"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/8856209285754482688/+/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:5186c93d33e98510",
"user_agent:luci-scheduler"
],
"updated_ts": "1612460626839693",
"url": "https://ci.chromium.org/b/8856209285754482688",
"utcnow_ts": "1612460627624389"
}
}
[17:43:47.638] Invocation finished in 49m3.362232304s with status SUCCEEDED