[21:06:36.202] New invocation is queued and will start shortly
[21:06:37.257] Starting the invocation (attempt 1)
[21:06:37.302] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:06:37.302] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:06:37.302] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9058768258094738080",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ba6ae1233ea67aaaa514bb48eae39dc5cb945a63\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9058768258094738080",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/ba6ae1233ea67aaaa514bb48eae39dc5cb945a63",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ba6ae1233ea67aaaa514bb48eae39dc5cb945a63",
"gitiles_ref:refs/heads/master"
]
}
[21:06:37.940] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1577048797417754",
"id": "8893338196158392096",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ba6ae1233ea67aaaa514bb48eae39dc5cb945a63\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1577048797808330",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166445",
"builder:Linux Builder (dbg)",
"buildset:commit/git/ba6ae1233ea67aaaa514bb48eae39dc5cb945a63",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ba6ae1233ea67aaaa514bb48eae39dc5cb945a63",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9058768258094738080",
"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/8893338196158392096/+/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": "1577048797808436",
"url": "https://ci.chromium.org/b/8893338196158392096",
"utcnow_ts": "1577048797929857"
}
}
[21:06:37.940] Task URL: https://ci.chromium.org/b/8893338196158392096
[21:06:37.940] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:2:0) after 1m0s
[21:07:37.957] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:2:0)
[21:07:37.957] Timer tick, asking Buildbucket for the build status
[21:07:38.153] Build 8893338196158392096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:07:38.153] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:3:0) after 1m0s
[21:08:38.179] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:3:0)
[21:08:38.179] Timer tick, asking Buildbucket for the build status
[21:08:38.388] Build 8893338196158392096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:08:38.388] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:4:0) after 1m0s
[21:09:38.654] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:4:0)
[21:09:38.654] Timer tick, asking Buildbucket for the build status
[21:09:39.538] Build 8893338196158392096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:09:39.538] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:5:0) after 1m0s
[21:10:39.509] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:5:0)
[21:10:39.509] Timer tick, asking Buildbucket for the build status
[21:10:39.762] Build 8893338196158392096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:10:39.762] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:6:0) after 1m0s
[21:11:05.257] Received PubSub notification, asking Buildbucket for the build status
[21:11:05.420] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:40.024] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:6:0)
[21:11:40.024] Timer tick, asking Buildbucket for the build status
[21:11:40.309] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:40.309] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:8:0) after 1m0s
[21:12:40.525] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:8:0)
[21:12:40.525] Timer tick, asking Buildbucket for the build status
[21:12:40.678] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:40.678] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:9:0) after 1m0s
[21:13:41.167] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:9:0)
[21:13:41.167] Timer tick, asking Buildbucket for the build status
[21:13:41.687] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:41.687] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:10:0) after 1m0s
[21:14:41.681] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:10:0)
[21:14:41.681] Timer tick, asking Buildbucket for the build status
[21:14:42.035] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:42.035] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:11:0) after 1m0s
[21:15:42.397] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:11:0)
[21:15:42.397] Timer tick, asking Buildbucket for the build status
[21:15:42.707] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:42.707] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:12:0) after 1m0s
[21:16:42.858] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:12:0)
[21:16:42.858] Timer tick, asking Buildbucket for the build status
[21:16:42.994] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:42.994] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:13:0) after 1m0s
[21:17:43.226] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:13:0)
[21:17:43.226] Timer tick, asking Buildbucket for the build status
[21:17:43.358] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:43.358] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:14:0) after 1m0s
[21:18:43.551] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:14:0)
[21:18:43.551] Timer tick, asking Buildbucket for the build status
[21:18:43.733] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:43.733] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:15:0) after 1m0s
[21:19:43.883] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:15:0)
[21:19:43.883] Timer tick, asking Buildbucket for the build status
[21:19:44.383] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:44.383] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:16:0) after 1m0s
[21:20:44.468] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:16:0)
[21:20:44.468] Timer tick, asking Buildbucket for the build status
[21:20:44.611] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:44.611] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:17:0) after 1m0s
[21:21:44.775] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:17:0)
[21:21:44.775] Timer tick, asking Buildbucket for the build status
[21:21:44.945] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:44.945] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:18:0) after 1m0s
[21:22:44.968] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:18:0)
[21:22:44.968] Timer tick, asking Buildbucket for the build status
[21:22:45.241] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:45.241] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:19:0) after 1m0s
[21:23:45.298] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:19:0)
[21:23:45.298] Timer tick, asking Buildbucket for the build status
[21:23:45.466] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:45.466] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:20:0) after 1m0s
[21:24:45.494] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:20:0)
[21:24:45.494] Timer tick, asking Buildbucket for the build status
[21:24:45.635] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:45.635] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:21:0) after 1m0s
[21:25:45.717] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:21:0)
[21:25:45.717] Timer tick, asking Buildbucket for the build status
[21:25:45.966] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:45.966] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:22:0) after 1m0s
[21:26:45.984] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:22:0)
[21:26:45.984] Timer tick, asking Buildbucket for the build status
[21:26:46.239] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:46.239] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:23:0) after 1m0s
[21:27:46.273] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:23:0)
[21:27:46.273] Timer tick, asking Buildbucket for the build status
[21:27:46.861] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:46.861] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:24:0) after 1m0s
[21:28:46.921] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:24:0)
[21:28:46.921] Timer tick, asking Buildbucket for the build status
[21:28:47.205] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:47.205] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:25:0) after 1m0s
[21:29:47.428] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:25:0)
[21:29:47.428] Timer tick, asking Buildbucket for the build status
[21:29:47.640] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:47.640] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:26:0) after 1m0s
[21:30:47.663] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:26:0)
[21:30:47.663] Timer tick, asking Buildbucket for the build status
[21:30:48.095] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:48.095] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:27:0) after 1m0s
[21:31:48.107] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:27:0)
[21:31:48.107] Timer tick, asking Buildbucket for the build status
[21:31:49.646] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:49.646] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:28:0) after 1m0s
[21:32:49.665] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:28:0)
[21:32:49.665] Timer tick, asking Buildbucket for the build status
[21:32:49.979] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:49.979] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:29:0) after 1m0s
[21:33:50.009] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:29:0)
[21:33:50.009] Timer tick, asking Buildbucket for the build status
[21:33:50.518] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:50.518] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:30:0) after 1m0s
[21:34:50.557] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:30:0)
[21:34:50.557] Timer tick, asking Buildbucket for the build status
[21:34:51.495] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:51.495] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:31:0) after 1m0s
[21:35:51.519] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:31:0)
[21:35:51.519] Timer tick, asking Buildbucket for the build status
[21:35:52.476] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:52.476] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:32:0) after 1m0s
[21:36:52.515] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:32:0)
[21:36:52.515] Timer tick, asking Buildbucket for the build status
[21:36:52.749] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:52.749] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:33:0) after 1m0s
[21:37:52.820] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:33:0)
[21:37:52.820] Timer tick, asking Buildbucket for the build status
[21:37:53.533] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:53.533] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:34:0) after 1m0s
[21:38:53.553] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:34:0)
[21:38:53.553] Timer tick, asking Buildbucket for the build status
[21:38:54.996] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:54.996] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:35:0) after 1m0s
[21:39:55.131] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:35:0)
[21:39:55.131] Timer tick, asking Buildbucket for the build status
[21:39:55.305] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:55.305] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:36:0) after 1m0s
[21:40:55.372] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:36:0)
[21:40:55.372] Timer tick, asking Buildbucket for the build status
[21:40:55.555] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:55.555] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:37:0) after 1m0s
[21:41:55.658] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:37:0)
[21:41:55.658] Timer tick, asking Buildbucket for the build status
[21:41:55.922] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:55.922] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:38:0) after 1m0s
[21:42:55.986] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:38:0)
[21:42:55.986] Timer tick, asking Buildbucket for the build status
[21:42:56.318] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:56.318] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:39:0) after 1m0s
[21:43:56.394] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:39:0)
[21:43:56.394] Timer tick, asking Buildbucket for the build status
[21:43:56.533] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:56.533] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:40:0) after 1m0s
[21:44:56.565] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:40:0)
[21:44:56.565] Timer tick, asking Buildbucket for the build status
[21:44:56.740] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:56.740] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:41:0) after 1m0s
[21:45:56.733] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:41:0)
[21:45:56.733] Timer tick, asking Buildbucket for the build status
[21:45:57.550] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:57.550] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:42:0) after 1m0s
[21:46:57.534] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:42:0)
[21:46:57.534] Timer tick, asking Buildbucket for the build status
[21:46:57.893] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:57.893] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:43:0) after 1m0s
[21:47:57.975] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:43:0)
[21:47:57.975] Timer tick, asking Buildbucket for the build status
[21:48:00.143] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:00.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:44:0) after 1m0s
[21:49:00.167] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:44:0)
[21:49:00.167] Timer tick, asking Buildbucket for the build status
[21:49:00.692] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:00.692] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:45:0) after 1m0s
[21:50:00.780] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:45:0)
[21:50:00.780] Timer tick, asking Buildbucket for the build status
[21:50:01.395] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:01.395] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:46:0) after 1m0s
[21:51:01.466] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:46:0)
[21:51:01.466] Timer tick, asking Buildbucket for the build status
[21:51:01.680] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:01.680] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:47:0) after 1m0s
[21:52:01.699] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:47:0)
[21:52:01.699] Timer tick, asking Buildbucket for the build status
[21:52:02.021] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:02.022] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:48:0) after 1m0s
[21:53:02.084] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:48:0)
[21:53:02.084] Timer tick, asking Buildbucket for the build status
[21:53:02.791] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:02.791] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:49:0) after 1m0s
[21:54:02.866] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:49:0)
[21:54:02.866] Timer tick, asking Buildbucket for the build status
[21:54:03.092] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:03.092] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:50:0) after 1m0s
[21:55:03.166] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:50:0)
[21:55:03.166] Timer tick, asking Buildbucket for the build status
[21:55:03.314] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:03.314] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:51:0) after 1m0s
[21:56:03.378] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:51:0)
[21:56:03.378] Timer tick, asking Buildbucket for the build status
[21:56:03.608] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:03.608] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:52:0) after 1m0s
[21:57:03.640] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:52:0)
[21:57:03.640] Timer tick, asking Buildbucket for the build status
[21:57:03.767] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:03.767] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:53:0) after 1m0s
[21:58:03.836] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:53:0)
[21:58:03.836] Timer tick, asking Buildbucket for the build status
[21:58:04.028] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:04.028] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:54:0) after 1m0s
[21:59:04.053] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:54:0)
[21:59:04.053] Timer tick, asking Buildbucket for the build status
[21:59:04.260] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:04.260] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:55:0) after 1m0s
[22:00:04.365] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:55:0)
[22:00:04.365] Timer tick, asking Buildbucket for the build status
[22:00:04.807] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:04.807] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:56:0) after 1m0s
[22:01:05.175] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:56:0)
[22:01:05.175] Timer tick, asking Buildbucket for the build status
[22:01:05.757] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:05.757] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:57:0) after 1m0s
[22:02:05.992] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:57:0)
[22:02:05.992] Timer tick, asking Buildbucket for the build status
[22:02:06.205] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:06.205] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:58:0) after 1m0s
[22:03:06.345] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:58:0)
[22:03:06.345] Timer tick, asking Buildbucket for the build status
[22:03:06.577] Build 8893338196158392096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:06.577] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058768258094738080:59:0) after 1m0s
[22:03:24.958] Received PubSub notification, asking Buildbucket for the build status
[22:03:25.126] Build 8893338196158392096: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:03:25.127] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1577052204255325",
"created_by": "project:chromium",
"created_ts": "1577048797417754",
"id": "8893338196158392096",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ba6ae1233ea67aaaa514bb48eae39dc5cb945a63\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"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-51-8qdz\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 166445, \"got_angle_revision\": \"07d3bcf1c443bc3dc1d3d47d9daf20475a74771d\", \"got_dawn_revision\": \"87d31381588b26edb3da1d75dd0588b2df4dd968\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"ba6ae1233ea67aaaa514bb48eae39dc5cb945a63\", \"got_revision_cp\": \"refs/heads/master@{#727100}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"6e3a387c01598240a7a8722ab8cbd1015ec1cd0a\", \"got_v8_revision\": \"3b4586216b8e96a98bd46c76068caadd744956d0\", \"got_v8_revision_cp\": \"refs/heads/8.1.69@{#1}\", \"got_webrtc_revision\": \"23df143850f9b6757de71805ce53d738d57f72c6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30119}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ba6ae1233ea67aaaa514bb48eae39dc5cb945a63\", \"swarm_hashes_refs/heads/master(at){#727100}_without_patch\": {\"accessibility_unittests\": \"d8cbe7f9953dc553dcddff286035b89fa49b457a\", \"angle_unittests\": \"f1df4369ca18cbdb9a812a9913a2cfac3893b25e\", \"app_shell_unittests\": \"95a68ae125dd903bf8aabf6834ae07f5b01cc9e0\", \"aura_unittests\": \"f91ffbf08df180e69299493d0f711670fb02ee07\", \"base_unittests\": \"d6744a2a52c2f916851c537e2e6fd2267adae385\", \"base_util_unittests\": \"0df9370fa5d798ea032a6eba290a8aa66632aff7\", \"blink_common_unittests\": \"c16f7c61a705f062f382efb4a3a1285643290092\", \"blink_fuzzer_unittests\": \"38a521a0aebb3b89d29f511b97b418cee6fab7f0\", \"blink_heap_unittests\": \"72efe025b9d0beac2b5094c8214f38600a3aedaa\", \"blink_platform_unittests\": \"9781e1e16b6d25d490c6c70d608ad6ddbae32807\", \"blink_python_tests\": \"e0734db32b9dd4beb1f660a8d14436ce1358cc4e\", \"blink_unittests\": \"a8b086ff229c4cfc0ce82efd434a491fb798c59f\", \"blink_web_tests\": \"be205217406fab81f2ffbf65ce6d628f165760c0\", \"boringssl_crypto_tests\": \"d5ee27370b765d1b8841eec2c897223ba713a9fd\", \"boringssl_ssl_tests\": \"9068614d6371f901eca99c2e47a55c53cfe48889\", \"browser_tests\": \"fd615008d2fa079cd5d34776652b20392806aefc\", \"cacheinvalidation_unittests\": \"2824919316cba1f74f15bb797bbaa86aedd30e7a\", \"capture_unittests\": \"45c339185432e7f5694c3cd4025fd482362e6cc2\", \"cast_unittests\": \"56a0cb6d360b115d365ce7a6db017dae11271291\", \"cc_unittests\": \"b95bcb5eb8fa674a4a0f500c9f61d0fe19cbabd3\", \"chrome_app_unittests\": \"8cfe5bb166b504f5f4d0099cf3476ca36349a65a\", \"chromedriver_unittests\": \"d032f892086dfd09c6659070f0ac8ead8af9de6c\", \"components_browsertests\": \"d5be9e72f33753bdcca863c438ae42d2f5078f4b\", \"components_unittests\": \"41e0b22fd801a6d4d1be4a28fb63cc8a52540f22\", \"compositor_unittests\": \"8fe954e3e099281867885327a63033a19bc7133c\", \"content_browsertests\": \"8c346fa9953aa03b09a257aac0a0b90346ddc231\", \"content_shell_crash_test\": \"40d13a835700e05c307e0dc1eef4b721b890c1eb\", \"content_unittests\": \"723fe0a926e58741acfdcf6417ede28fa5e171f8\", \"crashpad_tests\": \"ccc9f4fdaf39f3524daf060f179397eb9218e474\", \"cronet_tests\": \"dda2436dddc4cb45d24732763b22c30df96c50ec\", \"cronet_unittests\": \"e20595d160efd0eec75da5f2376cf30ff5c43010\", \"crypto_unittests\": \"a65ec25a716e7b0fe81869a60746741c8cd66140\", \"dbus_unittests\": \"f0018d7e01d6ecb1125ab810953758e99e9ca795\", \"device_unittests\": \"a2ed3883e0d8c0e4faeca705e6b5b741d53bbc14\", \"devtools_lint_check\": \"f5ef4bbd5230ceca8e6b62aa06ad4d93f07aaee9\", \"devtools_type_check\": \"0560d4eae584ad288c2a1900ea29c456fa9b4b3b\", \"display_unittests\": \"7ed5199f0bfd5ad811cc22e8736f17c57901376a\", \"events_unittests\": \"c0cfece8daa3aa2090a2c7e841e12fd491d53c38\", \"extensions_browsertests\": \"142ca606cd2ea36b6a197da6905ae3c9a250c09f\", \"extensions_unittests\": \"2ea37a881de4def12c56418ae8ac854835d8080f\", \"filesystem_service_unittests\": \"a28529b2e86cd431200a8c1e1314c3c909facb67\", \"flatbuffers_unittests\": \"35d92a748edf43f648bd80c0c655e609f5a52216\", \"gcm_unit_tests\": \"38122a9134639593ef6fc4da7bad109ae369f6fb\", \"gfx_unittests\": \"31b034c03f37451957008ae9fecba03fbda011df\", \"gin_unittests\": \"cf17b45102e51ea4f3de385b8eb1df55ace092ed\", \"google_apis_unittests\": \"21e048ebef549aa68239ae4d52805500f702e966\", \"gpu_unittests\": \"d760cdc62076726fdf1f66222461229a5cdf9984\", \"grit_python_unittests\": \"25dff9ef6327c596609d376409bc1d0e605b6d43\", \"gwp_asan_unittests\": \"62869b0741371065633b5476d2a7c20c9c9b4b1f\", \"headless_browsertests\": \"926331b634e5304cdec7e6753af3f055677d37c8\", \"headless_unittests\": \"77ea08324d1a47f1dbdc12bd9e79a688969cabc8\", \"interactive_ui_tests\": \"96640fb06a787669385449bb2d50daaa6169ed9e\", \"ipc_tests\": \"86100a576bad15ba5304d626fb7c175ba924065e\", \"jingle_unittests\": \"d222e267a6c70b487fbd243e08328c3765c07343\", \"latency_unittests\": \"628ea47c9dc14a82e3eae1dded8820228bf56170\", \"libjingle_xmpp_unittests\": \"04261cffa7c1417d0a42853d0f0d6c834959f566\", \"media_blink_unittests\": \"044eea6da6327a30c63ddd685df98a7701343aeb\", \"media_service_unittests\": \"308f14cadd9012ef06228bd46b1b7b5b1d5cfbde\", \"media_unittests\": \"dba743b4ae0360d71cec95e6f0a20f7ff45425f1\", \"message_center_unittests\": \"57193b12174405ced5449ddf878316275f59a371\", \"metrics_python_tests\": \"f40d7333148bfa645fb17e402f7a02929da6cb90\", \"midi_unittests\": \"e2840d1abca6540c3fcab910fa551cc6fb838095\", \"mojo_core_unittests\": \"970802ed1e72eb3260c0d2b6299d4ed65fc07351\", \"mojo_unittests\": \"51e67d4f0abfd449cfd7bfcbf5554511f59471ed\", \"nacl_helper_nonsfi_unittests\": \"34c7aae2b7d5ec9cde6e85a78b6a0d47d1175759\", \"nacl_loader_unittests\": \"d61522c6e8456310a94ebe064035c9c4ea13a3d4\", \"native_theme_unittests\": \"709d2426f2f915d6e065622c605961e2cd13193b\", \"net_unittests\": \"abef294260f4a211dac2c5f8e373718d933be3bf\", \"pdf_unittests\": \"1da8fe9f3692240c7a98b144cec975b4078f7c76\", \"perfetto_unittests\": \"001418ce632502d37e98b9f34feef3fda617b38e\", \"ppapi_unittests\": \"5db3583489d39afbe1dff2dc4da1c67a65e8148a\", \"printing_unittests\": \"008e7c06da6756eb727725b756803fe72249718c\", \"remoting_unittests\": \"8fd0c48de7309421ba002ff190ffc5b8017be281\", \"sandbox_linux_unittests\": \"e6d8dd3e4d322453357c200fa30892ca1f585d83\", \"service_manager_unittests\": \"e95705b1b37dc183c89d07439f24f4250ec1cc4e\", \"services_unittests\": \"fffa0159379dd992d70614dd66f605ba87fa186a\", \"shell_dialogs_unittests\": \"e1218705595e8d09f314828f2ac396efb7a06438\", \"skia_unittests\": \"1c48792fed339317fd926cd1e04218b8065fccef\", \"snapshot_unittests\": \"52dd318a3cfd48646b2f365fbe5f7923591adf5e\", \"sql_unittests\": \"fe641a557b2f70a9f4bbeb7199e0c2d4a6b064f6\", \"storage_unittests\": \"33a2052516fe566a3606b8d625220af64e2047cf\", \"sync_integration_tests\": \"4b43b70b3de7848e02ee4bc465a07b23e8bdec6b\", \"telemetry_gpu_unittests\": \"740d6a9976a744b6a7851f0186813b6bf18cd7b1\", \"telemetry_perf_unittests\": \"d876710cda062c7fc51ad902aa81c67db026c81c\", \"telemetry_unittests\": \"697b012a48b619f98b78b79e15c0d4f504e47e0f\", \"traffic_annotation_auditor_unittests\": \"8fbf7b544bfe529da387a7d6641ed7c190864d89\", \"ui_base_unittests\": \"993c73b42d76aeed9bbef0073dfe3e4ca48359dc\", \"ui_touch_selection_unittests\": \"225e2cb6c6ab24bb94cdcf4c478b2069400cb44d\", \"unit_tests\": \"504a85b9388a81df491cc7c67e3c10dae9229e88\", \"url_unittests\": \"88820b4759aecbcb2ee19c1115de9f25d023c1c9\", \"views_perftests\": \"fc38fc3d5ec25b0a676ed47b87c1fb088ae0824c\", \"views_unittests\": \"b3f4e2b48948db5951bfb0220d374e821a5ba826\", \"viz_unittests\": \"9c6ad19b3bae858ce6064d25567fc1cccc79b25d\", \"vr_common_unittests\": \"bcd9e063702c17e310259b7e3cf62c51cd2466be\", \"vr_pixeltests\": \"aeccc4dc16375286bd18c5dc07001e2f2538acb0\", \"webdriver_wpt_tests\": \"0187ff452d9125f86822ae9980a77210a697d992\", \"weblayer_browsertests\": \"3c1605fe0ece87d711ef682826e19539f29ad376\", \"wm_unittests\": \"f30d312a856dc14cc389c82d414840d0259a70a2\", \"wtf_unittests\": \"e5eec54b6b7a75dc16eb5a0f376c4b93c5911458\", \"xr_browser_tests\": \"cafc318046c62dfb1e2dfefed67225cb11e132c0\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_28bbe8e6e9c00f2d4cca6bb7518e2e3abcc6dba52c0db172294f96352b49589a_v2\", \"builder_53a0dbabd2cebac1b8e64bcbfab336eb0021d1dc40b2d4ba5321eb0a070d508c_v2\", \"builder_578c225c2c50d5653cce8ddfaab266952737b31d66b6113fbb64cc6f5229aebd_v2\", \"builder_662609c3a18ad850d61ba5f58749e60478bc4d9d576475e0c738a1c9e2d8d890_v2\", \"builder_b9373cfdcc5f43b5e07cc4931fc34027757a424c7183ae2b31ae65ec7718ca12_v2\", \"builder_c4692ee50382bcd35d592bcc0950e009bfc7efa8bba012f560f58cf3ab714e75_v2\", \"builder_f5610824250c57fc28679fbc8606252c3dbaca731fb7f2a4bebf1e0fc7f48a3a_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-51-8qdz\"], \"image\": [\"chrome-xenial-19121500-b03549ec744\"], \"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\": [\"4733-6bd8c41\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1577049064511214",
"status": "COMPLETED",
"status_changed_ts": "1577052204535418",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166445",
"builder:Linux Builder (dbg)",
"buildset:commit/git/ba6ae1233ea67aaaa514bb48eae39dc5cb945a63",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ba6ae1233ea67aaaa514bb48eae39dc5cb945a63",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9058768258094738080",
"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/8893338196158392096/+/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:4948407b99ac7a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1577052204540398",
"url": "https://ci.chromium.org/b/8893338196158392096",
"utcnow_ts": "1577052205117429"
}
}
[22:03:25.127] Invocation finished in 56m48.94104687s with status SUCCEEDED