[13:18:32.813] New invocation is queued and will start shortly
[13:18:34.982] Starting the invocation (attempt 1)
[13:18:35.054] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:18:35.054] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:18:35.054] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9053361887677332368",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5a4cbe005b41becbc6c529befb9e945e79699547\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9053361887677332368",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/5a4cbe005b41becbc6c529befb9e945e79699547",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a4cbe005b41becbc6c529befb9e945e79699547",
"gitiles_ref:refs/heads/master"
]
}
[13:18:35.968] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1582204715205238",
"id": "8887931824507497568",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5a4cbe005b41becbc6c529befb9e945e79699547\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582204715690824",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168824",
"builder:Linux Builder (dbg)",
"buildset:commit/git/5a4cbe005b41becbc6c529befb9e945e79699547",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a4cbe005b41becbc6c529befb9e945e79699547",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053361887677332368",
"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/8887931824507497568/+/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": "1582204715690940",
"url": "https://ci.chromium.org/b/8887931824507497568",
"utcnow_ts": "1582204715955026"
}
}
[13:18:35.968] Task URL: https://ci.chromium.org/b/8887931824507497568
[13:18:35.968] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:2:0) after 1m0s
[13:19:35.997] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:2:0)
[13:19:35.997] Timer tick, asking Buildbucket for the build status
[13:19:36.261] Build 8887931824507497568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:19:36.261] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:3:0) after 1m0s
[13:20:36.405] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:3:0)
[13:20:36.405] Timer tick, asking Buildbucket for the build status
[13:20:36.572] Build 8887931824507497568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:20:36.572] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:4:0) after 1m0s
[13:21:36.683] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:4:0)
[13:21:36.683] Timer tick, asking Buildbucket for the build status
[13:21:36.850] Build 8887931824507497568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:21:36.850] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:5:0) after 1m0s
[13:22:36.868] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:5:0)
[13:22:36.868] Timer tick, asking Buildbucket for the build status
[13:22:36.975] Build 8887931824507497568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:22:36.975] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:6:0) after 1m0s
[13:23:04.220] Received PubSub notification, asking Buildbucket for the build status
[13:23:04.346] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:37.020] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:6:0)
[13:23:37.020] Timer tick, asking Buildbucket for the build status
[13:23:37.141] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:37.141] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:8:0) after 1m0s
[13:24:37.179] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:8:0)
[13:24:37.179] Timer tick, asking Buildbucket for the build status
[13:24:37.278] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:37.278] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:9:0) after 1m0s
[13:25:37.418] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:9:0)
[13:25:37.418] Timer tick, asking Buildbucket for the build status
[13:25:37.791] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:37.791] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:10:0) after 1m0s
[13:26:37.910] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:10:0)
[13:26:37.910] Timer tick, asking Buildbucket for the build status
[13:26:38.467] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:38.467] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:11:0) after 1m0s
[13:27:38.633] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:11:0)
[13:27:38.633] Timer tick, asking Buildbucket for the build status
[13:27:38.749] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:38.750] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:12:0) after 1m0s
[13:28:38.812] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:12:0)
[13:28:38.812] Timer tick, asking Buildbucket for the build status
[13:28:38.910] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:38.910] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:13:0) after 1m0s
[13:29:38.979] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:13:0)
[13:29:38.979] Timer tick, asking Buildbucket for the build status
[13:29:39.083] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:39.083] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:14:0) after 1m0s
[13:30:39.082] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:14:0)
[13:30:39.082] Timer tick, asking Buildbucket for the build status
[13:30:39.368] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:39.368] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:15:0) after 1m0s
[13:31:39.463] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:15:0)
[13:31:39.463] Timer tick, asking Buildbucket for the build status
[13:31:39.553] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:39.553] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:16:0) after 1m0s
[13:32:39.585] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:16:0)
[13:32:39.585] Timer tick, asking Buildbucket for the build status
[13:32:39.842] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:39.842] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:17:0) after 1m0s
[13:33:40.088] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:17:0)
[13:33:40.088] Timer tick, asking Buildbucket for the build status
[13:33:40.406] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:40.406] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:18:0) after 1m0s
[13:34:40.479] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:18:0)
[13:34:40.479] Timer tick, asking Buildbucket for the build status
[13:34:40.905] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:40.905] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:19:0) after 1m0s
[13:35:40.983] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:19:0)
[13:35:40.983] Timer tick, asking Buildbucket for the build status
[13:35:41.258] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:41.258] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:20:0) after 1m0s
[13:36:41.385] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:20:0)
[13:36:41.385] Timer tick, asking Buildbucket for the build status
[13:36:41.572] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:41.572] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:21:0) after 1m0s
[13:37:41.617] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:21:0)
[13:37:41.617] Timer tick, asking Buildbucket for the build status
[13:37:41.735] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:41.735] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:22:0) after 1m0s
[13:38:41.819] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:22:0)
[13:38:41.819] Timer tick, asking Buildbucket for the build status
[13:38:41.916] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:41.916] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:23:0) after 1m0s
[13:39:42.052] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:23:0)
[13:39:42.052] Timer tick, asking Buildbucket for the build status
[13:39:42.347] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:42.347] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:24:0) after 1m0s
[13:40:42.357] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:24:0)
[13:40:42.357] Timer tick, asking Buildbucket for the build status
[13:40:42.555] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:42.555] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:25:0) after 1m0s
[13:41:42.568] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:25:0)
[13:41:42.568] Timer tick, asking Buildbucket for the build status
[13:41:42.658] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:42.658] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:26:0) after 1m0s
[13:42:42.684] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:26:0)
[13:42:42.684] Timer tick, asking Buildbucket for the build status
[13:42:42.859] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:42.859] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:27:0) after 1m0s
[13:43:42.874] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:27:0)
[13:43:42.874] Timer tick, asking Buildbucket for the build status
[13:43:42.992] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:42.992] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:28:0) after 1m0s
[13:44:43.171] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:28:0)
[13:44:43.171] Timer tick, asking Buildbucket for the build status
[13:44:43.739] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:43.739] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:29:0) after 1m0s
[13:45:43.864] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:29:0)
[13:45:43.864] Timer tick, asking Buildbucket for the build status
[13:45:43.963] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:43.963] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:30:0) after 1m0s
[13:46:44.005] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:30:0)
[13:46:44.005] Timer tick, asking Buildbucket for the build status
[13:46:44.106] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:44.106] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:31:0) after 1m0s
[13:47:44.165] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:31:0)
[13:47:44.165] Timer tick, asking Buildbucket for the build status
[13:47:44.279] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:44.279] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:32:0) after 1m0s
[13:48:44.361] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:32:0)
[13:48:44.361] Timer tick, asking Buildbucket for the build status
[13:48:44.567] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:44.567] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:33:0) after 1m0s
[13:49:44.625] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:33:0)
[13:49:44.625] Timer tick, asking Buildbucket for the build status
[13:49:44.938] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:44.938] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:34:0) after 1m0s
[13:50:45.079] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:34:0)
[13:50:45.079] Timer tick, asking Buildbucket for the build status
[13:50:45.265] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:45.265] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:35:0) after 1m0s
[13:51:45.382] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:35:0)
[13:51:45.382] Timer tick, asking Buildbucket for the build status
[13:51:45.552] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:45.552] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:36:0) after 1m0s
[13:52:45.601] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:36:0)
[13:52:45.601] Timer tick, asking Buildbucket for the build status
[13:52:45.734] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:45.734] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:37:0) after 1m0s
[13:53:45.820] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:37:0)
[13:53:45.820] Timer tick, asking Buildbucket for the build status
[13:53:45.961] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:45.961] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:38:0) after 1m0s
[13:54:46.022] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:38:0)
[13:54:46.022] Timer tick, asking Buildbucket for the build status
[13:54:46.137] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:46.137] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:39:0) after 1m0s
[13:55:46.199] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:39:0)
[13:55:46.200] Timer tick, asking Buildbucket for the build status
[13:55:46.310] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:46.310] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:40:0) after 1m0s
[13:56:46.387] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:40:0)
[13:56:46.387] Timer tick, asking Buildbucket for the build status
[13:56:46.545] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:46.545] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:41:0) after 1m0s
[13:57:46.573] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:41:0)
[13:57:46.573] Timer tick, asking Buildbucket for the build status
[13:57:47.285] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:47.285] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:42:0) after 1m0s
[13:58:47.425] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:42:0)
[13:58:47.425] Timer tick, asking Buildbucket for the build status
[13:58:48.082] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:48.082] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:43:0) after 1m0s
[13:59:48.247] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:43:0)
[13:59:48.247] Timer tick, asking Buildbucket for the build status
[13:59:48.388] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:48.388] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:44:0) after 1m0s
[14:00:48.433] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:44:0)
[14:00:48.433] Timer tick, asking Buildbucket for the build status
[14:00:48.635] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:48.635] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:45:0) after 1m0s
[14:01:48.758] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:45:0)
[14:01:48.758] Timer tick, asking Buildbucket for the build status
[14:01:48.892] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:48.892] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:46:0) after 1m0s
[14:02:48.949] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:46:0)
[14:02:48.949] Timer tick, asking Buildbucket for the build status
[14:02:49.062] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:49.062] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:47:0) after 1m0s
[14:03:49.107] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:47:0)
[14:03:49.107] Timer tick, asking Buildbucket for the build status
[14:03:49.272] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:49.272] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:48:0) after 1m0s
[14:04:49.362] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:48:0)
[14:04:49.362] Timer tick, asking Buildbucket for the build status
[14:04:49.478] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:49.478] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:49:0) after 1m0s
[14:05:49.524] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:49:0)
[14:05:49.524] Timer tick, asking Buildbucket for the build status
[14:05:49.643] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:49.643] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:50:0) after 1m0s
[14:06:49.777] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:50:0)
[14:06:49.777] Timer tick, asking Buildbucket for the build status
[14:06:49.896] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:49.896] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:51:0) after 1m0s
[14:07:50.087] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:51:0)
[14:07:50.087] Timer tick, asking Buildbucket for the build status
[14:07:50.555] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:50.555] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:52:0) after 1m0s
[14:08:50.580] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:52:0)
[14:08:50.580] Timer tick, asking Buildbucket for the build status
[14:08:51.049] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:51.049] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:53:0) after 1m0s
[14:09:51.088] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:53:0)
[14:09:51.088] Timer tick, asking Buildbucket for the build status
[14:09:51.374] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:51.374] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:54:0) after 1m0s
[14:10:51.436] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:54:0)
[14:10:51.436] Timer tick, asking Buildbucket for the build status
[14:10:51.725] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:51.725] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:55:0) after 1m0s
[14:11:51.825] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:55:0)
[14:11:51.825] Timer tick, asking Buildbucket for the build status
[14:11:51.925] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:51.925] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:56:0) after 1m0s
[14:12:52.061] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:56:0)
[14:12:52.061] Timer tick, asking Buildbucket for the build status
[14:12:52.189] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:52.189] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:57:0) after 1m0s
[14:13:52.298] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:57:0)
[14:13:52.298] Timer tick, asking Buildbucket for the build status
[14:13:52.432] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:52.432] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:58:0) after 1m0s
[14:14:52.514] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:58:0)
[14:14:52.514] Timer tick, asking Buildbucket for the build status
[14:14:52.804] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:52.804] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:59:0) after 1m0s
[14:15:52.906] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:59:0)
[14:15:52.906] Timer tick, asking Buildbucket for the build status
[14:15:53.012] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:53.012] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:60:0) after 1m0s
[14:16:53.048] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:60:0)
[14:16:53.048] Timer tick, asking Buildbucket for the build status
[14:16:53.217] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:53.217] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:61:0) after 1m0s
[14:17:53.245] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:61:0)
[14:17:53.245] Timer tick, asking Buildbucket for the build status
[14:17:53.485] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:53.485] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:62:0) after 1m0s
[14:18:53.659] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:62:0)
[14:18:53.659] Timer tick, asking Buildbucket for the build status
[14:18:53.758] Build 8887931824507497568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:53.758] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053361887677332368:63:0) after 1m0s
[14:19:00.035] Received PubSub notification, asking Buildbucket for the build status
[14:19:00.176] Build 8887931824507497568: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:19:00.176] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1582208339049177",
"created_by": "project:chromium",
"created_ts": "1582204715205238",
"id": "8887931824507497568",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5a4cbe005b41becbc6c529befb9e945e79699547\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-61-otzn\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168824, \"got_angle_revision\": \"b0e9cd6645e1d4e620db4d2a9a99597ee7620bd8\", \"got_dawn_revision\": \"b330ea6b491e3e000c11fe919e8099dece1c939b\", \"got_nacl_revision\": \"530e1f0dae34020864c81e5cd52be54d9c656690\", \"got_revision\": \"5a4cbe005b41becbc6c529befb9e945e79699547\", \"got_revision_cp\": \"refs/heads/master@{#743075}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"74d3f371f2015deeedb985a8b24ba242ea65caef\", \"got_v8_revision\": \"b86395aa07ec2a60590620ee163c24edfb060b8e\", \"got_v8_revision_cp\": \"refs/heads/8.2.125@{#2}\", \"got_webrtc_revision\": \"1282babe6621d3a2084bbd24e77652e0b3a4eb93\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30549}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5a4cbe005b41becbc6c529befb9e945e79699547\", \"swarm_hashes_refs/heads/master(at){#743075}_without_patch\": {\"accessibility_unittests\": \"5c25aba478f54ae8e76c4564d604f1f1663c0fce\", \"angle_unittests\": \"3d75038c17cdfabcabbf14e13d7e5777da966c04\", \"app_shell_unittests\": \"0df077f2d2c76454cd738148812382b881042d46\", \"aura_unittests\": \"6c1564d80cf74fc7f487db2e409d1100e56c9eb2\", \"base_unittests\": \"c1ba544a9adc85c150a9ecbdc03a79f77890d206\", \"base_util_unittests\": \"1ab8aedec856f6c0ca7f7b285a208a9e3f86f39c\", \"blink_common_unittests\": \"797b225ab099876fd7f29f749c48a8c36ee96536\", \"blink_fuzzer_unittests\": \"ec076cc1cbaa8507de8239965671dde7d27c0903\", \"blink_heap_unittests\": \"2c2c6e7d993a26a9518d65125c847835e4bd9c1e\", \"blink_platform_unittests\": \"82edf8609215972296ad06a5db1fc868a5cb855d\", \"blink_python_tests\": \"59752610129d337949726ebdf7abd2f678f0a601\", \"blink_unittests\": \"987ce491dfdee2c0910b55141daeeff4054dc657\", \"blink_web_tests\": \"4488b57004f5fadccf8c116b25ba78bf625828f6\", \"boringssl_crypto_tests\": \"662f28c77da22e313dd21fff2f9c9e72530ddcbc\", \"boringssl_ssl_tests\": \"12dab4e0d0514d8722e1e0c45a333ed33f631eb5\", \"browser_tests\": \"01502b3aa4c0ac1be0b05ff18aff65450b55fe2a\", \"cacheinvalidation_unittests\": \"ea925d820e993206cc14b83da3a5deed1403a514\", \"capture_unittests\": \"c842e79775e596cc766e0bf1de056608848f007f\", \"cast_unittests\": \"7b15c640335ed5e9d15eeb396cbf47685409434e\", \"cc_unittests\": \"5f42f09350bf22e09356e05eea083e0c5a1fa363\", \"chrome_app_unittests\": \"d37302c0e9a8257102b8ea02eaa192b96d137d50\", \"chromedriver_unittests\": \"4d1cdc471ceaa2a08eb390f3cd56e6bb9fc42cc6\", \"components_browsertests\": \"19e1f13089a1649cf7504477cb5083c99c9e6031\", \"components_unittests\": \"cabb68b85ef2c21fc9984ecd2782d1a5f3bf7ee3\", \"compositor_unittests\": \"38f116b6b9f14ac09ad821720646dd76d2cb2d6f\", \"content_browsertests\": \"a942e72890ed53a0000d5f8ac6942e5dc557d15b\", \"content_shell_crash_test\": \"67699faabb148eddd6cade7129fd4161a1966dae\", \"content_unittests\": \"633acbfc1abeda25b26480b12df7a01331e0d675\", \"crashpad_tests\": \"8b93159af0f865657b0cf56c3fe999af48fab48c\", \"cronet_tests\": \"45254b1fc4f98f7baeb9a04e8def64c7e3d7fe55\", \"cronet_unittests\": \"b9a322550f25536e48df78390743fac6ecfec855\", \"crypto_unittests\": \"a21b71445bd287108995fca793f041e788d15512\", \"dbus_unittests\": \"3ebfe474c3a5d5ae8b2bd37e1debeaf0381c4c19\", \"device_unittests\": \"07d22edfacb801e138947773588594c566b84672\", \"display_unittests\": \"8d22cfba15f176661cb4f1a44b99a259aba5f136\", \"events_unittests\": \"223605db2a0c7a2a5dc072e28927b2bcea59ff71\", \"extensions_browsertests\": \"fb8942099fcb35955008b3c14ba12bf3f4c27a75\", \"extensions_unittests\": \"8d8c6fe2db2c5a821a5a1e4e2e628dd0f520a68d\", \"filesystem_service_unittests\": \"41298c77f0e2adcb6c541c5e41c06fe30c6ac4a0\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"47aa463b7397e8df78c6ff2384a9fe01cdc2bdf9\", \"gfx_unittests\": \"26541d6c80248523744abcc3d6758449f715bccb\", \"gin_unittests\": \"0611899f1519a0ef7339873f942b449d242bc3c6\", \"google_apis_unittests\": \"4e30b7838cc308713165c35717d738676fcae256\", \"gpu_unittests\": \"0c5e9ed91b910c6690639bb74612b4f3ece3f942\", \"grit_python_unittests\": \"0a186e54539d3475bae4b9d19cc134d9a92e2108\", \"gwp_asan_unittests\": \"901f0c2458d9fe991f496e3cbf9b23458b4ced9e\", \"headless_browsertests\": \"b308636fa6a80c0ab9f9eda6b7263f6e488461be\", \"headless_unittests\": \"7a44d50bdf380867e3f8f4dad611c84a38f3e3d9\", \"interactive_ui_tests\": \"7bd9c5a7cfb839239db53b2ca75e1d536bb44f28\", \"ipc_tests\": \"d323c4aaaa18fca4afbda6eb7551b41fde82999c\", \"jingle_unittests\": \"678b1b0e5845b46337ad4963987f37f019a9ff77\", \"latency_unittests\": \"b539dd284a7118c1c591614f57130a5e46be98c8\", \"libjingle_xmpp_unittests\": \"c551e74d2f8543c7b8771500f39920b81aee6843\", \"media_blink_unittests\": \"36dbea708a1a7a2a860356f3a322a66048ed3771\", \"media_service_unittests\": \"112f4c5228b3730b9664e65cbecee106d7f5cbd2\", \"media_unittests\": \"aa832bc3b3c13ec5c0a706219e9347235c4dd81d\", \"message_center_unittests\": \"ed4b59a0223a2e8de54b6e576c277d2090ccfda0\", \"metrics_python_tests\": \"b9b82962a04a5fc4f2aec32def35a1ddfca15d3d\", \"midi_unittests\": \"5b4383887e530a7f19e7a8380db9656f0be79a70\", \"mojo_core_unittests\": \"5255db33211bc73caa94d7e68165c84eaacafdf4\", \"mojo_unittests\": \"9c10880cf4fc565e1a9d3c07b2ec60d185239191\", \"nacl_helper_nonsfi_unittests\": \"caecb15e17102e25d6ca3108a5e4d9890469bfde\", \"nacl_loader_unittests\": \"27abeb0831a463ab45dc30f8e6690b2411961b9d\", \"native_theme_unittests\": \"b8d0ed6b2fd99912d9890415fafc9042223a48e5\", \"net_unittests\": \"6648bdd1248bfa5fadc9a1128569b3fb338d1d11\", \"pdf_unittests\": \"a53746cc343ce346ada8c85ac367b1002902fa3f\", \"perfetto_unittests\": \"f8d47249a27ed07286fbdca57690c902df98bdaa\", \"ppapi_unittests\": \"b78af2deb8a2263d880db6c81492fdd02bfffc1a\", \"printing_unittests\": \"dca73a52b6273480df28ce86f9a9a76b001eabff\", \"remoting_unittests\": \"a67385338811bd783aec755cb9c7d42f7c00310e\", \"sandbox_linux_unittests\": \"ea39847a57442fbc0fba39e0ec10301229d41f74\", \"service_manager_unittests\": \"08242f27c265cb46516227a83fc88494425de2d8\", \"services_unittests\": \"7deb1f3b2dcec2342678ea3db5e3d8c2555c5026\", \"shell_dialogs_unittests\": \"1a625f6d7101d08778d3e7b96c8ec2575550e4ad\", \"skia_unittests\": \"b24242f6147d211ced3a0ff953d46602643b2bf2\", \"snapshot_unittests\": \"6d4a302f4a161de6e48a3399e2b5b994e5b6b3dc\", \"sql_unittests\": \"e0e35ab6140ad2257bfa0be56dc86f801424a0fe\", \"storage_unittests\": \"4b29b1d3b7056c608499a8980a0f1fc40fb082ea\", \"sync_integration_tests\": \"d1452010460e593135628c337dc18d2b26dbf781\", \"telemetry_gpu_unittests\": \"6fc5099d442f3abd798dd879be80b89d000def71\", \"telemetry_perf_unittests\": \"1fc88cf5d646c8a824ca62e6e9b76fcf6899319b\", \"telemetry_unittests\": \"de28695e9b6f8205d10a5cf8dc11a49d9257f642\", \"traffic_annotation_auditor_unittests\": \"6f4a598e35d97726348c300ca931e80ec7815dbc\", \"ui_base_unittests\": \"f7505b0db402b2c9060dffa02214d6bd202ed0eb\", \"ui_touch_selection_unittests\": \"bdfd3837dfeb3bc781e9640c9f188c605d685bf6\", \"unit_tests\": \"f2724c4031edddc305b57e9fbbcb509f78109630\", \"url_unittests\": \"3700f29786801439be7cdf86966fb678ad0957bf\", \"views_perftests\": \"a7d05d0440e26baf5eeea929f686de86bd3302e6\", \"views_unittests\": \"d405cf1e8d0924ff0bc84ce053c15a5136b83f82\", \"viz_unittests\": \"a790d1df91bdeafa87c3fe1b4577370c191ca8df\", \"vr_common_unittests\": \"dce5fe1b557d1e984b67e954192ca7c0a0ce82b6\", \"vr_pixeltests\": \"fbd4b47fd22d04f0e69a9766978fc7d120c50726\", \"webdriver_wpt_tests\": \"5cc772cf5a645fc5feaaf3d55b11fafde415637f\", \"weblayer_browsertests\": \"7680c1f13805d85945c7c0d7a0e758cf271b41f4\", \"wm_unittests\": \"71f3ebbd6c33edfc783673a022954cbcf3c4a7dc\", \"wtf_unittests\": \"6af03931ca7ff498de6eb63a2cb91409e0c0558c\", \"xr_browser_tests\": \"c383446d4147edd125f560fc62b2237a63746c8e\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_40238b720ec4feb1f57c327a3b72021d0e440ababc8fca32fd370c36be82ffcf_v2\", \"builder_b7e14c4c195c28a71eed7810023a619e81f723a242c926cb144e8d708664041b_v2\", \"builder_b9373cfdcc5f43b5e07cc4931fc34027757a424c7183ae2b31ae65ec7718ca12_v2\", \"builder_ee0cba3c54f4de5afe190f5f1f18eea3c236437d0f634917582eff9316b81bdc_v2\", \"builder_f2d41554cc5e5ccdd409a1e3331deca1f73b654eb50c3540ea4abf5478e808d4_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-61-otzn\"], \"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\": [\"4825-3d129d6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1582204982440453",
"status": "COMPLETED",
"status_changed_ts": "1582208339351467",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168824",
"builder:Linux Builder (dbg)",
"buildset:commit/git/5a4cbe005b41becbc6c529befb9e945e79699547",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a4cbe005b41becbc6c529befb9e945e79699547",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053361887677332368",
"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/8887931824507497568/+/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:4a7b918cea440e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1582208339355754",
"url": "https://ci.chromium.org/b/8887931824507497568",
"utcnow_ts": "1582208340167126"
}
}
[14:19:00.176] Invocation finished in 1h0m27.377946282s with status SUCCEEDED