[09:04:26.331] New invocation is queued and will start shortly
[09:04:27.388] Starting the invocation (attempt 1)
[09:04:27.435] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:04:27.435] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:04:27.435] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9057273544329685344",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b6c54785f0d34dcb92716fcc88861ea914cdcc12\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9057273544329685344",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/b6c54785f0d34dcb92716fcc88861ea914cdcc12",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b6c54785f0d34dcb92716fcc88861ea914cdcc12",
"gitiles_ref:refs/heads/master"
]
}
[09:04:28.358] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1578474267687500",
"id": "8891843482243620192",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b6c54785f0d34dcb92716fcc88861ea914cdcc12\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1578474268184343",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167116",
"builder:Linux Builder (dbg)",
"buildset:commit/git/b6c54785f0d34dcb92716fcc88861ea914cdcc12",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b6c54785f0d34dcb92716fcc88861ea914cdcc12",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9057273544329685344",
"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/8891843482243620192/+/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": "1578474268184490",
"url": "https://ci.chromium.org/b/8891843482243620192",
"utcnow_ts": "1578474268345708"
}
}
[09:04:28.358] Task URL: https://ci.chromium.org/b/8891843482243620192
[09:04:28.358] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:2:0) after 1m0s
[09:05:28.380] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:2:0)
[09:05:28.380] Timer tick, asking Buildbucket for the build status
[09:05:29.139] Build 8891843482243620192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:05:29.139] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:3:0) after 1m0s
[09:06:29.282] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:3:0)
[09:06:29.282] Timer tick, asking Buildbucket for the build status
[09:06:30.108] Build 8891843482243620192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:06:30.108] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:4:0) after 1m0s
[09:07:30.132] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:4:0)
[09:07:30.132] Timer tick, asking Buildbucket for the build status
[09:07:30.351] Build 8891843482243620192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:07:30.351] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:5:0) after 1m0s
[09:08:30.329] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:5:0)
[09:08:30.329] Timer tick, asking Buildbucket for the build status
[09:08:30.998] Build 8891843482243620192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:08:30.998] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:6:0) after 1m0s
[09:09:04.379] Received PubSub notification, asking Buildbucket for the build status
[09:09:04.620] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:31.020] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:6:0)
[09:09:31.020] Timer tick, asking Buildbucket for the build status
[09:09:31.341] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:31.341] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:8:0) after 1m0s
[09:10:31.374] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:8:0)
[09:10:31.374] Timer tick, asking Buildbucket for the build status
[09:10:31.969] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:31.969] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:9:0) after 1m0s
[09:11:32.029] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:9:0)
[09:11:32.029] Timer tick, asking Buildbucket for the build status
[09:11:32.213] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:32.213] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:10:0) after 1m0s
[09:12:32.237] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:10:0)
[09:12:32.237] Timer tick, asking Buildbucket for the build status
[09:12:32.423] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:32.423] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:11:0) after 1m0s
[09:13:32.434] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:11:0)
[09:13:32.434] Timer tick, asking Buildbucket for the build status
[09:13:32.635] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:32.635] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:12:0) after 1m0s
[09:14:32.723] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:12:0)
[09:14:32.723] Timer tick, asking Buildbucket for the build status
[09:14:33.628] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:33.628] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:13:0) after 1m0s
[09:15:33.653] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:13:0)
[09:15:33.653] Timer tick, asking Buildbucket for the build status
[09:15:34.187] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:34.187] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:14:0) after 1m0s
[09:16:34.243] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:14:0)
[09:16:34.243] Timer tick, asking Buildbucket for the build status
[09:16:34.580] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:34.580] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:15:0) after 1m0s
[09:17:34.723] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:15:0)
[09:17:34.723] Timer tick, asking Buildbucket for the build status
[09:17:35.428] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:35.428] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:16:0) after 1m0s
[09:18:35.444] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:16:0)
[09:18:35.444] Timer tick, asking Buildbucket for the build status
[09:18:36.297] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:36.297] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:17:0) after 1m0s
[09:19:36.343] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:17:0)
[09:19:36.343] Timer tick, asking Buildbucket for the build status
[09:19:36.876] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:36.876] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:18:0) after 1m0s
[09:20:36.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:18:0)
[09:20:36.933] Timer tick, asking Buildbucket for the build status
[09:20:38.662] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:38.662] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:19:0) after 1m0s
[09:21:38.722] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:19:0)
[09:21:38.722] Timer tick, asking Buildbucket for the build status
[09:21:39.548] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:39.548] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:20:0) after 1m0s
[09:22:39.553] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:20:0)
[09:22:39.554] Timer tick, asking Buildbucket for the build status
[09:22:40.088] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:40.088] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:21:0) after 1m0s
[09:23:40.154] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:21:0)
[09:23:40.154] Timer tick, asking Buildbucket for the build status
[09:23:40.324] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:40.324] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:22:0) after 1m0s
[09:24:40.415] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:22:0)
[09:24:40.415] Timer tick, asking Buildbucket for the build status
[09:24:41.350] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:41.350] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:23:0) after 1m0s
[09:25:41.379] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:23:0)
[09:25:41.379] Timer tick, asking Buildbucket for the build status
[09:25:42.185] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:42.185] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:24:0) after 1m0s
[09:26:42.293] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:24:0)
[09:26:42.293] Timer tick, asking Buildbucket for the build status
[09:26:42.623] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:42.624] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:25:0) after 1m0s
[09:27:42.766] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:25:0)
[09:27:42.766] Timer tick, asking Buildbucket for the build status
[09:27:43.247] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:43.247] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:26:0) after 1m0s
[09:28:43.335] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:26:0)
[09:28:43.335] Timer tick, asking Buildbucket for the build status
[09:28:43.617] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:43.617] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:27:0) after 1m0s
[09:29:43.702] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:27:0)
[09:29:43.702] Timer tick, asking Buildbucket for the build status
[09:29:44.311] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:44.311] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:28:0) after 1m0s
[09:30:44.367] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:28:0)
[09:30:44.367] Timer tick, asking Buildbucket for the build status
[09:30:44.867] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:44.867] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:29:0) after 1m0s
[09:31:44.849] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:29:0)
[09:31:44.849] Timer tick, asking Buildbucket for the build status
[09:31:45.327] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:45.327] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:30:0) after 1m0s
[09:32:45.398] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:30:0)
[09:32:45.398] Timer tick, asking Buildbucket for the build status
[09:32:45.805] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:45.805] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:31:0) after 1m0s
[09:33:45.917] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:31:0)
[09:33:45.917] Timer tick, asking Buildbucket for the build status
[09:33:46.210] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:46.210] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:32:0) after 1m0s
[09:34:46.235] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:32:0)
[09:34:46.235] Timer tick, asking Buildbucket for the build status
[09:34:47.593] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:47.593] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:33:0) after 1m0s
[09:35:47.881] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:33:0)
[09:35:47.881] Timer tick, asking Buildbucket for the build status
[09:35:48.025] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:48.025] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:34:0) after 1m0s
[09:36:48.197] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:34:0)
[09:36:48.197] Timer tick, asking Buildbucket for the build status
[09:36:48.392] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:48.392] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:35:0) after 1m0s
[09:37:48.436] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:35:0)
[09:37:48.436] Timer tick, asking Buildbucket for the build status
[09:37:49.095] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:49.095] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:36:0) after 1m0s
[09:38:49.198] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:36:0)
[09:38:49.198] Timer tick, asking Buildbucket for the build status
[09:38:49.765] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:49.765] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:37:0) after 1m0s
[09:39:49.775] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:37:0)
[09:39:49.775] Timer tick, asking Buildbucket for the build status
[09:39:50.127] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:50.127] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:38:0) after 1m0s
[09:40:50.735] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:38:0)
[09:40:50.735] Timer tick, asking Buildbucket for the build status
[09:40:50.882] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:50.882] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:39:0) after 1m0s
[09:41:51.257] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:39:0)
[09:41:51.257] Timer tick, asking Buildbucket for the build status
[09:41:51.510] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:51.510] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:40:0) after 1m0s
[09:42:51.527] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:40:0)
[09:42:51.527] Timer tick, asking Buildbucket for the build status
[09:42:51.818] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:51.818] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:41:0) after 1m0s
[09:43:53.014] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:41:0)
[09:43:53.014] Timer tick, asking Buildbucket for the build status
[09:43:53.324] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:53.324] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:42:0) after 1m0s
[09:44:53.507] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:42:0)
[09:44:53.507] Timer tick, asking Buildbucket for the build status
[09:44:54.107] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:54.107] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:43:0) after 1m0s
[09:45:54.173] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:43:0)
[09:45:54.173] Timer tick, asking Buildbucket for the build status
[09:45:54.481] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:54.481] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:44:0) after 1m0s
[09:46:54.463] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:44:0)
[09:46:54.463] Timer tick, asking Buildbucket for the build status
[09:46:55.635] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:55.635] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:45:0) after 1m0s
[09:47:55.659] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:45:0)
[09:47:55.659] Timer tick, asking Buildbucket for the build status
[09:47:55.923] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:55.923] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:46:0) after 1m0s
[09:48:55.991] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:46:0)
[09:48:55.991] Timer tick, asking Buildbucket for the build status
[09:48:56.184] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:56.184] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:47:0) after 1m0s
[09:49:56.223] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:47:0)
[09:49:56.223] Timer tick, asking Buildbucket for the build status
[09:49:56.431] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:56.431] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:48:0) after 1m0s
[09:50:56.462] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:48:0)
[09:50:56.462] Timer tick, asking Buildbucket for the build status
[09:50:56.944] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:56.944] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:49:0) after 1m0s
[09:51:56.972] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:49:0)
[09:51:56.972] Timer tick, asking Buildbucket for the build status
[09:51:57.399] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:57.399] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:50:0) after 1m0s
[09:52:57.426] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:50:0)
[09:52:57.426] Timer tick, asking Buildbucket for the build status
[09:52:58.124] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:58.124] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:51:0) after 1m0s
[09:53:58.131] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:51:0)
[09:53:58.131] Timer tick, asking Buildbucket for the build status
[09:53:58.572] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:58.572] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:52:0) after 1m0s
[09:54:58.656] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:52:0)
[09:54:58.656] Timer tick, asking Buildbucket for the build status
[09:54:59.234] Build 8891843482243620192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:59.234] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057273544329685344:53:0) after 1m0s
[09:55:41.540] Received PubSub notification, asking Buildbucket for the build status
[09:55:41.779] Build 8891843482243620192: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:55:41.779] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1578477340573404",
"created_by": "project:chromium",
"created_ts": "1578474267687500",
"id": "8891843482243620192",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b6c54785f0d34dcb92716fcc88861ea914cdcc12\"}}",
"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-20-5wyc\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 167116, \"got_angle_revision\": \"db49bc7dd0759fac8cb4065aa2afc3172aed7948\", \"got_dawn_revision\": \"96f79f6db56b87150be2a12755fed10f91f574dc\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"b6c54785f0d34dcb92716fcc88861ea914cdcc12\", \"got_revision_cp\": \"refs/heads/master@{#729268}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"45a4d0f36bd602f5b202a2961ef64984778d0681\", \"got_v8_revision\": \"abd4c18a8aaef5b05c11d7e926ddd67efb8eeacc\", \"got_v8_revision_cp\": \"refs/heads/8.1.109@{#1}\", \"got_webrtc_revision\": \"75b58972cbb788300b23a93bcb92012343d5606b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30169}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b6c54785f0d34dcb92716fcc88861ea914cdcc12\", \"swarm_hashes_refs/heads/master(at){#729268}_without_patch\": {\"accessibility_unittests\": \"c478d8c6c2f104d7ff15ddad57b8fc7dfd6d3d68\", \"angle_unittests\": \"e8fec559be991d271b1e9dbe11ff7e4f64dacee7\", \"app_shell_unittests\": \"b32a238794cd16d780d3e0ffbfba976f318f907e\", \"aura_unittests\": \"08771c06d27a25761cf0ac90220cb74bc8b0b7a3\", \"base_unittests\": \"fa9a8990f2b631435b735bf9b51d2904554ef60a\", \"base_util_unittests\": \"f90de5aa9a8e5c49353bbc568cb9bb93dde289cc\", \"blink_common_unittests\": \"2babd3a44202df6f8ca4f5a97ac42e51d7e1d4e2\", \"blink_fuzzer_unittests\": \"6216cb3083c414ead1d5a9df0eb30bfbc167fb65\", \"blink_heap_unittests\": \"3eef5f0e1a21601b1e05a7df1634d45e8e6e62e8\", \"blink_platform_unittests\": \"93e7fc82eca6e66cbaf70635498a39fc6299cc0f\", \"blink_python_tests\": \"76f75529fd6b20e3019972d996263650e97a783a\", \"blink_unittests\": \"9a8c8577027b1537369a386225b162c7b2fcd24b\", \"blink_web_tests\": \"a977ccc47ee144a997376c8afdb5f4963bfaf451\", \"boringssl_crypto_tests\": \"dd96a4d56f67e9e3a6b3de7a42ac564161034493\", \"boringssl_ssl_tests\": \"d44a59c6dfd71650fd7cff0bfe5b815e857136f7\", \"browser_tests\": \"185322d85925af819fed5feeb9011a9fac49a6b9\", \"cacheinvalidation_unittests\": \"7a23ebcb67d3b55c3d5f4a2461267bf283e17a18\", \"capture_unittests\": \"2df42edf3ba0bf1f6eb703988adae1843fab2ee3\", \"cast_unittests\": \"c61a70675e6c6914a30adc2795730dc3271cfe63\", \"cc_unittests\": \"0761e54ca2ebd85903f0361749ebb24e7ef4df9e\", \"chrome_app_unittests\": \"748410a61f6082aecbd2944145074d87ce145cb0\", \"chromedriver_unittests\": \"eb805a888e967ef272b514164387df69f2b1132d\", \"components_browsertests\": \"708f7c1b97f1a8cd6c5850fc05d951077bd4b779\", \"components_unittests\": \"75e1385bd30772284a1d9c0b0c375f7594d1d25b\", \"compositor_unittests\": \"8d1a8015c7b4ebe053548641c85ed85431e780c6\", \"content_browsertests\": \"4a320b0fdfe1f12cf1ae2f536d6815bb1c1b1cf1\", \"content_shell_crash_test\": \"e4e72b7fc7e322c2d6b309379419c0bfd87bccbe\", \"content_unittests\": \"af92fe9038972bd4cd4ef0fc86204fe68ebd0f20\", \"crashpad_tests\": \"a827b1604f018c78b24bbb5ac267d6fa2ab16f9a\", \"cronet_tests\": \"ea3461eff64af0a5c6676ad00ab7871c47441c7c\", \"cronet_unittests\": \"be07fe9c401d4567fba73c7cad8f7ce80cb3544a\", \"crypto_unittests\": \"fd5e19c6485259d8babce884584923ae2b1d631e\", \"dbus_unittests\": \"e609369d2b5f73f38c93cbf615fa438c68af6bb2\", \"device_unittests\": \"784fe9ac5e7f2e285c8d46c17ef9e8df322041f1\", \"devtools_lint_check\": \"d00c512fb68f12a95c3246635e8d61f5f667ccf2\", \"devtools_type_check\": \"d08015c18a198d6d90420417c533b0c79749f712\", \"display_unittests\": \"916bab5f7927dfe533b84350a93e61422c683813\", \"events_unittests\": \"3b6f8d17032592880f458328ff181bd176360e7a\", \"extensions_browsertests\": \"73e6796f6f3d9971a88dd351827984fdcbb2f0b6\", \"extensions_unittests\": \"53c2bc913fbcd7347170f7dc21648d5124fb786d\", \"filesystem_service_unittests\": \"498e62ca86b9a7c4592394c1197991425a03a903\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"35d3f32a24e14e9023bc53dec6f3328ac2f9dbed\", \"gfx_unittests\": \"2df7b9aa2b79d980a2f27df2bc12b8e45f6b23c6\", \"gin_unittests\": \"2c9fc9a6f315dff7d567db5a690ef87e0882426a\", \"google_apis_unittests\": \"218e6ef4369a278afea1ddfe559c8708a77ee467\", \"gpu_unittests\": \"bd9cb2aa0fb42ed1591d1730431448b2a25b0450\", \"grit_python_unittests\": \"5d2561226d0db6aa6e2a7322c9c06178bd9c6346\", \"gwp_asan_unittests\": \"43eec98bca1e5f5f731f7a5f5b625cf0032c0f8d\", \"headless_browsertests\": \"fe90e84ad394eee3734f57c288efba1781f307ae\", \"headless_unittests\": \"143e7d50339982c3ed4742d20a7e60d486413141\", \"interactive_ui_tests\": \"0fbf8866bc8ebbb37230f826b2f88994a4035eac\", \"ipc_tests\": \"d7a9b6e7bd956d1d90af578c665e701e0b24874b\", \"jingle_unittests\": \"3bff7541e9e8667301405d13a3eaa8e7bbd6132d\", \"latency_unittests\": \"77e40a9826c5647805a0923c084f98f89c937eea\", \"libjingle_xmpp_unittests\": \"122f0919ed350b53ac4d615c74afa5275b4105f8\", \"media_blink_unittests\": \"a2f67b8a1c266c1077afa8d4554d4fbfc569785b\", \"media_service_unittests\": \"2ae1035b781b98497f504f924df78467ef413b9b\", \"media_unittests\": \"209faf94ae412d1dd5b54eba009a5bba4fd27f7d\", \"message_center_unittests\": \"629c268e312d8d2c41569e4eab3b83560cdf7be0\", \"metrics_python_tests\": \"4927b4da5f942db335be18871c27aa416a1755c6\", \"midi_unittests\": \"aa15ba049b04a82258e853491182bd672ff697a4\", \"mojo_core_unittests\": \"b727db8f82b600d31192dae41001746a5b64744a\", \"mojo_unittests\": \"3f5438ef3b30495dde2550b403f066b3a3b430c9\", \"nacl_helper_nonsfi_unittests\": \"e084eed6bfce5c31f98b4a63f1fcdc2c37e2feb8\", \"nacl_loader_unittests\": \"e494833e46674980c214d5b63dc4f9e76ad2d45b\", \"native_theme_unittests\": \"7aba8b7e7c52b3a44be43b3a60d610bb3bc1e610\", \"net_unittests\": \"9b3298abe441c9bae6bdd30e5290640476c6dcff\", \"pdf_unittests\": \"b202a3c9fe50cf7e78a0b88e871eba686246c379\", \"perfetto_unittests\": \"0984069a80364fc32fe50d349aa0a9fd663c1b3a\", \"ppapi_unittests\": \"962dd8de5f86f102eb7f0e453fc3d3419d238455\", \"printing_unittests\": \"4a25f322dfa1b2d7ac0dd8af735ccd9ecd2f5d2d\", \"remoting_unittests\": \"e7f99552adcec72574ffcf7d50eeabf0f09f02f7\", \"sandbox_linux_unittests\": \"bd0ce247ad1fe32bd47bbec59a5df0a3ece4cd62\", \"service_manager_unittests\": \"b37c85a4eaf99ea1e46a7bb86f0a1eb35225493c\", \"services_unittests\": \"12c6138fb2ccd3968777ce16b78030f4f32f8918\", \"shell_dialogs_unittests\": \"277cc4983ae011d858d1225caca31a9711fbe1ac\", \"skia_unittests\": \"211ca45560aefcdc42b68cfb208bb0d6604187ff\", \"snapshot_unittests\": \"ba323d55ebe2ece5ca73433d623e3dffd2fdf330\", \"sql_unittests\": \"26a1e83fac8d847e64f3ef09a0988e34c4c68177\", \"storage_unittests\": \"26e4098d042dfba2191e9acd60c7f2af8817a43c\", \"sync_integration_tests\": \"b7196fad77c2ab59a3c7f7c01f1fa4ce166f43ff\", \"telemetry_gpu_unittests\": \"3f59b1019ebf5e3ffbacbb8aa457d2cd79d58450\", \"telemetry_perf_unittests\": \"6f5113d30b9bb8cec49b86defa137c29dc05e304\", \"telemetry_unittests\": \"7b27dfc9092f8ef2257e9e968f117c77dfb9bd96\", \"traffic_annotation_auditor_unittests\": \"8c3c9858de4df05371b4b5128a37286ff5978d49\", \"ui_base_unittests\": \"2c107b0620d2a25f5d4c39e9e3743025da2c5c85\", \"ui_touch_selection_unittests\": \"ae3cafef3d8ea14e160ee591fb10bc3738a5e04d\", \"unit_tests\": \"de2e5bdf3326f1ae11bd7147b39935e44216034d\", \"url_unittests\": \"ed852d327dfba876ffe6e9d82be7b9490a08cc29\", \"views_perftests\": \"b80ab711fdc6fc094279ba700a067bb16b1ef8af\", \"views_unittests\": \"62ef325239788a43d98ecced0b2e522e8bed5d01\", \"viz_unittests\": \"f24fe960e327f6766bb8c7ba5e70d49b85909968\", \"vr_common_unittests\": \"8676e14eec64368f14820f9fa72a6947a7687fcd\", \"vr_pixeltests\": \"2e39ec21c74f94cfbf6aeb78d505ebcf08e028b1\", \"webdriver_wpt_tests\": \"9a45957758b5129ab3771f564f342acb25682f75\", \"weblayer_browsertests\": \"2ccee08633eef2ffaf102a827b30c7ac5ff086e5\", \"wm_unittests\": \"114ac150fd160bc0e3cc8bb84bddcd09a932b9a9\", \"wtf_unittests\": \"b03322434e4cbda3f8a3a28d6f9cdcecab8dc3dd\", \"xr_browser_tests\": \"7e15919c18296fb6c64060c0f65b8410f52faf7b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_093d924ecb526dae9a1dcf9db4725f8944eec2b4c0f2ddc6c2ac4a04802f7f15_v2\", \"builder_361cbaa79261c75a92fc63a8f8c6fc117764238b3277286fa51086e6da3301df_v2\", \"builder_a785143896320bcc3f6844d574895f87b0975623707ad7da12ce94e1b959defa_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-20-5wyc\"], \"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\": [\"4745-ef32e44\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1578474543224136",
"status": "COMPLETED",
"status_changed_ts": "1578477340779642",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167116",
"builder:Linux Builder (dbg)",
"buildset:commit/git/b6c54785f0d34dcb92716fcc88861ea914cdcc12",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b6c54785f0d34dcb92716fcc88861ea914cdcc12",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9057273544329685344",
"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/8891843482243620192/+/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:499d37715c398b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1578477340786622",
"url": "https://ci.chromium.org/b/8891843482243620192",
"utcnow_ts": "1578477341766061"
}
}
[09:55:41.779] Invocation finished in 51m15.464701073s with status SUCCEEDED