[02:57:42.235] New invocation is queued and will start shortly
[02:57:43.314] Starting the invocation (attempt 1)
[02:57:43.352] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:57:43.352] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:57:43.353] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9053400947739636976",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b7acd7738deb9e94e059d5b6e62203a37eb6158e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9053400947739636976",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/b7acd7738deb9e94e059d5b6e62203a37eb6158e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b7acd7738deb9e94e059d5b6e62203a37eb6158e",
"gitiles_ref:refs/heads/master"
]
}
[02:57:44.090] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1582167463439018",
"id": "8887970885815652048",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b7acd7738deb9e94e059d5b6e62203a37eb6158e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582167463828531",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168808",
"builder:Linux Builder (dbg)",
"buildset:commit/git/b7acd7738deb9e94e059d5b6e62203a37eb6158e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b7acd7738deb9e94e059d5b6e62203a37eb6158e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053400947739636976",
"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/8887970885815652048/+/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": "1582167463828632",
"url": "https://ci.chromium.org/b/8887970885815652048",
"utcnow_ts": "1582167464076600"
}
}
[02:57:44.090] Task URL: https://ci.chromium.org/b/8887970885815652048
[02:57:44.090] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:2:0) after 1m0s
[02:58:44.112] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:2:0)
[02:58:44.112] Timer tick, asking Buildbucket for the build status
[02:58:44.482] Build 8887970885815652048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:58:44.482] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:3:0) after 1m0s
[02:59:44.573] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:3:0)
[02:59:44.573] Timer tick, asking Buildbucket for the build status
[02:59:44.709] Build 8887970885815652048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:59:44.709] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:4:0) after 1m0s
[03:00:44.799] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:4:0)
[03:00:44.799] Timer tick, asking Buildbucket for the build status
[03:00:45.244] Build 8887970885815652048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:00:45.244] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:5:0) after 1m0s
[03:01:45.311] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:5:0)
[03:01:45.311] Timer tick, asking Buildbucket for the build status
[03:01:45.532] Build 8887970885815652048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:01:45.532] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:6:0) after 1m0s
[03:02:26.014] Received PubSub notification, asking Buildbucket for the build status
[03:02:26.171] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:45.603] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:6:0)
[03:02:45.603] Timer tick, asking Buildbucket for the build status
[03:02:45.695] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:45.695] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:8:0) after 1m0s
[03:03:45.702] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:8:0)
[03:03:45.702] Timer tick, asking Buildbucket for the build status
[03:03:45.880] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:45.880] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:9:0) after 1m0s
[03:04:45.982] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:9:0)
[03:04:45.983] Timer tick, asking Buildbucket for the build status
[03:04:46.095] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:46.095] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:10:0) after 1m0s
[03:05:46.114] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:10:0)
[03:05:46.114] Timer tick, asking Buildbucket for the build status
[03:05:46.250] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:46.250] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:11:0) after 1m0s
[03:06:46.293] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:11:0)
[03:06:46.293] Timer tick, asking Buildbucket for the build status
[03:06:46.377] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:46.377] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:12:0) after 1m0s
[03:07:46.404] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:12:0)
[03:07:46.404] Timer tick, asking Buildbucket for the build status
[03:07:46.495] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:46.495] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:13:0) after 1m0s
[03:08:46.513] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:13:0)
[03:08:46.513] Timer tick, asking Buildbucket for the build status
[03:08:46.957] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:46.957] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:14:0) after 1m0s
[03:09:46.984] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:14:0)
[03:09:46.984] Timer tick, asking Buildbucket for the build status
[03:09:47.087] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:47.087] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:15:0) after 1m0s
[03:10:47.111] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:15:0)
[03:10:47.111] Timer tick, asking Buildbucket for the build status
[03:10:47.368] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:47.368] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:16:0) after 1m0s
[03:11:47.401] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:16:0)
[03:11:47.401] Timer tick, asking Buildbucket for the build status
[03:11:47.624] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:47.624] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:17:0) after 1m0s
[03:12:47.615] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:17:0)
[03:12:47.615] Timer tick, asking Buildbucket for the build status
[03:12:48.012] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:48.012] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:18:0) after 1m0s
[03:13:48.048] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:18:0)
[03:13:48.048] Timer tick, asking Buildbucket for the build status
[03:13:48.281] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:48.281] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:19:0) after 1m0s
[03:14:48.273] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:19:0)
[03:14:48.273] Timer tick, asking Buildbucket for the build status
[03:14:48.389] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:48.389] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:20:0) after 1m0s
[03:15:48.429] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:20:0)
[03:15:48.429] Timer tick, asking Buildbucket for the build status
[03:15:48.666] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:48.666] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:21:0) after 1m0s
[03:16:48.758] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:21:0)
[03:16:48.758] Timer tick, asking Buildbucket for the build status
[03:16:49.209] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:49.209] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:22:0) after 1m0s
[03:17:49.266] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:22:0)
[03:17:49.266] Timer tick, asking Buildbucket for the build status
[03:17:49.545] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:49.545] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:23:0) after 1m0s
[03:18:49.661] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:23:0)
[03:18:49.661] Timer tick, asking Buildbucket for the build status
[03:18:49.789] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:49.789] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:24:0) after 1m0s
[03:19:49.844] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:24:0)
[03:19:49.844] Timer tick, asking Buildbucket for the build status
[03:19:50.019] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:50.019] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:25:0) after 1m0s
[03:20:50.087] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:25:0)
[03:20:50.087] Timer tick, asking Buildbucket for the build status
[03:20:50.284] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:50.284] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:26:0) after 1m0s
[03:21:50.352] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:26:0)
[03:21:50.352] Timer tick, asking Buildbucket for the build status
[03:21:50.443] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:50.443] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:27:0) after 1m0s
[03:22:50.479] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:27:0)
[03:22:50.479] Timer tick, asking Buildbucket for the build status
[03:22:50.704] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:50.704] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:28:0) after 1m0s
[03:23:50.887] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:28:0)
[03:23:50.887] Timer tick, asking Buildbucket for the build status
[03:23:51.053] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:51.053] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:29:0) after 1m0s
[03:24:51.146] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:29:0)
[03:24:51.146] Timer tick, asking Buildbucket for the build status
[03:24:51.269] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:51.269] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:30:0) after 1m0s
[03:25:51.243] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:30:0)
[03:25:51.243] Timer tick, asking Buildbucket for the build status
[03:25:51.381] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:51.381] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:31:0) after 1m0s
[03:26:51.443] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:31:0)
[03:26:51.443] Timer tick, asking Buildbucket for the build status
[03:26:51.552] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:51.552] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:32:0) after 1m0s
[03:27:51.619] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:32:0)
[03:27:51.619] Timer tick, asking Buildbucket for the build status
[03:27:51.717] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:51.718] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:33:0) after 1m0s
[03:28:51.789] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:33:0)
[03:28:51.789] Timer tick, asking Buildbucket for the build status
[03:28:51.990] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:51.990] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:34:0) after 1m0s
[03:29:52.149] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:34:0)
[03:29:52.149] Timer tick, asking Buildbucket for the build status
[03:29:52.259] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:52.259] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:35:0) after 1m0s
[03:30:52.349] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:35:0)
[03:30:52.349] Timer tick, asking Buildbucket for the build status
[03:30:52.475] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:52.475] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:36:0) after 1m0s
[03:31:52.465] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:36:0)
[03:31:52.465] Timer tick, asking Buildbucket for the build status
[03:31:52.557] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:52.557] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:37:0) after 1m0s
[03:32:52.565] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:37:0)
[03:32:52.565] Timer tick, asking Buildbucket for the build status
[03:32:52.673] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:52.673] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:38:0) after 1m0s
[03:33:52.854] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:38:0)
[03:33:52.854] Timer tick, asking Buildbucket for the build status
[03:33:52.974] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:52.974] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:39:0) after 1m0s
[03:34:53.124] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:39:0)
[03:34:53.124] Timer tick, asking Buildbucket for the build status
[03:34:53.302] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:53.302] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:40:0) after 1m0s
[03:35:53.517] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:40:0)
[03:35:53.517] Timer tick, asking Buildbucket for the build status
[03:35:53.631] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:53.631] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:41:0) after 1m0s
[03:36:54.358] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:41:0)
[03:36:54.358] Timer tick, asking Buildbucket for the build status
[03:36:54.494] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:54.494] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:42:0) after 1m0s
[03:37:54.584] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:42:0)
[03:37:54.584] Timer tick, asking Buildbucket for the build status
[03:37:54.676] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:54.676] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:43:0) after 1m0s
[03:38:54.750] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:43:0)
[03:38:54.750] Timer tick, asking Buildbucket for the build status
[03:38:55.136] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:55.136] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:44:0) after 1m0s
[03:39:55.203] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:44:0)
[03:39:55.203] Timer tick, asking Buildbucket for the build status
[03:39:55.312] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:55.312] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:45:0) after 1m0s
[03:40:55.317] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:45:0)
[03:40:55.317] Timer tick, asking Buildbucket for the build status
[03:40:55.478] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:55.478] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:46:0) after 1m0s
[03:41:55.521] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:46:0)
[03:41:55.521] Timer tick, asking Buildbucket for the build status
[03:41:55.666] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:55.666] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:47:0) after 1m0s
[03:42:55.797] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:47:0)
[03:42:55.797] Timer tick, asking Buildbucket for the build status
[03:42:56.103] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:56.103] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:48:0) after 1m0s
[03:43:56.122] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:48:0)
[03:43:56.122] Timer tick, asking Buildbucket for the build status
[03:43:56.372] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:56.372] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:49:0) after 1m0s
[03:44:56.481] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:49:0)
[03:44:56.481] Timer tick, asking Buildbucket for the build status
[03:44:56.973] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:56.973] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:50:0) after 1m0s
[03:45:57.044] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:50:0)
[03:45:57.044] Timer tick, asking Buildbucket for the build status
[03:45:57.303] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:57.303] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:51:0) after 1m0s
[03:46:57.622] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:51:0)
[03:46:57.622] Timer tick, asking Buildbucket for the build status
[03:46:57.709] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:57.709] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:52:0) after 1m0s
[03:47:57.846] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:52:0)
[03:47:57.846] Timer tick, asking Buildbucket for the build status
[03:47:58.044] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:58.044] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:53:0) after 1m0s
[03:48:58.172] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:53:0)
[03:48:58.172] Timer tick, asking Buildbucket for the build status
[03:48:58.507] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:58.507] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:54:0) after 1m0s
[03:49:58.614] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:54:0)
[03:49:58.614] Timer tick, asking Buildbucket for the build status
[03:49:58.863] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:58.863] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:55:0) after 1m0s
[03:50:58.876] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:55:0)
[03:50:58.876] Timer tick, asking Buildbucket for the build status
[03:50:58.983] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:58.983] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:56:0) after 1m0s
[03:51:59.017] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:56:0)
[03:51:59.017] Timer tick, asking Buildbucket for the build status
[03:51:59.250] Build 8887970885815652048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:59.250] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053400947739636976:57:0) after 1m0s
[03:52:21.651] Received PubSub notification, asking Buildbucket for the build status
[03:52:21.745] Build 8887970885815652048: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:52:21.745] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1582170740898489",
"created_by": "project:chromium",
"created_ts": "1582167463439018",
"id": "8887970885815652048",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b7acd7738deb9e94e059d5b6e62203a37eb6158e\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-62-hnx1\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168808, \"got_angle_revision\": \"2801bf43716e8642efe6132c50d2a1708756bba8\", \"got_dawn_revision\": \"b330ea6b491e3e000c11fe919e8099dece1c939b\", \"got_nacl_revision\": \"530e1f0dae34020864c81e5cd52be54d9c656690\", \"got_revision\": \"b7acd7738deb9e94e059d5b6e62203a37eb6158e\", \"got_revision_cp\": \"refs/heads/master@{#742938}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"8def9063c4fdefa8e644198d2254ef279952c88b\", \"got_v8_revision\": \"07a0ee92dfd89a66d57b9fb242d90e33607e3c44\", \"got_v8_revision_cp\": \"refs/heads/8.2.120@{#1}\", \"got_webrtc_revision\": \"1282babe6621d3a2084bbd24e77652e0b3a4eb93\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30549}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b7acd7738deb9e94e059d5b6e62203a37eb6158e\", \"swarm_hashes_refs/heads/master(at){#742938}_without_patch\": {\"accessibility_unittests\": \"d35c31fadeb1cea39ba6e0afa14943a842b35bb6\", \"angle_unittests\": \"04c0617279978e7cf923dcf6476483df55446fc5\", \"app_shell_unittests\": \"365d9fad5e6229202c28d7c2995b4f89f7cb1971\", \"aura_unittests\": \"7887514dee073696ccf8a5f77334c8f25229abe2\", \"base_unittests\": \"c151c6cabf6556ffbb11f3838721dbc86416e75e\", \"base_util_unittests\": \"774aa7bbcc06277cee27287eb6c31a57094fbfd8\", \"blink_common_unittests\": \"38ca181c3b42e316fdf0dadad3a7c8ced6fa4bc7\", \"blink_fuzzer_unittests\": \"d6ac2278245a8a01fe21e1285c6f00a116b89f0b\", \"blink_heap_unittests\": \"c2d872a03707f632291fd24b89ec0e4b8f1e9b1a\", \"blink_platform_unittests\": \"7e7e5ffe87a7947d0e4c08bd0f8e570e198de1f5\", \"blink_python_tests\": \"eaf23057c19182bb72145a5d53671fd1eb4cf5bf\", \"blink_unittests\": \"f530397c12db0c184de7a33636d2a61314736d0f\", \"blink_web_tests\": \"0d20b5e9b178f98b4421e9abd3d9167009c86125\", \"boringssl_crypto_tests\": \"e134738b0b11b0c76b1f6ae86b8180418508349a\", \"boringssl_ssl_tests\": \"2f79b72cc9bea4cf1dfbeab03c32b34daa6e9708\", \"browser_tests\": \"23f2a1fe66960e43f8935e7bcc3e4cf6047d6fe4\", \"cacheinvalidation_unittests\": \"58101dc47a4877ae1278842a018e0c70e368c9f7\", \"capture_unittests\": \"986e4341f1d4cbe298ec072689315038ae031209\", \"cast_unittests\": \"80cb2c874b6f3ad672ae2fcc1c14e5429838ba99\", \"cc_unittests\": \"0ddf86cc3d9b84bead96c2a93f4e2b014909a26b\", \"chrome_app_unittests\": \"7f1ddaf86090789772f6dd770bec6fc2baa86f81\", \"chromedriver_unittests\": \"01e84ea83d3e1e5cea1e87e6436bd339e5e7a545\", \"components_browsertests\": \"2f3fde474e7286dc71bf497a7fa1509c5840133e\", \"components_unittests\": \"723e487931bb1433ef211817eea16ca901a2b1a8\", \"compositor_unittests\": \"0157ea2a93e6c167cd07501365e640a50302a2e7\", \"content_browsertests\": \"9f843874b2d13d35bc9d6b90bf9e4c0c1a6ee45d\", \"content_shell_crash_test\": \"e7a9e39d6a4cf0c6e1d5a7be15317d7d2805ceed\", \"content_unittests\": \"7c1ec9aa0a76db00b5baff27777c58bbbdeb0249\", \"crashpad_tests\": \"50f14793322b6fe88b1bc4a19d554cfa98dbc2eb\", \"cronet_tests\": \"bf19d0446386e143ef7a59f67154601ee99f1324\", \"cronet_unittests\": \"71655b52627db969d01a1584f94b6edb0f170784\", \"crypto_unittests\": \"1a3c1bb87b9adc899c71c6a674e750c4eda2e0f1\", \"dbus_unittests\": \"cf3f5200c3b9aa67fa6545afbcd65a15038bf01a\", \"device_unittests\": \"014f012ee37ae81051236cba75d571203e9e3e5d\", \"display_unittests\": \"365d82e373d602485d7f2307268287af6f553558\", \"events_unittests\": \"f67746cecc4e38fbac01e5943c3840a2b301f0ea\", \"extensions_browsertests\": \"2c3acc43ddab8cda0594bd60544b265aeecfacd8\", \"extensions_unittests\": \"e5cf36d5441360b3aec9d36bf00fede054da2bb6\", \"filesystem_service_unittests\": \"611685285dd0c5ea034e6d9bda28416a596d6228\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"cbc1699d23a4474aff203dd8e30c9eca1387b9bc\", \"gfx_unittests\": \"f4fd00d9ae7c782e7bdb303d0b7e06cd28de2ff5\", \"gin_unittests\": \"8a7808d309805a8c18d91f4c68ff2c836b8daa7e\", \"google_apis_unittests\": \"2bc55685ff82a7d971d98693fc577c0a45103295\", \"gpu_unittests\": \"1bd7009a15e911a19e69ee3f403e8560f146daaf\", \"grit_python_unittests\": \"0a186e54539d3475bae4b9d19cc134d9a92e2108\", \"gwp_asan_unittests\": \"6542385fbcebfd1876b97303d131d873ed011186\", \"headless_browsertests\": \"f1b4361732f5feb1f9cb9fbeea57d9537bfa9f0c\", \"headless_unittests\": \"821899a3421053dbd44e4c60919197a85814be8b\", \"interactive_ui_tests\": \"8ea69a0395b2069cb903d5dc794e5b804e64fbcd\", \"ipc_tests\": \"3993843d75201d11289d306a46557ee8a1fd40e8\", \"jingle_unittests\": \"b5cf6c0abf6c2003ad5e8f064032c59375acc92c\", \"latency_unittests\": \"e8b9707e98eb434cc6d16544dc2cc93b7d92988e\", \"libjingle_xmpp_unittests\": \"8228cfe0f5a80693ba55a4994d7826b3369d9333\", \"media_blink_unittests\": \"e9f430a368f0c370e1769f54caf483281298eb0b\", \"media_service_unittests\": \"09a0bb7fd2250dfcbb083e506df12b6d36e105ab\", \"media_unittests\": \"677729ccf3cf89cf84d05ec983382a09cac73380\", \"message_center_unittests\": \"86a9ba51f1a28ccee02d502625f47290773a352a\", \"metrics_python_tests\": \"56f08dd178b96a96cd478ddf1c5555d691967cc1\", \"midi_unittests\": \"fb080403e4747e4eae73a5e77620f8e4bebb9486\", \"mojo_core_unittests\": \"c54fd327134f787dbcd0e014c81323c81985d8fc\", \"mojo_unittests\": \"b6b46799ec90562d9e260a46ba485fb7b81f318f\", \"nacl_helper_nonsfi_unittests\": \"4c5a928605cc724131db967c95cbac313483ad4e\", \"nacl_loader_unittests\": \"883d7b21e492eedd51cd286615ce0bf7d7ae6ef9\", \"native_theme_unittests\": \"dd6b7f890c4df593bb22cee168e0f20248df315e\", \"net_unittests\": \"b148f96881be413a4bd00693fa5823cf47cb209f\", \"pdf_unittests\": \"25b4a86a8b52ea441475a4cb62001fd5a393faca\", \"perfetto_unittests\": \"9ce53e1433e7675d0377e67507ac1fb542d70bd4\", \"ppapi_unittests\": \"2fdf752ef42d25c0ed5a43ac5c3d5ea305abef07\", \"printing_unittests\": \"2ccb4a8d1caf61ee19158240b8ccc47c89259982\", \"remoting_unittests\": \"5741bcc23743f747198afac29108a6a1bb16a245\", \"sandbox_linux_unittests\": \"235180178d48bbdb5576d9f9634c38e2de9cb175\", \"service_manager_unittests\": \"a5d0a7e1db71247b0c91cef8102791fe2ca435f1\", \"services_unittests\": \"29755c25f1aee2d9529eec845bf314510072690f\", \"shell_dialogs_unittests\": \"2ff651bc55fa0775de1d8a36b36b31edded9acf0\", \"skia_unittests\": \"5046deab622c23ec92b7accdda028ba3b3019ce4\", \"snapshot_unittests\": \"0b565cee5ae95193014dfa4152fcf829c1d003db\", \"sql_unittests\": \"d2c42f946372cab6379d7672c3ee8a8f0c9433a4\", \"storage_unittests\": \"206b5b895a7da3ca43ae931ed7d62349baffc3c4\", \"sync_integration_tests\": \"108f49cec645438efd43e966a554535f47e1d2e1\", \"telemetry_gpu_unittests\": \"9551f08fbb100c845bf75f795fd9fc15e9bad975\", \"telemetry_perf_unittests\": \"473f9cb5c6e500af99e7f0d4e296a9a299edd241\", \"telemetry_unittests\": \"efcb4135860d2965d7d93c479a11e598f36eb62f\", \"traffic_annotation_auditor_unittests\": \"a05ced982179bafc2cfd127ff1613aa752832926\", \"ui_base_unittests\": \"550d542a0500995506e6daa02414f03a48981153\", \"ui_touch_selection_unittests\": \"696379fbad34fd17ac18ed4d89b91f04c6edd8db\", \"unit_tests\": \"5b4712bb438986bc9d6d1702d5b4d35547d30029\", \"url_unittests\": \"330114ef43948597a7d6c8a653324300518b38e4\", \"views_perftests\": \"51775ef814af718f67f99da01548f17b9210f9e3\", \"views_unittests\": \"ccf64c3e43ca34d288d6a445a2c74a001c1a6bf8\", \"viz_unittests\": \"ab5c5617479e17381d031483224697365508bb6f\", \"vr_common_unittests\": \"94a44534d6f0d2b3905893c41a9dcd78111aae3b\", \"vr_pixeltests\": \"520e7cb70fb2c573c6a5f382e2d609188d788182\", \"webdriver_wpt_tests\": \"d008e21f46aaa0169219849fdfa1689918e10be5\", \"weblayer_browsertests\": \"b21397d31d8b4837a52d39db9b4acb2297d35426\", \"wm_unittests\": \"9d20a5b0fc22c0a65542dadc6244c598c9525f83\", \"wtf_unittests\": \"e9f599977c68623398966711202500e126c34be5\", \"xr_browser_tests\": \"a9fe50a286813f69e0a52be653e74f21d995ba7d\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_016577e073ce9abf0fa10d80f88bc2caf8f81662b409bd6e59fa52089e44a3df_v2\", \"builder_2bbcd421f4eb1113f3a23522b280d70ced47ef846cc2e40eada60721dff8d17e_v2\", \"builder_349392596e0d17d3422c55529f4e230875f36ec061130c176581539a4c7cd365_v2\", \"builder_4637b11d2a56187e25d14badb4fa154a12143e816e25cb34f5fe38c5c81ebb47_v2\", \"builder_5ae174071c5d6730648bf4e0d5db68d27bfbde0d9c5b879a557aa1bc8e90c36b_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-62-hnx1\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4825-3d129d6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1582167745347256",
"status": "COMPLETED",
"status_changed_ts": "1582170741155308",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168808",
"builder:Linux Builder (dbg)",
"buildset:commit/git/b7acd7738deb9e94e059d5b6e62203a37eb6158e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b7acd7738deb9e94e059d5b6e62203a37eb6158e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053400947739636976",
"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/8887970885815652048/+/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:4a7959210fb67910",
"user_agent:luci-scheduler"
],
"updated_ts": "1582170741160263",
"url": "https://ci.chromium.org/b/8887970885815652048",
"utcnow_ts": "1582170741734444"
}
}
[03:52:21.745] Invocation finished in 54m39.525678215s with status SUCCEEDED