[23:35:28.511] New invocation is queued and will start shortly
[23:35:29.810] Starting the invocation (attempt 1)
[23:35:29.942] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:35:29.942] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:35:29.942] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9059664861560217296",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ddcac35315d830b0aa08a9178c36857589f7ba48\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9059664861560217296",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/ddcac35315d830b0aa08a9178c36857589f7ba48",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ddcac35315d830b0aa08a9178c36857589f7ba48",
"gitiles_ref:refs/heads/master"
]
}
[23:35:32.081] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1576193731166743",
"id": "8894234798106918160",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ddcac35315d830b0aa08a9178c36857589f7ba48\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1576193731715691",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166019",
"builder:Linux Builder (dbg)",
"buildset:commit/git/ddcac35315d830b0aa08a9178c36857589f7ba48",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ddcac35315d830b0aa08a9178c36857589f7ba48",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059664861560217296",
"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/8894234798106918160/+/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": "1576193731715843",
"url": "https://ci.chromium.org/b/8894234798106918160",
"utcnow_ts": "1576193732064103"
}
}
[23:35:32.081] Task URL: https://ci.chromium.org/b/8894234798106918160
[23:35:32.081] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:2:0) after 1m0s
[23:36:32.077] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:2:0)
[23:36:32.077] Timer tick, asking Buildbucket for the build status
[23:36:32.434] Build 8894234798106918160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:32.434] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:3:0) after 1m0s
[23:37:32.468] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:3:0)
[23:37:32.468] Timer tick, asking Buildbucket for the build status
[23:37:32.797] Build 8894234798106918160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:32.798] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:4:0) after 1m0s
[23:38:32.831] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:4:0)
[23:38:32.831] Timer tick, asking Buildbucket for the build status
[23:38:33.060] Build 8894234798106918160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:33.060] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:5:0) after 1m0s
[23:39:33.119] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:5:0)
[23:39:33.119] Timer tick, asking Buildbucket for the build status
[23:39:33.724] Build 8894234798106918160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:33.724] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:6:0) after 1m0s
[23:40:09.638] Received PubSub notification, asking Buildbucket for the build status
[23:40:09.853] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:33.779] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:6:0)
[23:40:33.779] Timer tick, asking Buildbucket for the build status
[23:40:35.732] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:35.732] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:8:0) after 1m0s
[23:41:35.794] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:8:0)
[23:41:35.794] Timer tick, asking Buildbucket for the build status
[23:41:36.591] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:36.591] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:9:0) after 1m0s
[23:42:36.642] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:9:0)
[23:42:36.643] Timer tick, asking Buildbucket for the build status
[23:42:38.078] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:38.078] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:10:0) after 1m0s
[23:43:38.097] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:10:0)
[23:43:38.097] Timer tick, asking Buildbucket for the build status
[23:43:38.303] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:38.303] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:11:0) after 1m0s
[23:44:38.450] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:11:0)
[23:44:38.450] Timer tick, asking Buildbucket for the build status
[23:44:38.669] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:38.669] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:12:0) after 1m0s
[23:45:38.701] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:12:0)
[23:45:38.701] Timer tick, asking Buildbucket for the build status
[23:45:39.031] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:39.031] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:13:0) after 1m0s
[23:46:39.153] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:13:0)
[23:46:39.153] Timer tick, asking Buildbucket for the build status
[23:46:39.405] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:39.405] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:14:0) after 1m0s
[23:47:39.459] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:14:0)
[23:47:39.459] Timer tick, asking Buildbucket for the build status
[23:47:40.113] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:40.113] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:15:0) after 1m0s
[23:48:40.183] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:15:0)
[23:48:40.183] Timer tick, asking Buildbucket for the build status
[23:48:40.490] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:40.490] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:16:0) after 1m0s
[23:49:40.536] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:16:0)
[23:49:40.536] Timer tick, asking Buildbucket for the build status
[23:49:40.686] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:40.686] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:17:0) after 1m0s
[23:50:40.770] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:17:0)
[23:50:40.770] Timer tick, asking Buildbucket for the build status
[23:50:41.102] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:41.102] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:18:0) after 1m0s
[23:51:41.240] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:18:0)
[23:51:41.240] Timer tick, asking Buildbucket for the build status
[23:51:41.399] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:41.399] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:19:0) after 1m0s
[23:52:41.621] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:19:0)
[23:52:41.621] Timer tick, asking Buildbucket for the build status
[23:52:41.934] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:41.934] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:20:0) after 1m0s
[23:53:42.681] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:20:0)
[23:53:42.682] Timer tick, asking Buildbucket for the build status
[23:53:43.309] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:43.310] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:21:0) after 1m0s
[23:54:43.899] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:21:0)
[23:54:43.899] Timer tick, asking Buildbucket for the build status
[23:54:44.440] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:44.440] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:22:0) after 1m0s
[23:55:44.626] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:22:0)
[23:55:44.626] Timer tick, asking Buildbucket for the build status
[23:55:44.990] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:44.990] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:23:0) after 1m0s
[23:56:45.152] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:23:0)
[23:56:45.152] Timer tick, asking Buildbucket for the build status
[23:56:45.429] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:45.429] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:24:0) after 1m0s
[23:57:45.844] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:24:0)
[23:57:45.844] Timer tick, asking Buildbucket for the build status
[23:57:46.307] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:46.307] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:25:0) after 1m0s
[23:58:46.476] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:25:0)
[23:58:46.476] Timer tick, asking Buildbucket for the build status
[23:58:46.758] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:46.758] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:26:0) after 1m0s
[23:59:47.204] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:26:0)
[23:59:47.204] Timer tick, asking Buildbucket for the build status
[23:59:48.380] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:48.380] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:27:0) after 1m0s
[00:00:48.837] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:27:0)
[00:00:48.837] Timer tick, asking Buildbucket for the build status
[00:00:50.065] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:50.065] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:28:0) after 1m0s
[00:01:50.611] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:28:0)
[00:01:50.611] Timer tick, asking Buildbucket for the build status
[00:01:50.853] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:50.853] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:29:0) after 1m0s
[00:02:51.402] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:29:0)
[00:02:51.402] Timer tick, asking Buildbucket for the build status
[00:02:51.682] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:51.682] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:30:0) after 1m0s
[00:03:51.756] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:30:0)
[00:03:51.756] Timer tick, asking Buildbucket for the build status
[00:03:52.043] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:52.043] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:31:0) after 1m0s
[00:04:52.066] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:31:0)
[00:04:52.066] Timer tick, asking Buildbucket for the build status
[00:04:52.402] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:52.402] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:32:0) after 1m0s
[00:05:52.697] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:32:0)
[00:05:52.697] Timer tick, asking Buildbucket for the build status
[00:05:53.252] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:53.252] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:33:0) after 1m0s
[00:06:53.624] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:33:0)
[00:06:53.624] Timer tick, asking Buildbucket for the build status
[00:06:54.069] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:54.069] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:34:0) after 1m0s
[00:07:54.304] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:34:0)
[00:07:54.304] Timer tick, asking Buildbucket for the build status
[00:07:54.911] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:54.911] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:35:0) after 1m0s
[00:08:55.178] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:35:0)
[00:08:55.178] Timer tick, asking Buildbucket for the build status
[00:08:55.480] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:55.480] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:36:0) after 1m0s
[00:09:55.566] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:36:0)
[00:09:55.566] Timer tick, asking Buildbucket for the build status
[00:09:56.053] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:56.053] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:37:0) after 1m0s
[00:10:56.328] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:37:0)
[00:10:56.328] Timer tick, asking Buildbucket for the build status
[00:10:56.907] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:56.907] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:38:0) after 1m0s
[00:11:57.254] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:38:0)
[00:11:57.254] Timer tick, asking Buildbucket for the build status
[00:11:58.191] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:58.191] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:39:0) after 1m0s
[00:12:58.208] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:39:0)
[00:12:58.208] Timer tick, asking Buildbucket for the build status
[00:12:58.605] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:58.605] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:40:0) after 1m0s
[00:13:58.746] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:40:0)
[00:13:58.746] Timer tick, asking Buildbucket for the build status
[00:13:59.658] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:59.658] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:41:0) after 1m0s
[00:14:59.719] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:41:0)
[00:14:59.719] Timer tick, asking Buildbucket for the build status
[00:15:02.871] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:02.871] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:42:0) after 1m0s
[00:16:03.383] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:42:0)
[00:16:03.383] Timer tick, asking Buildbucket for the build status
[00:16:04.105] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:04.105] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:43:0) after 1m0s
[00:17:04.376] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:43:0)
[00:17:04.376] Timer tick, asking Buildbucket for the build status
[00:17:05.339] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:05.339] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:44:0) after 1m0s
[00:18:05.399] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:44:0)
[00:18:05.399] Timer tick, asking Buildbucket for the build status
[00:18:05.756] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:05.756] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:45:0) after 1m0s
[00:19:05.864] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:45:0)
[00:19:05.864] Timer tick, asking Buildbucket for the build status
[00:19:06.114] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:06.114] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:46:0) after 1m0s
[00:20:06.866] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:46:0)
[00:20:06.866] Timer tick, asking Buildbucket for the build status
[00:20:07.157] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:07.157] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:47:0) after 1m0s
[00:21:07.177] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:47:0)
[00:21:07.177] Timer tick, asking Buildbucket for the build status
[00:21:07.675] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:07.675] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:48:0) after 1m0s
[00:22:07.814] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:48:0)
[00:22:07.814] Timer tick, asking Buildbucket for the build status
[00:22:08.424] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:08.424] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:49:0) after 1m0s
[00:23:08.523] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:49:0)
[00:23:08.523] Timer tick, asking Buildbucket for the build status
[00:23:08.712] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:08.712] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:50:0) after 1m0s
[00:24:08.763] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:50:0)
[00:24:08.763] Timer tick, asking Buildbucket for the build status
[00:24:09.276] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:09.276] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:51:0) after 1m0s
[00:25:09.383] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:51:0)
[00:25:09.383] Timer tick, asking Buildbucket for the build status
[00:25:09.908] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:09.908] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:52:0) after 1m0s
[00:26:10.093] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:52:0)
[00:26:10.093] Timer tick, asking Buildbucket for the build status
[00:26:10.760] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:10.760] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:53:0) after 1m0s
[00:27:10.834] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:53:0)
[00:27:10.834] Timer tick, asking Buildbucket for the build status
[00:27:11.368] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:11.368] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:54:0) after 1m0s
[00:28:11.540] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:54:0)
[00:28:11.540] Timer tick, asking Buildbucket for the build status
[00:28:11.747] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:11.747] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:55:0) after 1m0s
[00:29:12.166] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:55:0)
[00:29:12.166] Timer tick, asking Buildbucket for the build status
[00:29:12.990] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:12.990] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:56:0) after 1m0s
[00:30:13.042] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:56:0)
[00:30:13.042] Timer tick, asking Buildbucket for the build status
[00:30:15.904] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:15.904] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:57:0) after 1m0s
[00:31:16.241] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:57:0)
[00:31:16.241] Timer tick, asking Buildbucket for the build status
[00:31:16.598] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:16.598] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:58:0) after 1m0s
[00:32:17.113] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:58:0)
[00:32:17.113] Timer tick, asking Buildbucket for the build status
[00:32:18.371] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:18.371] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:59:0) after 1m0s
[00:33:18.444] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:59:0)
[00:33:18.444] Timer tick, asking Buildbucket for the build status
[00:33:19.085] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:19.085] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:60:0) after 1m0s
[00:34:19.211] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:60:0)
[00:34:19.211] Timer tick, asking Buildbucket for the build status
[00:34:20.661] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:20.661] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:61:0) after 1m0s
[00:35:20.762] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:61:0)
[00:35:20.762] Timer tick, asking Buildbucket for the build status
[00:35:21.469] Build 8894234798106918160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:21.469] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059664861560217296:62:0) after 1m0s
[00:36:08.773] Received PubSub notification, asking Buildbucket for the build status
[00:36:08.970] Build 8894234798106918160: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:36:08.970] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1576197367833719",
"created_by": "project:chromium",
"created_ts": "1576193731166743",
"id": "8894234798106918160",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ddcac35315d830b0aa08a9178c36857589f7ba48\"}}",
"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-east1-d-8-103-2en0\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 166019, \"got_angle_revision\": \"0bf3c4b585afa0e7849df8efa38e464140da6a3e\", \"got_dawn_revision\": \"5c413afdc7f8ae44071b7520e828bc75f0e4a4ed\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"ddcac35315d830b0aa08a9178c36857589f7ba48\", \"got_revision_cp\": \"refs/heads/master@{#724401}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"07958d452f93b6513ebf5cbae64a1325c1755dbf\", \"got_v8_revision\": \"724fd9f1ece40deb98f111c77029733c8f735d8a\", \"got_v8_revision_cp\": \"refs/heads/8.1.27@{#1}\", \"got_webrtc_revision\": \"26fe811623627e1089a8df2c86a89dda2cd9d338\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30068}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ddcac35315d830b0aa08a9178c36857589f7ba48\", \"swarm_hashes_refs/heads/master(at){#724401}_without_patch\": {\"accessibility_unittests\": \"c112b8555ebfe5788cde49a6ea3b026c704a2cfd\", \"angle_unittests\": \"8f5aee17ef318068a40b77d878674c32f1ebf72e\", \"app_shell_unittests\": \"d7aa7609e6c2070c8928b8f0653b844a3bef948d\", \"aura_unittests\": \"8d26d912abc252b091695408130c3d27406c7899\", \"base_unittests\": \"20325d6087b403c868ec66841267fbf9d7860768\", \"base_util_unittests\": \"3f4a8ddc9768b5cdcfb93fa532814fde425da7ca\", \"blink_common_unittests\": \"67ac9f8e32497daf93a8a34c4dc6c9f8b8b395f3\", \"blink_fuzzer_unittests\": \"b7ffeb1f56f34858daeb7168f11df88e02047850\", \"blink_heap_unittests\": \"e07eff80ce1b6c7279fcf944a58906b6dee92a9d\", \"blink_platform_unittests\": \"f689b62bacf42eaab5b2f8419f1ac4c655db3b8a\", \"blink_python_tests\": \"4f9ec9f8b259545c4c13f1c64a6406b5f6a959fd\", \"blink_unittests\": \"d19b1adf2841c3d35b14d8a27bc7a85d84322c4a\", \"blink_web_tests\": \"49d93ed2db333c4e95770f5428d8467e8bc3b51a\", \"boringssl_crypto_tests\": \"e4b14083ab2bb9f72b26a68f3ca839a979e7b9f9\", \"boringssl_ssl_tests\": \"586f4cdca1cc15fdbc971d930c1ce420b45ab5b8\", \"browser_tests\": \"8bf140bca6a782b8fe584bc991384aa8045f3f20\", \"cacheinvalidation_unittests\": \"3c959a661153aca6c9f34f06350ce47cc0eb1003\", \"capture_unittests\": \"0d6e803bef60cd63c0289bf38d1bee35b7a7b5cf\", \"cast_unittests\": \"02c3039ac1712f5422fe461f81ee9881ce183c26\", \"cc_unittests\": \"a990d6cb5f84a87623e2599d7da1a74bff070394\", \"chrome_app_unittests\": \"607a929bd56c248759934b38d20636c81a49e190\", \"chromedriver_unittests\": \"7109ac6425063c719de063c5ff9411d65c9e234b\", \"components_browsertests\": \"590710437e7bc2a3463981c671e743ee073f3655\", \"components_unittests\": \"522797a44482b88cbebac272007ed8b035add429\", \"compositor_unittests\": \"327ca53179eba9659cbb0681e7ebf1f91275bdd8\", \"content_browsertests\": \"ed5bd89934e1c75e68a0d7ea8caf2aa78cb6a931\", \"content_shell_crash_test\": \"eb4d776bfb212cd237ed4cefe1cea6b8b716fac7\", \"content_unittests\": \"e924920922d05a484334d0b72db28ca37ca243c5\", \"crashpad_tests\": \"28c9ddeb4fb5ea7b13bdcccfd0662c8bbdb90dd8\", \"cronet_tests\": \"c4bfb3af8f7f2463775ab631f28b42e8f735e47e\", \"cronet_unittests\": \"b74329ffd45065b6826985557c1f2f6671793bb3\", \"crypto_unittests\": \"8d4386b722cc9f7712b771fe7d7aa821a78770c6\", \"dbus_unittests\": \"f8f0e29bd851455c47be21313782e78fcc0d3c0f\", \"device_unittests\": \"9caed2a733295c6b29b953bf4030bc6a4cd95117\", \"devtools_lint_check\": \"6c6a97f3c5dbcb352e8c4122493c6e2554ec0eac\", \"devtools_type_check\": \"c0d5907f6347abe0be6686402e71990f45936158\", \"display_unittests\": \"ee0f7d1832ed4d93afcbe24dc92e1334cccbfc5b\", \"events_unittests\": \"a63d103506a37606d362d33b76f9c993de468596\", \"extensions_browsertests\": \"26f8a34d930942d00404b450dbf094fda9d68c85\", \"extensions_unittests\": \"efdd7924a8e8102094d0e3955ff830d065fea0bd\", \"filesystem_service_unittests\": \"59742c74b67704ad678412a8b9d7b99f04ac18ec\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"730d6f1ecfdb3786a8244122cc4063b8d8633499\", \"gfx_unittests\": \"6ce96e788776ab1855988fb8330d5dd35c6e7bc2\", \"gin_unittests\": \"6b322f109bcbd2682ebac164cd960c5814ab672b\", \"google_apis_unittests\": \"5af7226bd7bab8ebf179e3a304a31c99c2c73021\", \"gpu_unittests\": \"d5150fc710c928e77168edcfb58881a214a78dd3\", \"grit_python_unittests\": \"25dff9ef6327c596609d376409bc1d0e605b6d43\", \"gwp_asan_unittests\": \"8f8d888dffdeb1fd43b38593fcc979621931a896\", \"headless_browsertests\": \"a8512e6090817ffeebc624c2c47a7bab532ad8d6\", \"headless_unittests\": \"6036658c8466145032ff049cb309098a22cbf42c\", \"interactive_ui_tests\": \"f191688fd1d50d3a644261ae46f5ee4dddb65a44\", \"ipc_tests\": \"1a878348d9beb3b11d76779b59d266e1b352ba71\", \"jingle_unittests\": \"0c8735a55b8de2b26e9000f6c098ea30a7ca9c07\", \"latency_unittests\": \"a257d243b4340def4048d8a2712d0eda3bb80bb3\", \"libjingle_xmpp_unittests\": \"2b45ad08a8834741da563f57cac8ea41735910e0\", \"media_blink_unittests\": \"d969ee54ab5242c701e4ccc9ddc84abbe58222f6\", \"media_service_unittests\": \"b5bf1d6c12146948379809df751aef99b373c748\", \"media_unittests\": \"756055ff9c4b84df9bafdfcd867586ca87fc9b8d\", \"message_center_unittests\": \"97c57d2743dcc822a35ebb870b2de6f7801dd2a1\", \"metrics_python_tests\": \"853fa2427dd246638470b898c7cf06ac1281d3f4\", \"midi_unittests\": \"8055be88af67ed7a881c28b580b55be4e276b762\", \"mojo_core_unittests\": \"7bfdaa1f062463fcac794719732d0ebb96126d86\", \"mojo_unittests\": \"471b80d8e08e4794cc3efdc3df288c14928d5ea5\", \"nacl_helper_nonsfi_unittests\": \"ddec84fc9ec0bcc0c34b9a26e4efbc6e128666b9\", \"nacl_loader_unittests\": \"849da2e616959ab5cfef93ad28a74ef7eb35b909\", \"native_theme_unittests\": \"40d634a37417d8d36d9811304653661412ba66be\", \"net_unittests\": \"5f8af3ddf6d9ac5d85943c5462995509f9a3d70c\", \"pdf_unittests\": \"47f297dbf2a1b654ae838dc156212d1e2ec018d6\", \"perfetto_unittests\": \"90c522fd6e1ee1b394b5e3d062866691b667f067\", \"ppapi_unittests\": \"ab6a3ded00ac97ff957a4485cf49106f911413ce\", \"printing_unittests\": \"dbb6ab6c36459fb07596b79a2e7f2044c888f80a\", \"remoting_unittests\": \"d81a15d489b0edde20fc744c678418574fc7ccaf\", \"sandbox_linux_unittests\": \"5e3aac58544fbcb14043f6d58ae6d99c763d763f\", \"service_manager_unittests\": \"fe26e7294febf9b627dff91c242598ed655c4930\", \"services_unittests\": \"a00a3e9636a2f198dfa2161a375f7ff79881e3b1\", \"shell_dialogs_unittests\": \"16e935bbffbd019e3cc9452631b8b3897a93ce26\", \"skia_unittests\": \"9ad2d4c943dedf4f29b8048a924b6c14b47b1e1c\", \"snapshot_unittests\": \"c6d780a689f3c34c5310b442d327dbba376fbe64\", \"sql_unittests\": \"908b75195a407a10497bc30c768a8227fd17fdeb\", \"storage_unittests\": \"a069dac79e20db0f0ad5cf7ed586811908e9a662\", \"sync_integration_tests\": \"bff985743bfa2e494ed65c1301fedbfb2a6a64d2\", \"telemetry_gpu_unittests\": \"9695e7fe1a153ccef4fee9daafaf87333ddc603a\", \"telemetry_perf_unittests\": \"e0f4cb5581704e61a39e6162c174da7671bc39cd\", \"telemetry_unittests\": \"7c94b826506468d062e918d88c2df14b4fe0f954\", \"traffic_annotation_auditor_unittests\": \"b7ccfe94b7e4fe30fcbaecd6c307b00584947df7\", \"ui_base_unittests\": \"377ab4ae87fd852685b88f3381de05a6c0bc71bc\", \"ui_touch_selection_unittests\": \"43d0b2e0e855358a6919cae7f6ad1714936f8003\", \"unit_tests\": \"8aa99aa14ef85636a6ec11fd8c6ec73316fc7471\", \"url_unittests\": \"14260a12a03e1abc564f3715c9c45b9b66167bfe\", \"views_perftests\": \"c38718a8563f61f14d2127118dafd5bf333ff464\", \"views_unittests\": \"73be363a8c50f274402d6937c8e45eac60153c43\", \"viz_unittests\": \"751dae9c1dd3f91e5e88230579c6279b3981b613\", \"vr_common_unittests\": \"8fcac0f012a57e6fded41090a548ff95ff00fe9f\", \"vr_pixeltests\": \"fc6514598c54e608f482307f497579ad95b14916\", \"webdriver_wpt_tests\": \"fa35bf61c97ca01827eaf9880c929a062e66a465\", \"weblayer_browsertests\": \"45fd18447d6d2d6341a479609ee9c4a4f26ef8ce\", \"wm_unittests\": \"7fad74c9c0a2661a77e66f6875479aeb91d6377c\", \"wtf_unittests\": \"9f45f854d2c8bc5050cf77641395eb540fe2ebb9\", \"xr_browser_tests\": \"010cfac9e2b02a43ba9353f6d024ca2bb1f15c06\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_387e931c7b633ee2f48b892df14288ffa2061feda74b30444810d565fa474120_v2\", \"builder_3d74f5cea4cbf91e4b26f92a522c5c74e61b738440ef29ba16f5c9a596a5b201_v2\", \"builder_61faa0d0c1c079ecf670d8cfa4f78ad3b1687a5534233c7377462c3ee6819708_v2\", \"builder_662609c3a18ad850d61ba5f58749e60478bc4d9d576475e0c738a1c9e2d8d890_v2\", \"builder_a1b9c6892397d61c3624774723419f5ae48fcf776e7d2304bd4682bf5cd4c049_v2\", \"builder_a9a41095618141e4757e131cf792803c0a27ecf4e01eb5d0d372c002309e764f_v2\", \"builder_bee64b857be6df8b7c1ab34ae7b2c6eab296c35495667b8b8424b2083d103b1e_v2\", \"builder_c33cb68a27562f3c0030aeadb8872bc9395ca543fdfb70fdbe284a5ae715c202_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-east1-d-8-103-2en0\"], \"image\": [\"chrome-xenial-19120100-f3a09baa543\"], \"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\": [\"4713-a14a20b\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1576194005760573",
"status": "COMPLETED",
"status_changed_ts": "1576197368262432",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166019",
"builder:Linux Builder (dbg)",
"buildset:commit/git/ddcac35315d830b0aa08a9178c36857589f7ba48",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ddcac35315d830b0aa08a9178c36857589f7ba48",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059664861560217296",
"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/8894234798106918160/+/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:4915496c47329310",
"user_agent:luci-scheduler"
],
"updated_ts": "1576197368325269",
"url": "https://ci.chromium.org/b/8894234798106918160",
"utcnow_ts": "1576197368955200"
}
}
[00:36:08.970] Invocation finished in 1h0m40.481259306s with status SUCCEEDED