[02:38:33.464] New invocation is queued and will start shortly
[02:38:34.923] Starting the invocation (attempt 1)
[02:38:34.957] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:38:34.957] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:38:34.957] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030481119816302768",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7ee6edbfa65aed7067125f191ad0352f087b6e7a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9030481119816302768",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/7ee6edbfa65aed7067125f191ad0352f087b6e7a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7ee6edbfa65aed7067125f191ad0352f087b6e7a",
"gitiles_ref:refs/heads/master"
]
}
[02:38:36.595] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604025515056555",
"id": "8865051057482833584",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7ee6edbfa65aed7067125f191ad0352f087b6e7a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604025515999330",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/177161",
"builder:Linux Builder (dbg)",
"buildset:commit/git/7ee6edbfa65aed7067125f191ad0352f087b6e7a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7ee6edbfa65aed7067125f191ad0352f087b6e7a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030481119816302768",
"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/8865051057482833584/+/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": "1604025515999466",
"url": "https://ci.chromium.org/b/8865051057482833584",
"utcnow_ts": "1604025516519621"
}
}
[02:38:36.595] Task URL: https://ci.chromium.org/b/8865051057482833584
[02:38:36.595] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:2:0) after 1m0s
[02:38:37.918] Received PubSub notification, asking Buildbucket for the build status
[02:38:38.008] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:36.615] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:2:0)
[02:39:36.615] Timer tick, asking Buildbucket for the build status
[02:39:36.831] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:36.831] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:4:0) after 1m0s
[02:40:36.880] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:4:0)
[02:40:36.880] Timer tick, asking Buildbucket for the build status
[02:40:36.963] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:36.963] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:5:0) after 1m0s
[02:41:36.959] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:5:0)
[02:41:36.959] Timer tick, asking Buildbucket for the build status
[02:41:37.106] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:37.106] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:6:0) after 1m0s
[02:42:37.156] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:6:0)
[02:42:37.156] Timer tick, asking Buildbucket for the build status
[02:42:37.478] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:37.478] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:7:0) after 1m0s
[02:43:37.534] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:7:0)
[02:43:37.534] Timer tick, asking Buildbucket for the build status
[02:43:37.670] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:37.670] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:8:0) after 1m0s
[02:44:37.713] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:8:0)
[02:44:37.713] Timer tick, asking Buildbucket for the build status
[02:44:37.838] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:37.838] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:9:0) after 1m0s
[02:45:37.858] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:9:0)
[02:45:37.858] Timer tick, asking Buildbucket for the build status
[02:45:37.951] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:37.951] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:10:0) after 1m0s
[02:46:38.029] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:10:0)
[02:46:38.029] Timer tick, asking Buildbucket for the build status
[02:46:38.112] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:38.112] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:11:0) after 1m0s
[02:47:38.141] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:11:0)
[02:47:38.141] Timer tick, asking Buildbucket for the build status
[02:47:38.215] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:38.215] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:12:0) after 1m0s
[02:48:38.274] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:12:0)
[02:48:38.274] Timer tick, asking Buildbucket for the build status
[02:48:38.419] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:38.419] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:13:0) after 1m0s
[02:49:38.492] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:13:0)
[02:49:38.492] Timer tick, asking Buildbucket for the build status
[02:49:38.552] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:38.552] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:14:0) after 1m0s
[02:50:38.682] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:14:0)
[02:50:38.682] Timer tick, asking Buildbucket for the build status
[02:50:38.764] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:38.764] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:15:0) after 1m0s
[02:51:38.793] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:15:0)
[02:51:38.793] Timer tick, asking Buildbucket for the build status
[02:51:38.864] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:38.864] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:16:0) after 1m0s
[02:52:39.026] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:16:0)
[02:52:39.026] Timer tick, asking Buildbucket for the build status
[02:52:39.107] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:39.107] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:17:0) after 1m0s
[02:53:39.144] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:17:0)
[02:53:39.144] Timer tick, asking Buildbucket for the build status
[02:53:39.267] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:39.267] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:18:0) after 1m0s
[02:54:39.379] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:18:0)
[02:54:39.379] Timer tick, asking Buildbucket for the build status
[02:54:39.584] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:39.584] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:19:0) after 1m0s
[02:55:39.593] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:19:0)
[02:55:39.594] Timer tick, asking Buildbucket for the build status
[02:55:39.678] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:39.678] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:20:0) after 1m0s
[02:56:39.709] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:20:0)
[02:56:39.709] Timer tick, asking Buildbucket for the build status
[02:56:39.777] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:39.777] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:21:0) after 1m0s
[02:57:39.988] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:21:0)
[02:57:39.988] Timer tick, asking Buildbucket for the build status
[02:57:40.050] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:40.050] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:22:0) after 1m0s
[02:58:40.129] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:22:0)
[02:58:40.129] Timer tick, asking Buildbucket for the build status
[02:58:40.347] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:40.347] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:23:0) after 1m0s
[02:59:40.427] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:23:0)
[02:59:40.427] Timer tick, asking Buildbucket for the build status
[02:59:40.693] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:40.693] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:24:0) after 1m0s
[03:00:40.886] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:24:0)
[03:00:40.886] Timer tick, asking Buildbucket for the build status
[03:00:40.986] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:40.986] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:25:0) after 1m0s
[03:01:41.099] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:25:0)
[03:01:41.099] Timer tick, asking Buildbucket for the build status
[03:01:41.167] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:41.167] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:26:0) after 1m0s
[03:02:41.271] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:26:0)
[03:02:41.271] Timer tick, asking Buildbucket for the build status
[03:02:42.841] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:42.841] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:27:0) after 1m0s
[03:03:42.905] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:27:0)
[03:03:42.905] Timer tick, asking Buildbucket for the build status
[03:03:43.813] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:43.813] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:28:0) after 1m0s
[03:04:43.916] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:28:0)
[03:04:43.916] Timer tick, asking Buildbucket for the build status
[03:04:44.098] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:44.098] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:29:0) after 1m0s
[03:05:44.176] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:29:0)
[03:05:44.176] Timer tick, asking Buildbucket for the build status
[03:05:44.251] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:44.251] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:30:0) after 1m0s
[03:06:44.367] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:30:0)
[03:06:44.367] Timer tick, asking Buildbucket for the build status
[03:06:44.435] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:44.436] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:31:0) after 1m0s
[03:07:44.481] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:31:0)
[03:07:44.481] Timer tick, asking Buildbucket for the build status
[03:07:44.552] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:44.552] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:32:0) after 1m0s
[03:08:44.650] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:32:0)
[03:08:44.650] Timer tick, asking Buildbucket for the build status
[03:08:44.850] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:44.850] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:33:0) after 1m0s
[03:09:44.910] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:33:0)
[03:09:44.910] Timer tick, asking Buildbucket for the build status
[03:09:44.985] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:44.985] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:34:0) after 1m0s
[03:10:45.005] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:34:0)
[03:10:45.005] Timer tick, asking Buildbucket for the build status
[03:10:45.187] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:45.187] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:35:0) after 1m0s
[03:11:45.261] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:35:0)
[03:11:45.261] Timer tick, asking Buildbucket for the build status
[03:11:45.381] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:45.381] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:36:0) after 1m0s
[03:12:45.472] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:36:0)
[03:12:45.472] Timer tick, asking Buildbucket for the build status
[03:12:45.726] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:45.726] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:37:0) after 1m0s
[03:13:45.751] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:37:0)
[03:13:45.751] Timer tick, asking Buildbucket for the build status
[03:13:45.856] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:45.856] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:38:0) after 1m0s
[03:14:45.929] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:38:0)
[03:14:45.929] Timer tick, asking Buildbucket for the build status
[03:14:46.031] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:46.031] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:39:0) after 1m0s
[03:15:46.087] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:39:0)
[03:15:46.087] Timer tick, asking Buildbucket for the build status
[03:15:46.203] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:46.203] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:40:0) after 1m0s
[03:16:46.229] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:40:0)
[03:16:46.229] Timer tick, asking Buildbucket for the build status
[03:16:46.339] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:46.339] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:41:0) after 1m0s
[03:17:46.420] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:41:0)
[03:17:46.420] Timer tick, asking Buildbucket for the build status
[03:17:46.531] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:46.531] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:42:0) after 1m0s
[03:18:46.640] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:42:0)
[03:18:46.640] Timer tick, asking Buildbucket for the build status
[03:18:46.785] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:46.786] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:43:0) after 1m0s
[03:19:46.945] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:43:0)
[03:19:46.945] Timer tick, asking Buildbucket for the build status
[03:19:47.013] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:47.013] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:44:0) after 1m0s
[03:20:47.038] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:44:0)
[03:20:47.038] Timer tick, asking Buildbucket for the build status
[03:20:47.171] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:47.171] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:45:0) after 1m0s
[03:21:47.188] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:45:0)
[03:21:47.188] Timer tick, asking Buildbucket for the build status
[03:21:47.340] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:47.340] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:46:0) after 1m0s
[03:22:47.399] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:46:0)
[03:22:47.399] Timer tick, asking Buildbucket for the build status
[03:22:47.477] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:47.477] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:47:0) after 1m0s
[03:23:47.559] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:47:0)
[03:23:47.559] Timer tick, asking Buildbucket for the build status
[03:23:47.658] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:47.658] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:48:0) after 1m0s
[03:24:47.839] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:48:0)
[03:24:47.839] Timer tick, asking Buildbucket for the build status
[03:24:48.409] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:48.409] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:49:0) after 1m0s
[03:25:48.499] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:49:0)
[03:25:48.499] Timer tick, asking Buildbucket for the build status
[03:25:48.884] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:48.884] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:50:0) after 1m0s
[03:26:48.920] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:50:0)
[03:26:48.920] Timer tick, asking Buildbucket for the build status
[03:26:48.994] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:48.994] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:51:0) after 1m0s
[03:27:49.012] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:51:0)
[03:27:49.012] Timer tick, asking Buildbucket for the build status
[03:27:49.096] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:49.096] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:52:0) after 1m0s
[03:28:49.164] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:52:0)
[03:28:49.164] Timer tick, asking Buildbucket for the build status
[03:28:49.285] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:49.285] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:53:0) after 1m0s
[03:29:49.278] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:53:0)
[03:29:49.278] Timer tick, asking Buildbucket for the build status
[03:29:49.370] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:49.370] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:54:0) after 1m0s
[03:30:49.473] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:54:0)
[03:30:49.473] Timer tick, asking Buildbucket for the build status
[03:30:49.603] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:49.603] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:55:0) after 1m0s
[03:31:49.662] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:55:0)
[03:31:49.662] Timer tick, asking Buildbucket for the build status
[03:31:49.734] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:49.734] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:56:0) after 1m0s
[03:32:49.838] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:56:0)
[03:32:49.838] Timer tick, asking Buildbucket for the build status
[03:32:49.976] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:49.976] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:57:0) after 1m0s
[03:33:49.994] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:57:0)
[03:33:49.994] Timer tick, asking Buildbucket for the build status
[03:33:50.177] Build 8865051057482833584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:50.177] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9030481119816302768:58:0) after 1m0s
[03:34:40.474] Received PubSub notification, asking Buildbucket for the build status
[03:34:40.904] Build 8865051057482833584: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:34:40.904] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604028878664242",
"created_by": "project:chromium",
"created_ts": "1604025515056555",
"id": "8865051057482833584",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7ee6edbfa65aed7067125f191ad0352f087b6e7a\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"7ee6edbfa65aed7067125f191ad0352f087b6e7a\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-163-dpfz\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 177161, \"got_angle_revision\": \"a653a9badb55294ab1710a7ac41a87b614dbac81\", \"got_dawn_revision\": \"25eb373eede4aaa8ed069457e2c05b9c9eab9db2\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"7ee6edbfa65aed7067125f191ad0352f087b6e7a\", \"got_revision_cp\": \"refs/heads/master@{#822494}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"2be25f08deafe1fb1eada032cd3b2aae3021f367\", \"got_v8_revision\": \"ffc771df4710e998f73be031890f60ce2f69d513\", \"got_v8_revision_cp\": \"refs/heads/8.8.211@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7ee6edbfa65aed7067125f191ad0352f087b6e7a\", \"swarm_hashes_refs/heads/master(at){#822494}_without_patch\": {\"absl_hardening_tests\": \"6779412fc328e4e4f143b3a93d5ecdfce5ff11c7\", \"accessibility_unittests\": \"da1e0c112fbaab4bd9edd7ce3ea53371f70acfa4\", \"angle_unittests\": \"ae530343f1d548803be6a31341f42e345cdf65ec\", \"app_shell_unittests\": \"86142621a79a35dd468c10a566c2138ff41b914c\", \"aura_unittests\": \"a88c6a1fece5efde4c4c428acaacb5f01e063e08\", \"base_unittests\": \"2a65181d24c4217c18bcabf0b61571950fd28f82\", \"base_util_unittests\": \"eda69eb6a8a2f1271629d975d3488f69e1bf6f0f\", \"blink_common_unittests\": \"bc9f1ed699e7aeca9917a607ee2de5669c9f36da\", \"blink_fuzzer_unittests\": \"3f622051f1ed78e31ea57826669cb6944e252716\", \"blink_heap_unittests\": \"6c0583a20696d0bfc1576cf63c4d27b4f55e7501\", \"blink_platform_unittests\": \"42b00508e821d72b77c241ae10b7ffa3faf5c4f9\", \"blink_python_tests\": \"c7ed97304320e9d89a9a0798e9e8261bd8964d38\", \"blink_unittests\": \"14d0b9800ec9ca2af8eea38d9db72fb415b96dba\", \"blink_web_tests\": \"67b1b96a72b99a8e1bfac03946cf32671ce32095\", \"boringssl_crypto_tests\": \"31c67ce476552e63e80e72bc2644ecd6ec14c662\", \"boringssl_ssl_tests\": \"da93ac4d08787be106f291b43ae13f09c56cef21\", \"browser_tests\": \"85a5e53914840d7a2c84916730d9d4522f882660\", \"capture_unittests\": \"d555084eeb7eef8bf6f099f2e1bb526ae042273f\", \"cast_unittests\": \"64f32eb9e16645745634ce5d23a6b5684912783b\", \"cc_unittests\": \"cb696e550e4d3847377462f43f7cd389b54869d1\", \"chrome_app_unittests\": \"ce269c6ea3d20a7215d1a5cd0897dbc991b1e985\", \"chromedriver_unittests\": \"2cad3938e8e07f943743b7f0e0b7be359bbd29d0\", \"components_browsertests\": \"7b6ebfab43219c9076275d9d9d808c895f146abd\", \"components_unittests\": \"e26c15b52a67a726f0020e3fa5ca88ce1b37066c\", \"compositor_unittests\": \"6b43671956071d41cd00fa3d641ddbf763bc03d4\", \"content_browsertests\": \"92ef8e2de30b98885c354c4f036416c7bdc660c5\", \"content_shell_crash_test\": \"3e8269a810b0390fb3f05ae3e7cf77aa3c6bed67\", \"content_unittests\": \"8ecc638f5f905a27517e5c9075423aaf8ae1f2d1\", \"crashpad_tests\": \"577c30c31c2573045b5daa43bcdb8573bab98a88\", \"cronet_tests\": \"ec0f0e94837af7a4e240def723bf6c4a62ac8c14\", \"cronet_unittests\": \"01c6861d2fb76e1a9bb2cbaa6b5b733c2eb46890\", \"crypto_unittests\": \"95d952d42e4a30682c0f3260e1c27318b8806d50\", \"dbus_unittests\": \"f0f591d62cbc1c440e9f0f4e093600e507d6c4ff\", \"device_unittests\": \"a6e11972c28da21a5441e8a325b6d35f1e728f43\", \"display_unittests\": \"d8f2c92115ad3c98e37ec7cbb9950bbb66b50fcc\", \"events_unittests\": \"9c8407aee174c3690f565a512dd2ee3eef704032\", \"extension_docserver_python_unittests\": \"9e7079c2d8b02e02a14c25b59c5a7a47a882d7f1\", \"extensions_browsertests\": \"a3d2c6f20f31999de5ba12265772c5b0538b0a5a\", \"extensions_unittests\": \"48c6a598e1707f45ee4453aba140b1508a91b9e4\", \"filesystem_service_unittests\": \"ad39f64af0cea9893ed84c9d15a6458b0f1a0c1c\", \"flatbuffers_unittests\": \"be237720fdbb07e58b96ce0c0382048fec390853\", \"gcm_unit_tests\": \"a5fd9594e74372928848dbfd4278ac87ddd4c2f8\", \"gfx_unittests\": \"343fc147f31e450662f2f1682468182931b07b59\", \"gin_unittests\": \"ab942902dbf3fb4efef89468fc6595f44b1e874f\", \"google_apis_unittests\": \"eafa6df9fe6072db3245600490781e6602474910\", \"gpu_unittests\": \"fe3798a8c1f397d382bb39342320127dfcef5667\", \"grit_python_unittests\": \"425c15fa19ff9dc82de965106a11d59ef0573ed5\", \"gwp_asan_unittests\": \"e7ec9acb53f67f59e56a2be02fce5cab4f6a3b13\", \"headless_browsertests\": \"2cb65d0d22eddcc4de3e09dd9ac82a51b728dfc4\", \"headless_unittests\": \"a552772b9003a15485b8f82150e03722e52dae07\", \"interactive_ui_tests\": \"a11ff19255bcbe61849f83970d54c882f1765aaf\", \"ipc_tests\": \"2cc7279b08c9b5fc9b061e14646b96233d578b99\", \"jingle_unittests\": \"db2186d404a1841325f9ab575f6d1021dfd9ee09\", \"latency_unittests\": \"f04c15c17bfdbf67a04d80b882eb38e44a1053b3\", \"libjingle_xmpp_unittests\": \"85d04e42c705de4798d317f1605a7e2d27fa3245\", \"liburlpattern_unittests\": \"4e72c74c2845c07fb8124096f8ccd62663beeda7\", \"media_blink_unittests\": \"f17080cef8525e3f6fd4d0192ee7f387526d2982\", \"media_unittests\": \"197e89050794d05ae314d7187f34f97cfd725627\", \"message_center_unittests\": \"c7a0d6e2c9aa1b1ac5cfc4a6897d49c89f36d54f\", \"metrics_python_tests\": \"a63875543446095b26f915401cd6857ba51ccd5c\", \"midi_unittests\": \"2b14d9908ae34c573ec35f7bfde88f7d037ede5a\", \"mojo_core_unittests\": \"5bcb4ae129e07e3cc760531303e8073638214d47\", \"mojo_unittests\": \"c0453e447e82cee7078b464a961916b3034b4688\", \"nacl_helper_nonsfi_unittests\": \"6d551f09db9002d9d79a62a6365d566825fa86d2\", \"nacl_loader_unittests\": \"fe75ff54b1dd90fe4923bd3929ecb5d6191f54b4\", \"native_theme_unittests\": \"7072d4229b3fb065488d304b253276617cf9f779\", \"net_unittests\": \"74ec93b2e3fc42a2f3ed7cb791d5dccfb6698546\", \"openscreen_unittests\": \"75fb5e4f9aa960a4eb390bfdcf933b6d7bdd464f\", \"pdf_unittests\": \"4d0b606c5c0b8525ad53ee2ae1365b1cdeabe859\", \"perfetto_unittests\": \"c8267693e3f756c9d4f9c62a1ce2ece34d820573\", \"ppapi_unittests\": \"310830f28d8e00816219267f32a5d4820bb7a9f9\", \"printing_unittests\": \"b86fed5eeca62e85e5aa2335425770740f4c69fc\", \"remoting_unittests\": \"043161012334bd5eb133d8f8f0bbb7c3c5b8f0cb\", \"sandbox_linux_unittests\": \"29c29f0bf6aa2d1b146c8f0e51d805c9708e687f\", \"service_manager_unittests\": \"a93e72adc06f286dc15397ae5a3a6835de65bdd9\", \"services_unittests\": \"24979c83b8c6a2d04cf49f2ee04493e994121ba7\", \"shell_dialogs_unittests\": \"45fd7539ea6ad241e00a8aa8c06dbbb44d450cce\", \"skia_unittests\": \"762abac98111ec119a9810bd620cff527d98bc47\", \"snapshot_unittests\": \"6c3a1549940d071ec5291b404ad695a5ca67a65d\", \"sql_unittests\": \"34a510c479d823133ed05d4dfe1dad7a81dd30f3\", \"storage_unittests\": \"83406ddafd4131af7d978f616d83df392c3acab0\", \"sync_integration_tests\": \"b8d654db3da85bfa09fcaf820bc524c1e8523e88\", \"telemetry_gpu_unittests\": \"16ab5528c0b0f78c17742a0f363527b10037a79d\", \"telemetry_perf_unittests\": \"9e98db953b48426724c7eae453e345ac3191b452\", \"telemetry_unittests\": \"9878f2c8f7cb69ebf951febce496d6c069cb8d5a\", \"traffic_annotation_auditor_unittests\": \"3eea4cc8dfeca7e6f2d8feb4d116301880819754\", \"ui_base_unittests\": \"ace369e3f699a7331587940ccaf75fa12fbd17e4\", \"ui_touch_selection_unittests\": \"9cb70ee83a2e1b5089bb32e9e6b0afc59f1769eb\", \"unit_tests\": \"f76ba1577136ba7e13b2cf23d2ffc428b1c10db6\", \"url_unittests\": \"26f8bd43fc0eea1c7b4fd90f40b3aa6acaf92057\", \"views_perftests\": \"d5ce6bab10a6d4deb52b3cf7d12f3aa5b70a97a7\", \"views_unittests\": \"53946df9e6d2893f848f24980d05cc7986a1002b\", \"viz_unittests\": \"0cbaf16ef93f52decd12c67e5f103e85baabbf76\", \"vr_common_unittests\": \"06045cbd365ffb215adb0b6f4ed6b49ecc9ecee8\", \"vr_pixeltests\": \"41320cfbed04fae0de13fdde5d100029868880ee\", \"webdriver_wpt_tests\": \"f989da715e8c0c9bca2cd5ec2db32cd3f74ec41f\", \"weblayer_browsertests\": \"7066531dac57daa99d7ffd6aa9ab3227247b6f9a\", \"weblayer_unittests\": \"35533bff9ea60065b979e1872c12579cf0695743\", \"wm_unittests\": \"a2d378e5ff6ebe587546a6af9860e5a037f11ce2\", \"wtf_unittests\": \"5ca43976e74e944f56170f9da2dec3a13f859821\", \"x11_unittests\": \"51c179cc78a785a5dd4a81e039b430e441b52e37\", \"xr_browser_tests\": \"9d3e53daed801cf8920b20e26cc841d61c788321\", \"zlib_unittests\": \"9414cacc00fbe44fa538bce9208b0e62facf6451\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_08506e22a0e0fdc29bd318165699b599f0671cf91287ea7a96642b02d7ff24e4_v2\", \"builder_361cbaa79261c75a92fc63a8f8c6fc117764238b3277286fa51086e6da3301df_v2\", \"builder_578c225c2c50d5653cce8ddfaab266952737b31d66b6113fbb64cc6f5229aebd_v2\", \"builder_5c906bad1aaeae24a2a7f043536626a3e8df9b2c7401ab4a0dd83c120911bddd_v2\", \"builder_bee64b857be6df8b7c1ab34ae7b2c6eab296c35495667b8b8424b2083d103b1e_v2\", \"builder_d0ba3cd99c36b697d8651140d51bf4b4f4b9cd9aa25af11363af5ede5f6e35b1_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-163-dpfz\"], \"image\": [\"chrome-xenial-20101800-2d400e1627f\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604025517110373",
"status": "COMPLETED",
"status_changed_ts": "1604028879259852",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/177161",
"builder:Linux Builder (dbg)",
"buildset:commit/git/7ee6edbfa65aed7067125f191ad0352f087b6e7a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7ee6edbfa65aed7067125f191ad0352f087b6e7a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030481119816302768",
"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/8865051057482833584/+/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:4f90308ab4d06410",
"user_agent:luci-scheduler"
],
"updated_ts": "1604028879266643",
"url": "https://ci.chromium.org/b/8865051057482833584",
"utcnow_ts": "1604028880889037"
}
}
[03:34:40.905] Invocation finished in 56m7.45706437s with status SUCCEEDED