[17:52:10.648] New invocation is queued and will start shortly
[17:52:11.969] Starting the invocation (attempt 1)
[17:52:12.002] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:52:12.002] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:52:12.002] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9055337805598344592",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e974948e932fa68e0ffa262fbb1d8a4328a40938\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9055337805598344592",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/e974948e932fa68e0ffa262fbb1d8a4328a40938",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e974948e932fa68e0ffa262fbb1d8a4328a40938",
"gitiles_ref:refs/heads/master"
]
}
[17:52:12.902] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1580320332302515",
"id": "8889907743194861568",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e974948e932fa68e0ffa262fbb1d8a4328a40938\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1580320332764233",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167994",
"builder:Linux Builder (dbg)",
"buildset:commit/git/e974948e932fa68e0ffa262fbb1d8a4328a40938",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e974948e932fa68e0ffa262fbb1d8a4328a40938",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055337805598344592",
"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/8889907743194861568/+/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": "1580320332764341",
"url": "https://ci.chromium.org/b/8889907743194861568",
"utcnow_ts": "1580320332889142"
}
}
[17:52:12.902] Task URL: https://ci.chromium.org/b/8889907743194861568
[17:52:12.902] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:2:0) after 1m0s
[17:53:12.977] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:2:0)
[17:53:12.977] Timer tick, asking Buildbucket for the build status
[17:53:13.740] Build 8889907743194861568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:53:13.740] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:3:0) after 1m0s
[17:54:13.715] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:3:0)
[17:54:13.715] Timer tick, asking Buildbucket for the build status
[17:54:13.885] Build 8889907743194861568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:54:13.885] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:4:0) after 1m0s
[17:55:13.908] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:4:0)
[17:55:13.908] Timer tick, asking Buildbucket for the build status
[17:55:14.094] Build 8889907743194861568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:55:14.094] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:5:0) after 1m0s
[17:56:14.180] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:5:0)
[17:56:14.180] Timer tick, asking Buildbucket for the build status
[17:56:14.812] Build 8889907743194861568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:56:14.812] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:6:0) after 1m0s
[17:57:10.441] Received PubSub notification, asking Buildbucket for the build status
[17:57:10.714] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:14.858] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:6:0)
[17:57:14.858] Timer tick, asking Buildbucket for the build status
[17:57:15.046] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:15.046] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:8:0) after 1m0s
[17:58:15.127] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:8:0)
[17:58:15.127] Timer tick, asking Buildbucket for the build status
[17:58:15.641] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:15.641] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:9:0) after 1m0s
[17:59:15.739] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:9:0)
[17:59:15.739] Timer tick, asking Buildbucket for the build status
[17:59:16.137] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:16.137] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:10:0) after 1m0s
[18:00:16.245] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:10:0)
[18:00:16.245] Timer tick, asking Buildbucket for the build status
[18:00:16.548] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:16.548] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:11:0) after 1m0s
[18:01:16.575] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:11:0)
[18:01:16.575] Timer tick, asking Buildbucket for the build status
[18:01:16.963] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:16.963] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:12:0) after 1m0s
[18:02:16.991] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:12:0)
[18:02:16.991] Timer tick, asking Buildbucket for the build status
[18:02:17.471] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:17.471] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:13:0) after 1m0s
[18:03:17.626] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:13:0)
[18:03:17.626] Timer tick, asking Buildbucket for the build status
[18:03:17.824] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:17.824] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:14:0) after 1m0s
[18:04:17.797] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:14:0)
[18:04:17.797] Timer tick, asking Buildbucket for the build status
[18:04:18.181] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:18.181] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:15:0) after 1m0s
[18:05:18.240] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:15:0)
[18:05:18.240] Timer tick, asking Buildbucket for the build status
[18:05:18.348] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:18.348] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:16:0) after 1m0s
[18:06:18.419] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:16:0)
[18:06:18.419] Timer tick, asking Buildbucket for the build status
[18:06:20.748] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:20.748] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:17:0) after 1m0s
[18:07:20.964] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:17:0)
[18:07:20.964] Timer tick, asking Buildbucket for the build status
[18:07:21.437] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:21.437] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:18:0) after 1m0s
[18:08:21.580] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:18:0)
[18:08:21.580] Timer tick, asking Buildbucket for the build status
[18:08:22.807] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:22.807] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:19:0) after 1m0s
[18:09:22.815] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:19:0)
[18:09:22.815] Timer tick, asking Buildbucket for the build status
[18:09:23.212] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:23.212] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:20:0) after 1m0s
[18:10:23.280] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:20:0)
[18:10:23.280] Timer tick, asking Buildbucket for the build status
[18:10:23.440] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:23.440] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:21:0) after 1m0s
[18:11:23.418] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:21:0)
[18:11:23.418] Timer tick, asking Buildbucket for the build status
[18:11:23.683] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:23.683] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:22:0) after 1m0s
[18:12:23.758] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:22:0)
[18:12:23.758] Timer tick, asking Buildbucket for the build status
[18:12:24.458] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:24.458] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:23:0) after 1m0s
[18:13:24.483] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:23:0)
[18:13:24.483] Timer tick, asking Buildbucket for the build status
[18:13:24.666] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:24.666] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:24:0) after 1m0s
[18:14:24.722] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:24:0)
[18:14:24.722] Timer tick, asking Buildbucket for the build status
[18:14:25.164] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:25.164] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:25:0) after 1m0s
[18:15:25.215] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:25:0)
[18:15:25.215] Timer tick, asking Buildbucket for the build status
[18:15:25.561] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:25.561] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:26:0) after 1m0s
[18:16:25.579] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:26:0)
[18:16:25.579] Timer tick, asking Buildbucket for the build status
[18:16:26.068] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:26.068] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:27:0) after 1m0s
[18:17:26.102] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:27:0)
[18:17:26.102] Timer tick, asking Buildbucket for the build status
[18:17:26.309] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:26.309] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:28:0) after 1m0s
[18:18:26.336] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:28:0)
[18:18:26.336] Timer tick, asking Buildbucket for the build status
[18:18:26.896] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:26.896] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:29:0) after 1m0s
[18:19:26.952] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:29:0)
[18:19:26.952] Timer tick, asking Buildbucket for the build status
[18:19:27.115] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:27.115] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:30:0) after 1m0s
[18:20:27.152] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:30:0)
[18:20:27.152] Timer tick, asking Buildbucket for the build status
[18:20:27.700] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:27.700] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:31:0) after 1m0s
[18:21:27.703] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:31:0)
[18:21:27.703] Timer tick, asking Buildbucket for the build status
[18:21:27.997] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:27.997] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:32:0) after 1m0s
[18:22:28.039] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:32:0)
[18:22:28.039] Timer tick, asking Buildbucket for the build status
[18:22:28.348] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:28.348] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:33:0) after 1m0s
[18:23:28.375] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:33:0)
[18:23:28.375] Timer tick, asking Buildbucket for the build status
[18:23:28.573] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:28.573] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:34:0) after 1m0s
[18:24:28.643] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:34:0)
[18:24:28.643] Timer tick, asking Buildbucket for the build status
[18:24:28.860] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:28.860] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:35:0) after 1m0s
[18:25:28.884] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:35:0)
[18:25:28.884] Timer tick, asking Buildbucket for the build status
[18:25:30.983] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:30.983] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:36:0) after 1m0s
[18:26:31.088] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:36:0)
[18:26:31.088] Timer tick, asking Buildbucket for the build status
[18:26:32.420] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:32.420] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:37:0) after 1m0s
[18:27:32.487] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:37:0)
[18:27:32.487] Timer tick, asking Buildbucket for the build status
[18:27:32.881] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:32.881] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:38:0) after 1m0s
[18:28:32.914] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:38:0)
[18:28:32.914] Timer tick, asking Buildbucket for the build status
[18:28:33.198] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:33.198] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:39:0) after 1m0s
[18:29:33.205] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:39:0)
[18:29:33.205] Timer tick, asking Buildbucket for the build status
[18:29:33.361] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:33.361] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:40:0) after 1m0s
[18:30:33.383] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:40:0)
[18:30:33.383] Timer tick, asking Buildbucket for the build status
[18:30:33.593] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:33.593] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:41:0) after 1m0s
[18:31:33.616] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:41:0)
[18:31:33.616] Timer tick, asking Buildbucket for the build status
[18:31:33.971] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:33.971] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:42:0) after 1m0s
[18:32:34.005] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:42:0)
[18:32:34.005] Timer tick, asking Buildbucket for the build status
[18:32:34.178] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:34.178] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:43:0) after 1m0s
[18:33:34.309] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:43:0)
[18:33:34.309] Timer tick, asking Buildbucket for the build status
[18:33:34.718] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:34.718] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:44:0) after 1m0s
[18:34:34.791] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:44:0)
[18:34:34.791] Timer tick, asking Buildbucket for the build status
[18:34:35.068] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:35.068] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:45:0) after 1m0s
[18:35:35.131] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:45:0)
[18:35:35.131] Timer tick, asking Buildbucket for the build status
[18:35:36.117] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:36.117] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:46:0) after 1m0s
[18:36:36.148] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:46:0)
[18:36:36.148] Timer tick, asking Buildbucket for the build status
[18:36:36.547] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:36.547] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:47:0) after 1m0s
[18:37:36.622] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:47:0)
[18:37:36.622] Timer tick, asking Buildbucket for the build status
[18:37:37.200] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:37.200] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:48:0) after 1m0s
[18:38:37.242] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:48:0)
[18:38:37.242] Timer tick, asking Buildbucket for the build status
[18:38:38.109] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:38.109] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:49:0) after 1m0s
[18:39:38.159] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:49:0)
[18:39:38.159] Timer tick, asking Buildbucket for the build status
[18:39:38.476] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:38.476] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:50:0) after 1m0s
[18:40:38.530] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:50:0)
[18:40:38.530] Timer tick, asking Buildbucket for the build status
[18:40:38.817] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:38.817] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:51:0) after 1m0s
[18:41:38.998] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:51:0)
[18:41:38.998] Timer tick, asking Buildbucket for the build status
[18:41:39.439] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:39.439] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:52:0) after 1m0s
[18:42:39.459] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:52:0)
[18:42:39.459] Timer tick, asking Buildbucket for the build status
[18:42:39.782] Build 8889907743194861568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:39.782] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055337805598344592:53:0) after 1m0s
[18:42:59.871] Received PubSub notification, asking Buildbucket for the build status
[18:43:00.274] Build 8889907743194861568: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:43:00.274] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1580323378943579",
"created_by": "project:chromium",
"created_ts": "1580320332302515",
"id": "8889907743194861568",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e974948e932fa68e0ffa262fbb1d8a4328a40938\"}}",
"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-central1-b-8-134-ve57\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 167994, \"got_angle_revision\": \"05e08edf70d5ce2a6f99f9c0e4d7e12acf1eb1ef\", \"got_dawn_revision\": \"7b6246a2ade815a032e1abc8df1be7c72d3dd0c2\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"e974948e932fa68e0ffa262fbb1d8a4328a40938\", \"got_revision_cp\": \"refs/heads/master@{#736433}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"6c3dc3581eaf4345c0507d5ac7bb013d55351547\", \"got_v8_revision\": \"4bf20c761983541bfa2160b705dd9ab987169e35\", \"got_v8_revision_cp\": \"refs/heads/8.1.304@{#1}\", \"got_webrtc_revision\": \"99d6d8115b374cd57bfd9195d8eb28de26ffc134\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30408}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e974948e932fa68e0ffa262fbb1d8a4328a40938\", \"swarm_hashes_refs/heads/master(at){#736433}_without_patch\": {\"accessibility_unittests\": \"7afd026651c783aa1b51d392ee938fa9bbfe3498\", \"angle_unittests\": \"fdc70722de0073518c366677f1b78ac44b2b308a\", \"app_shell_unittests\": \"6427c2285076ba263262154cb9b3f9f660367e1d\", \"aura_unittests\": \"9ac4426504fdab594cf9136ebcc792b18e98ad4b\", \"base_unittests\": \"c4551be4989ae59a48602fbb0c3bd5f9d9b8f632\", \"base_util_unittests\": \"de5e6191cbf789e45e7dbff0e3af5258d196ba9b\", \"blink_common_unittests\": \"431ca12068c4c247380dc814aa279cc3228e1a96\", \"blink_fuzzer_unittests\": \"2e09a42c048a0c61f797d1491ae2a92bbd1347b7\", \"blink_heap_unittests\": \"971fe656fd07b523d4e1d6ad49f97066f00315d4\", \"blink_platform_unittests\": \"d4cae93fe16eceb15d15c96f41236308e3d6927b\", \"blink_python_tests\": \"f3ec5efbe64508819822fc4b483f52ceebc9c0b3\", \"blink_unittests\": \"d3431857c06fc2a2f569c33f9b252f5e6d4c92d2\", \"blink_web_tests\": \"62c8782dd966f645da3315f5ad5ba46e10414e7e\", \"boringssl_crypto_tests\": \"2f4f1ca98768a9c2cba60e198ee3bb13cba7bfab\", \"boringssl_ssl_tests\": \"987d47cb797ee88257c8322893b917449433615f\", \"browser_tests\": \"572398e066230a9c20b9ba8ba6c84eee82398013\", \"cacheinvalidation_unittests\": \"38bef89803105b4aae1573829e583a9b1ba9bbdb\", \"capture_unittests\": \"f4f7fc1add16d7e806493e90313501d1c1390b13\", \"cast_unittests\": \"b97760b1ef1538325a17a599b77f882de53d2591\", \"cc_unittests\": \"f63fc40600d5c7acb95caba1a8ea9bf19bc9b385\", \"chrome_app_unittests\": \"98267ef26962f396c8902aa0cc7ca16a42613e83\", \"chromedriver_unittests\": \"af61f15d0372b4e7b5e4cccd469e6a556960a3fc\", \"components_browsertests\": \"422b968cf09660e9fec86b39b6684b2f52897997\", \"components_unittests\": \"818125a2f92c964233c3cb4a96b9c2c6716f8b8d\", \"compositor_unittests\": \"a4801baa1f9948ec8e9382d7473dcf8d2191d099\", \"content_browsertests\": \"62a80425d8fe7b4cebb37ae55ebac080cb2ffd52\", \"content_shell_crash_test\": \"5aaed7fb746d557809ebe25d52e06bcf2823be43\", \"content_unittests\": \"68e35a452445862e5e861d0ee915929059ba8d23\", \"crashpad_tests\": \"8faed7c3efc128d4dde8869ecaf181a5ad5d4e9d\", \"cronet_tests\": \"7e3df91dffb761324071d83d9aa8aa63c8684081\", \"cronet_unittests\": \"8583d8b05833d1fab7bd1f3b416c03cc1c356d14\", \"crypto_unittests\": \"408cedd804a590f46bdbc31109b5026f9411b331\", \"dbus_unittests\": \"b20ae385d4fa63ffd2f659277953fdac1aa821aa\", \"device_unittests\": \"232a9531351829f8748382ace1db14b58624d9bd\", \"display_unittests\": \"3b52c782518cf54b82396a1a5b28672396c3d2eb\", \"events_unittests\": \"16f0cdab63b73849e77b9816645a07e50c879207\", \"extensions_browsertests\": \"51f99f328214f79113752df4c09d169ee8919c81\", \"extensions_unittests\": \"3b1827c421b6285798f357c19ce2a98ed35b29ac\", \"filesystem_service_unittests\": \"0acdb5b02b2e080d16acb0a162474f83e7e0938c\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"9000fbfe2d0eda88c27596f5cf634b512f39ef60\", \"gfx_unittests\": \"afc08f4d195e96ec59998031116bf1daf34a56bb\", \"gin_unittests\": \"23aaa3f25f16bdaf676dec2f54c6206b59d135cb\", \"google_apis_unittests\": \"ebf6718e705bf65a71eca15c9227ef6b7716f1bc\", \"gpu_unittests\": \"d3735b3b5b627db096b812c5ace8a32811bf6039\", \"grit_python_unittests\": \"896da2265579af982b0c06ff8662522b696fea99\", \"gwp_asan_unittests\": \"cafd9ea11a70a1d21885d53578b4784a5cadb6a4\", \"headless_browsertests\": \"144bde6db8392ce3f497d669c97838abcf9dda29\", \"headless_unittests\": \"a1702c39ca60ff9cde6fd7fc86505848d4f2d7e8\", \"interactive_ui_tests\": \"508bec5751197b17b2912c16141434c1174a7a7b\", \"ipc_tests\": \"a2c62d29101472e2b5eb8fc37419acadd4aae3f3\", \"jingle_unittests\": \"7001bd0b8f1589686666f2ea7d763490b266f70e\", \"latency_unittests\": \"42d902d007796c2972594bb8473c9b7f37205253\", \"libjingle_xmpp_unittests\": \"f1cf2fc8d0ce899bdb646d68ce1878189cd1d9df\", \"media_blink_unittests\": \"13b77ef7a34bbf1eeea4a7c4e7dc94e052cc42ff\", \"media_service_unittests\": \"59bcbfd7cb4e353a63448b658b89014a31ffc96b\", \"media_unittests\": \"fab300a18e1018dd026ffece6f4c40e7af413c46\", \"message_center_unittests\": \"7823f108829ee795e32db0db64bff661106ec1b3\", \"metrics_python_tests\": \"26c6edf7823cd8f30626555c1226c92aed3af528\", \"midi_unittests\": \"8c365ef38513bb14f0a48e44a3fa3a06b9c89ff7\", \"mojo_core_unittests\": \"d3a7ae21090747297c4f6448cfe523fe22bb5224\", \"mojo_unittests\": \"ff5cf13133ed207a6292cf53b680d4c3adacb463\", \"nacl_helper_nonsfi_unittests\": \"a25464394ed64bfb7d326ca763f27b753d11f870\", \"nacl_loader_unittests\": \"1e394c62420223fa48bc2a2f5e9a1172b5d155da\", \"native_theme_unittests\": \"ad93e41f29b4f6da14302740954c10c3113d3a00\", \"net_unittests\": \"b8b0c99a37fba6d9b10eb55456e54cecd7d9eb63\", \"pdf_unittests\": \"a6ca5843a8c4e165f47a8cc90c42b0605d5f98c0\", \"perfetto_unittests\": \"5c842424ee14b0b2f8c890683e919fe26a2b6ccf\", \"ppapi_unittests\": \"0bfd09dd10ed1102a9414ea6f1e8cb027fea0c1d\", \"printing_unittests\": \"c6717f45009d7414fea7724921b4592d59d331fa\", \"remoting_unittests\": \"8da8ca5ce74689349542edd31f178cebe7f97d17\", \"sandbox_linux_unittests\": \"f2295d731ff35c2b08fa973bd341b6020e60249b\", \"service_manager_unittests\": \"3a2e30aa9806a9d48a6b5413cd0f23851ea0dfe8\", \"services_unittests\": \"56522f77b9406a404d5084870436ea1598c8fb48\", \"shell_dialogs_unittests\": \"eebde7f92fca1d740451f404b854057be46aab05\", \"skia_unittests\": \"e2830372a48e1d3b90bd7daedf4786bd932b20f8\", \"snapshot_unittests\": \"d475f8dcd5cbe8d44fcb65e001d49b9a6489a7ed\", \"sql_unittests\": \"c381f80bf9420bc50e1b3f0fec74aeba7ff1ae3b\", \"storage_unittests\": \"318a536686c2dc232f23deb583294b24a7893a21\", \"sync_integration_tests\": \"72846b89749cfae8ef41e6fd3de1ef8e42d7f00a\", \"telemetry_gpu_unittests\": \"f5c448c0e59ee1dbd3234fbafbdae2bc9eabd0a3\", \"telemetry_perf_unittests\": \"7cca2a8e71528bdd23c6bebc08bce955c6da97d7\", \"telemetry_unittests\": \"51b5180470a4e953e17ffb8c602b475d514efa6f\", \"traffic_annotation_auditor_unittests\": \"ecaec32a8ee25c7277265540ed02e936e7b0f946\", \"ui_base_unittests\": \"822e82fbd6afd68dbcb75cb3e273303c9a13c929\", \"ui_touch_selection_unittests\": \"5c532afdc7e6ff8ea2b8dbd9245342ff68fe979d\", \"unit_tests\": \"855468038191bc65b1543b37743fc0669ce6e336\", \"url_unittests\": \"c09fd0afea33aa510b134c4f9a89ca599c0b58fc\", \"views_perftests\": \"fa5712c0867237854e23ef685e0c30c52b831895\", \"views_unittests\": \"2fd53946324baa06e61b9f63c21ec1ce2bfda400\", \"viz_unittests\": \"f06a875f1a0bdd7ea063f223464a7df41eb0ac22\", \"vr_common_unittests\": \"4e74f4a5219340eb42ffa55ea14c933188359e7e\", \"vr_pixeltests\": \"86f6ce9dc4af03d3bf0772d0003ad338c93c3f34\", \"webdriver_wpt_tests\": \"ca221eab29d87ba2da511f9a439f1ddd055cb42d\", \"weblayer_browsertests\": \"0e160b577ea0acc68b39622fc1759e15dc54db89\", \"wm_unittests\": \"80aa0370314483bb4ac11529eb5b897f0fe47155\", \"wtf_unittests\": \"3d9e0aa4a704d1d2bf523efd0cc647d3b83a0684\", \"xr_browser_tests\": \"4c7d514707873c2ef64acc22c0a60dc94916349c\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0095fe006eafdabf54002a791fb8c836a288874203ece8347c24b74b8d090ce3_v2\", \"builder_f5174a4d80cdc4c8aa1989fa45d69979d28910f768af140dd646791c34143bfe_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-central1-b-8-134-ve57\"], \"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\": [\"4801-8ac0b59\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1580320629381956",
"status": "COMPLETED",
"status_changed_ts": "1580323379433085",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167994",
"builder:Linux Builder (dbg)",
"buildset:commit/git/e974948e932fa68e0ffa262fbb1d8a4328a40938",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e974948e932fa68e0ffa262fbb1d8a4328a40938",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055337805598344592",
"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/8889907743194861568/+/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:4a0b4026ad500310",
"user_agent:luci-scheduler"
],
"updated_ts": "1580323379438046",
"url": "https://ci.chromium.org/b/8889907743194861568",
"utcnow_ts": "1580323380265879"
}
}
[18:43:00.274] Invocation finished in 50m49.648688567s with status SUCCEEDED