[02:33:27.166] New invocation is queued and will start shortly
[02:33:28.313] Starting the invocation (attempt 1)
[02:33:28.371] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:33:28.371] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:33:28.371] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9061646797436753984",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"825e7791fd77b894021d83830b506d6eb914a39d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9061646797436753984",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/825e7791fd77b894021d83830b506d6eb914a39d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/825e7791fd77b894021d83830b506d6eb914a39d",
"gitiles_ref:refs/heads/master"
]
}
[02:33:29.718] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1574303608648437",
"id": "8896216735216930160",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"825e7791fd77b894021d83830b506d6eb914a39d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1574303609218251",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165089",
"builder:Linux Builder (dbg)",
"buildset:commit/git/825e7791fd77b894021d83830b506d6eb914a39d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/825e7791fd77b894021d83830b506d6eb914a39d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061646797436753984",
"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/8896216735216930160/+/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": "1574303609218410",
"url": "https://ci.chromium.org/b/8896216735216930160",
"utcnow_ts": "1574303609711036"
}
}
[02:33:29.718] Task URL: https://ci.chromium.org/b/8896216735216930160
[02:33:29.718] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:2:0) after 1m0s
[02:33:57.298] Received PubSub notification, asking Buildbucket for the build status
[02:33:57.979] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:29.895] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:2:0)
[02:34:29.895] Timer tick, asking Buildbucket for the build status
[02:34:31.372] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:31.372] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:4:0) after 1m0s
[02:35:31.403] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:4:0)
[02:35:31.403] Timer tick, asking Buildbucket for the build status
[02:35:31.674] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:31.674] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:5:0) after 1m0s
[02:36:31.764] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:5:0)
[02:36:31.764] Timer tick, asking Buildbucket for the build status
[02:36:31.973] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:31.973] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:6:0) after 1m0s
[02:37:32.113] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:6:0)
[02:37:32.113] Timer tick, asking Buildbucket for the build status
[02:37:32.302] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:32.302] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:7:0) after 1m0s
[02:38:32.428] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:7:0)
[02:38:32.428] Timer tick, asking Buildbucket for the build status
[02:38:32.976] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:32.976] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:8:0) after 1m0s
[02:39:33.021] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:8:0)
[02:39:33.021] Timer tick, asking Buildbucket for the build status
[02:39:33.661] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:33.661] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:9:0) after 1m0s
[02:40:33.653] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:9:0)
[02:40:33.653] Timer tick, asking Buildbucket for the build status
[02:40:33.863] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:33.863] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:10:0) after 1m0s
[02:41:33.911] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:10:0)
[02:41:33.911] Timer tick, asking Buildbucket for the build status
[02:41:34.601] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:34.601] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:11:0) after 1m0s
[02:42:34.662] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:11:0)
[02:42:34.662] Timer tick, asking Buildbucket for the build status
[02:42:35.894] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:35.894] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:12:0) after 1m0s
[02:43:35.930] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:12:0)
[02:43:35.931] Timer tick, asking Buildbucket for the build status
[02:43:36.192] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:36.192] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:13:0) after 1m0s
[02:44:36.311] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:13:0)
[02:44:36.311] Timer tick, asking Buildbucket for the build status
[02:44:37.612] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:37.612] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:14:0) after 1m0s
[02:45:37.713] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:14:0)
[02:45:37.713] Timer tick, asking Buildbucket for the build status
[02:45:38.839] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:38.839] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:15:0) after 1m0s
[02:46:38.936] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:15:0)
[02:46:38.936] Timer tick, asking Buildbucket for the build status
[02:46:40.198] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:40.198] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:16:0) after 1m0s
[02:47:40.269] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:16:0)
[02:47:40.269] Timer tick, asking Buildbucket for the build status
[02:47:41.430] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:41.430] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:17:0) after 1m0s
[02:48:41.443] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:17:0)
[02:48:41.443] Timer tick, asking Buildbucket for the build status
[02:48:43.033] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:43.033] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:18:0) after 1m0s
[02:49:43.198] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:18:0)
[02:49:43.198] Timer tick, asking Buildbucket for the build status
[02:49:43.471] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:43.471] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:19:0) after 1m0s
[02:50:43.534] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:19:0)
[02:50:43.534] Timer tick, asking Buildbucket for the build status
[02:50:43.779] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:43.779] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:20:0) after 1m0s
[02:51:44.002] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:20:0)
[02:51:44.002] Timer tick, asking Buildbucket for the build status
[02:51:44.268] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:44.268] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:21:0) after 1m0s
[02:52:44.413] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:21:0)
[02:52:44.413] Timer tick, asking Buildbucket for the build status
[02:52:45.733] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:45.733] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:22:0) after 1m0s
[02:53:46.121] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:22:0)
[02:53:46.121] Timer tick, asking Buildbucket for the build status
[02:53:46.529] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:46.529] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:23:0) after 1m0s
[02:54:46.783] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:23:0)
[02:54:46.783] Timer tick, asking Buildbucket for the build status
[02:54:46.942] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:46.942] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:24:0) after 1m0s
[02:55:47.141] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:24:0)
[02:55:47.141] Timer tick, asking Buildbucket for the build status
[02:55:48.007] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:48.007] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:25:0) after 1m0s
[02:56:48.116] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:25:0)
[02:56:48.116] Timer tick, asking Buildbucket for the build status
[02:56:48.650] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:48.650] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:26:0) after 1m0s
[02:57:48.669] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:26:0)
[02:57:48.669] Timer tick, asking Buildbucket for the build status
[02:57:49.130] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:49.130] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:27:0) after 1m0s
[02:58:49.307] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:27:0)
[02:58:49.307] Timer tick, asking Buildbucket for the build status
[02:58:51.197] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:51.197] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:28:0) after 1m0s
[02:59:51.258] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:28:0)
[02:59:51.258] Timer tick, asking Buildbucket for the build status
[02:59:51.525] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:51.525] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:29:0) after 1m0s
[03:00:51.611] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:29:0)
[03:00:51.611] Timer tick, asking Buildbucket for the build status
[03:00:54.501] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:54.501] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:30:0) after 1m0s
[03:01:54.729] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:30:0)
[03:01:54.729] Timer tick, asking Buildbucket for the build status
[03:01:55.065] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:55.065] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:31:0) after 1m0s
[03:02:55.367] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:31:0)
[03:02:55.367] Timer tick, asking Buildbucket for the build status
[03:02:55.726] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:55.726] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:32:0) after 1m0s
[03:03:55.911] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:32:0)
[03:03:55.911] Timer tick, asking Buildbucket for the build status
[03:03:56.481] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:56.481] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:33:0) after 1m0s
[03:04:56.652] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:33:0)
[03:04:56.652] Timer tick, asking Buildbucket for the build status
[03:04:57.051] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:57.051] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:34:0) after 1m0s
[03:05:57.077] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:34:0)
[03:05:57.077] Timer tick, asking Buildbucket for the build status
[03:05:58.188] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:58.188] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:35:0) after 1m0s
[03:06:58.253] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:35:0)
[03:06:58.253] Timer tick, asking Buildbucket for the build status
[03:06:59.296] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:59.296] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:36:0) after 1m0s
[03:07:59.307] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:36:0)
[03:07:59.307] Timer tick, asking Buildbucket for the build status
[03:07:59.584] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:59.584] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:37:0) after 1m0s
[03:08:59.617] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:37:0)
[03:08:59.617] Timer tick, asking Buildbucket for the build status
[03:08:59.748] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:59.748] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:38:0) after 1m0s
[03:09:59.831] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:38:0)
[03:09:59.831] Timer tick, asking Buildbucket for the build status
[03:10:00.142] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:00.142] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:39:0) after 1m0s
[03:11:00.304] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:39:0)
[03:11:00.304] Timer tick, asking Buildbucket for the build status
[03:11:01.345] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:01.345] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:40:0) after 1m0s
[03:12:01.462] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:40:0)
[03:12:01.462] Timer tick, asking Buildbucket for the build status
[03:12:03.050] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:03.050] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:41:0) after 1m0s
[03:13:03.120] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:41:0)
[03:13:03.120] Timer tick, asking Buildbucket for the build status
[03:13:03.349] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:03.349] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:42:0) after 1m0s
[03:14:03.531] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:42:0)
[03:14:03.531] Timer tick, asking Buildbucket for the build status
[03:14:04.403] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:04.403] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:43:0) after 1m0s
[03:15:04.444] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:43:0)
[03:15:04.444] Timer tick, asking Buildbucket for the build status
[03:15:04.796] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:04.796] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:44:0) after 1m0s
[03:16:04.886] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:44:0)
[03:16:04.886] Timer tick, asking Buildbucket for the build status
[03:16:06.264] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:06.264] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:45:0) after 1m0s
[03:17:06.425] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:45:0)
[03:17:06.425] Timer tick, asking Buildbucket for the build status
[03:17:07.374] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:07.374] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:46:0) after 1m0s
[03:18:07.421] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:46:0)
[03:18:07.421] Timer tick, asking Buildbucket for the build status
[03:18:08.035] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:08.035] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:47:0) after 1m0s
[03:19:08.154] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:47:0)
[03:19:08.154] Timer tick, asking Buildbucket for the build status
[03:19:08.946] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:08.946] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:48:0) after 1m0s
[03:20:09.100] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:48:0)
[03:20:09.100] Timer tick, asking Buildbucket for the build status
[03:20:10.242] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:10.242] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:49:0) after 1m0s
[03:21:10.335] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:49:0)
[03:21:10.335] Timer tick, asking Buildbucket for the build status
[03:21:10.876] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:10.876] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:50:0) after 1m0s
[03:22:10.993] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:50:0)
[03:22:10.993] Timer tick, asking Buildbucket for the build status
[03:22:11.546] Build 8896216735216930160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:11.546] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061646797436753984:51:0) after 1m0s
[03:22:26.584] Received PubSub notification, asking Buildbucket for the build status
[03:22:26.819] Build 8896216735216930160: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:22:26.819] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1574306545954974",
"created_by": "project:chromium",
"created_ts": "1574303608648437",
"id": "8896216735216930160",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"825e7791fd77b894021d83830b506d6eb914a39d\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-51-00r4\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 165089, \"got_angle_revision\": \"5530b3ff782281dffb2179e13ce690cb4f822218\", \"got_dawn_revision\": \"8f3dc5836bb6c111c21e5a7d5be864f0692bd066\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"825e7791fd77b894021d83830b506d6eb914a39d\", \"got_revision_cp\": \"refs/heads/master@{#717446}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"f2c9ce1e08ed30ed42f16df61c0ea91579941134\", \"got_v8_revision\": \"96bd79e5ddf97d6e48b1774ca953bf4084d1e93a\", \"got_v8_revision_cp\": \"refs/heads/8.0.329@{#1}\", \"got_webrtc_revision\": \"6dd488b2e55125644263e4837f1abd950d5e410d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29846}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"825e7791fd77b894021d83830b506d6eb914a39d\", \"swarm_hashes_refs/heads/master(at){#717446}_without_patch\": {\"accessibility_unittests\": \"b80828b8f14d6303342638d6beaa8c3a32b16905\", \"angle_unittests\": \"9cf08f0c7f33ed48fc8da0fac32df19c4169a9a2\", \"app_shell_unittests\": \"5251f9f974546a1eea2dd8c37f3b5d460542cbc3\", \"aura_unittests\": \"1af3e72493d630ea37ee0feffcae464c95dbee0f\", \"base_unittests\": \"49e4c2047a3fc1d939b20bbc474ada59c4c57557\", \"base_util_unittests\": \"2bf632d395f9b36cf2bc30a1ab87dd361488fd3f\", \"blink_common_unittests\": \"cfcbf5d575561dcf5dbcba4de44c2241278a51db\", \"blink_fuzzer_unittests\": \"bfd1b272e789e3f5f14ef0b9ff4b84af22d5c974\", \"blink_heap_unittests\": \"6c82e9cd4ef39352aa7a8a88b88a1175c77a54fa\", \"blink_platform_unittests\": \"dd06cdd7d94046716c7fbc7614094aa76f988183\", \"blink_python_tests\": \"4c4abc86b3e13993484ea3819fed3749855b6a35\", \"blink_unittests\": \"9765a2f3aef1e15189c8f03e2685ecd1a552e440\", \"blink_web_tests_exparchive\": \"3f834aa95a209d6f36c3c60e4b166e6f7fceec67\", \"boringssl_crypto_tests\": \"6876847fd6d670c66b69e73656d1c932fb297d36\", \"boringssl_ssl_tests\": \"1ce9f0a0a823318eb1ba0f15d2534bbebf31b837\", \"browser_tests\": \"41dace35e55e2d9cc7ed0e6c5507225fd7c538cf\", \"cacheinvalidation_unittests\": \"0316c6068ec7667a3b8b86425616a36361610c22\", \"capture_unittests\": \"7d9e7141c92958ec51275a12866aa25cd6b446ea\", \"cast_unittests\": \"38832a283e7f441157c2472319f52b4e54491a3f\", \"cc_unittests\": \"8e6b7e94330bb913587471e89105b3d6ca1f10a6\", \"chrome_app_unittests\": \"a11d2862dd76d5f03b49688a6f7a6306fc2fc9e3\", \"chromedriver_unittests\": \"1278d2afc90172ff0680d285000d333041797a33\", \"color_unittests\": \"0ef6b9478419c654fcf1d02c193bb287feaffa67\", \"components_browsertests\": \"f954c89286d644e84995445f393d32007f685614\", \"components_unittests\": \"229058556d9380d4ed2fee2886620df5322da39c\", \"compositor_unittests\": \"4da96fc4e704718cb230fbbfce2d32a4a6651348\", \"content_browsertests\": \"64be90d8e989237f7a7bf6e8710ee4a78d55b253\", \"content_shell_crash_test\": \"dbbf8b0b7172d20f047ba02d596b37961d538992\", \"content_unittests\": \"d5e900daea6eda68c2a85eda9c135430b54d073e\", \"crashpad_tests\": \"21992af4f9aa43ab12aed47be1cbeb65d88daa03\", \"cronet_tests\": \"6435415ad87faff58bea209a27f3c661130ef5ff\", \"cronet_unittests\": \"1f2e66d1b8e1686c3a6a57b726d9a1a43cddf356\", \"crypto_unittests\": \"4a7f82940d1f831199b455fb8a4ca3630a6587fa\", \"dbus_unittests\": \"dd86ad4a54a10b585e285f538a3a5e09a722c405\", \"device_unittests\": \"784e6472134f8b86a1795b63c992cfec0b952615\", \"devtools_lint_check\": \"3397cf410fc5de1ca90a4507b824571fb4cb9728\", \"devtools_type_check\": \"9ca08ed6c94d3c66d16970090f1f4d3a51d32e82\", \"display_unittests\": \"d82d98115a6ac5103bd4f0cdd35ea4dc1cc81df4\", \"events_unittests\": \"b503db07bc080d063398ba84eec70c23af484059\", \"extensions_browsertests\": \"d0e61ccfd5c765a38d3e0431e9734d4b0e734895\", \"extensions_unittests\": \"440cee22fbb651720cb6c6cb2f5e029ab706bc1e\", \"filesystem_service_unittests\": \"b63d026d274b0a49b5db13969288e6fc9a3ee0ea\", \"flatbuffers_unittests\": \"68877d8b1262b0aa771438df1015a16a10098d5e\", \"gcm_unit_tests\": \"8eeb410a334164bb7755d61dc975f88c87c60ceb\", \"gfx_unittests\": \"ade58e3977017ada3549751cb6561fb6420501dd\", \"gin_unittests\": \"4b6204701a1d40497fe0736dc3e342bd78916ff4\", \"google_apis_unittests\": \"b18e5d6077e38df8ef860969a563ff25c2d38a65\", \"gpu_unittests\": \"2d5524c8e73d615a90aeaf5953adfe833f3ac1ad\", \"grit_python_unittests\": \"51a2acc75851f783c79f3039759e252916938d35\", \"gwp_asan_unittests\": \"bd5713154da76ef03b2ec119d85ae6ceef0ff1e0\", \"headless_browsertests\": \"95fb278468a16ee48d597184b41af6b8b78e3066\", \"headless_unittests\": \"a85247c9b1354fbac21865e42e1cd9d7e4b98c86\", \"interactive_ui_tests\": \"8a3bd99b28da3883fcc3a02c0e407d774fb34787\", \"ipc_tests\": \"6363a428fe385d8fdb7b460560e077c62cee338a\", \"jingle_unittests\": \"d8bffc0e43379d1f5cc884995bb63ad12950b0ed\", \"latency_unittests\": \"b9f2e911197659d3ee71e6cdac55b7dc5f9689dc\", \"libjingle_xmpp_unittests\": \"295e238e8a02b92eff3f4e14cc37d73a2ef1c67f\", \"media_blink_unittests\": \"884652b516b3ec73ae53704388d50afd177598b5\", \"media_service_unittests\": \"5ea19239f507ef5b09b0b578459426c20d980365\", \"media_unittests\": \"6dc20e1d3ee453f3d7a0fcbf68d65be7700cc98a\", \"message_center_unittests\": \"d1f3339d1607db0f0c149905600624e6518d62ee\", \"metrics_python_tests\": \"765a92cdf72a2f37aa6c933e1ff82a165db96cdc\", \"midi_unittests\": \"47b041da02df5c0ff75a162d522549325481fb3f\", \"mojo_core_unittests\": \"036d817038df42971b5da9d26a0f03822873df73\", \"mojo_unittests\": \"f617b6b043538e476e1005af7a45982ddb8fce50\", \"nacl_helper_nonsfi_unittests\": \"0405788d3440481a86fcbf7aabfcea4c75506181\", \"nacl_loader_unittests\": \"858d56cba74f98b52108e2f3b340abdef7f302d6\", \"native_theme_unittests\": \"8fe7a73a352149d397d47025894b22c39a0310dc\", \"net_unittests\": \"580012917b0d58ed6861f498f1c134b65030644a\", \"pdf_unittests\": \"6ffbbef1d85a736ac19bb0c13f789cb3ba006e33\", \"perfetto_unittests\": \"c6eae2a1fe92f570918b730057d09e11f44f0a1c\", \"ppapi_unittests\": \"0884e3cd88ad05cb8c919cb45c12764c4855f3bb\", \"printing_unittests\": \"cddc6b9adbc6d62b42de6f45e4f3de3c2297b5e4\", \"remoting_unittests\": \"ec6e8b9e58ac57a212438e941fd247e94496bf31\", \"sandbox_linux_unittests\": \"f2de588a8cfe2912871a53fb8c7f8eca623bc003\", \"service_manager_unittests\": \"c23c0015c204cadd55cd7c9d496087630335f018\", \"services_unittests\": \"5b90693da8e99b13ad14b9cdcea228b1d6d9cfca\", \"shell_dialogs_unittests\": \"508c81c9537df6dd21da4ae876a23fa58bfbd479\", \"skia_unittests\": \"0e44b2f790e9c7ce6372f711ebb666646ce230bd\", \"snapshot_unittests\": \"cb58d6055249a35a5938aa1716d5d078ea4aa3ab\", \"sql_unittests\": \"986f78fec156f5e26b01e808487a30dd907bb7ed\", \"storage_unittests\": \"cf0f4a234f72cf9ddf347ab73e44749d57a078b5\", \"sync_integration_tests\": \"5bb0e4f047088bc98bc7b180ebc256ed1ace95d5\", \"telemetry_gpu_unittests\": \"4ae01dba50fa3c20fc7093ba09e08087a5a10066\", \"telemetry_perf_unittests\": \"5908f7c0fa04be1ef69c4fd86616bc98be5e3be2\", \"telemetry_unittests\": \"ceebc6151501e8e6b2ac2ee407ec4328f3fbd13f\", \"traffic_annotation_auditor_unittests\": \"6e49b0d1bcfc595cedd311a254aa677338b0fc17\", \"ui_base_unittests\": \"cad7059870fb212c6b990626e10cfaf9bf2aff1f\", \"ui_touch_selection_unittests\": \"79e51e8ca9a4f73fd0016a31e23967ebcd7f9340\", \"unit_tests\": \"9b72e3878e35e078302693ee77a878d846238faa\", \"url_unittests\": \"fcbbcebbd5eb481f6442f78e14c02adf14677a43\", \"views_perftests\": \"8540a6a25aed15aedc263b02360ee78a9879edfa\", \"views_unittests\": \"23dfa4154c4f12131dc3c40fde26eed256e077f0\", \"viz_unittests\": \"c164c60ab4021d96350ded748b444e050ce1b136\", \"vr_common_unittests\": \"109a6cf87ea819a651b66d2a4d0b73d7c3698ca5\", \"vr_pixeltests\": \"bc87ff84161948cc66024314a2ff5f96921cfdac\", \"webdriver_wpt_tests\": \"b794fd60113dea661fbabb75a2efb5325d0d0c05\", \"weblayer_browsertests\": \"4a6b0f324628fd9bf7c8f9bd7e6de26d9c579f35\", \"wm_unittests\": \"9e498e2d03c675411356822d52da88e4b7283b6e\", \"wtf_unittests\": \"1f4129d4d9ca5d0230bf09e0eaa643975e24d91f\", \"xr_browser_tests\": \"7c5dc90238d29e573abe4904bc5b9db7e31689a8\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5018db700e530f8e7ae860b5b3feab2b02d3dedf1bbd3e704c6c59fde1b308dd_v2\", \"builder_a9a41095618141e4757e131cf792803c0a27ecf4e01eb5d0d372c002309e764f_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-51-00r4\"], \"image\": [\"chrome-xenial-19111704-ac4c24a5ee0\"], \"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\": [\"4645-7f6a85d\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1574303634255322",
"status": "COMPLETED",
"status_changed_ts": "1574306546221101",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165089",
"builder:Linux Builder (dbg)",
"buildset:commit/git/825e7791fd77b894021d83830b506d6eb914a39d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/825e7791fd77b894021d83830b506d6eb914a39d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061646797436753984",
"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/8896216735216930160/+/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:48a4a09469000d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1574306546225612",
"url": "https://ci.chromium.org/b/8896216735216930160",
"utcnow_ts": "1574306546798443"
}
}
[03:22:26.819] Invocation finished in 48m59.672233876s with status SUCCEEDED