[14:49:17.072] New invocation is queued and will start shortly
[14:49:18.166] Starting the invocation (attempt 1)
[14:49:18.204] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:49:18.204] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:49:18.204] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9059244981793094384",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ca64895e22d3a30b6d74ac4eac4fcfafd73466bc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9059244981793094384",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/ca64895e22d3a30b6d74ac4eac4fcfafd73466bc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ca64895e22d3a30b6d74ac4eac4fcfafd73466bc",
"gitiles_ref:refs/heads/master"
]
}
[14:49:19.784] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1576594158704950",
"id": "8893814919400620720",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ca64895e22d3a30b6d74ac4eac4fcfafd73466bc\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1576594159516463",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166210",
"builder:Linux Builder (dbg)",
"buildset:commit/git/ca64895e22d3a30b6d74ac4eac4fcfafd73466bc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ca64895e22d3a30b6d74ac4eac4fcfafd73466bc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059244981793094384",
"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/8893814919400620720/+/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": "1576594159516616",
"url": "https://ci.chromium.org/b/8893814919400620720",
"utcnow_ts": "1576594159766375"
}
}
[14:49:19.784] Task URL: https://ci.chromium.org/b/8893814919400620720
[14:49:19.784] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:2:0) after 1m0s
[14:50:19.790] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:2:0)
[14:50:19.791] Timer tick, asking Buildbucket for the build status
[14:50:21.533] Build 8893814919400620720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:50:21.533] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:3:0) after 1m0s
[14:51:21.698] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:3:0)
[14:51:21.698] Timer tick, asking Buildbucket for the build status
[14:51:22.242] Build 8893814919400620720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:51:22.242] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:4:0) after 1m0s
[14:52:22.286] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:4:0)
[14:52:22.286] Timer tick, asking Buildbucket for the build status
[14:52:22.865] Build 8893814919400620720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:52:22.865] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:5:0) after 1m0s
[14:53:22.942] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:5:0)
[14:53:22.942] Timer tick, asking Buildbucket for the build status
[14:53:23.723] Build 8893814919400620720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:53:23.723] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:6:0) after 1m0s
[14:54:17.086] Received PubSub notification, asking Buildbucket for the build status
[14:54:17.479] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:23.822] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:6:0)
[14:54:23.822] Timer tick, asking Buildbucket for the build status
[14:54:24.232] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:24.232] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:8:0) after 1m0s
[14:55:24.228] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:8:0)
[14:55:24.228] Timer tick, asking Buildbucket for the build status
[14:55:24.433] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:24.433] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:9:0) after 1m0s
[14:56:24.483] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:9:0)
[14:56:24.483] Timer tick, asking Buildbucket for the build status
[14:56:24.634] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:24.634] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:10:0) after 1m0s
[14:57:24.807] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:10:0)
[14:57:24.807] Timer tick, asking Buildbucket for the build status
[14:57:25.099] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:25.099] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:11:0) after 1m0s
[14:58:25.245] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:11:0)
[14:58:25.245] Timer tick, asking Buildbucket for the build status
[14:58:25.439] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:25.439] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:12:0) after 1m0s
[14:59:25.466] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:12:0)
[14:59:25.466] Timer tick, asking Buildbucket for the build status
[14:59:26.032] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:26.032] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:13:0) after 1m0s
[15:00:26.216] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:13:0)
[15:00:26.216] Timer tick, asking Buildbucket for the build status
[15:00:26.410] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:26.410] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:14:0) after 1m0s
[15:01:26.529] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:14:0)
[15:01:26.529] Timer tick, asking Buildbucket for the build status
[15:01:26.725] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:26.725] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:15:0) after 1m0s
[15:02:26.772] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:15:0)
[15:02:26.772] Timer tick, asking Buildbucket for the build status
[15:02:27.383] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:27.383] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:16:0) after 1m0s
[15:03:27.364] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:16:0)
[15:03:27.364] Timer tick, asking Buildbucket for the build status
[15:03:27.655] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:27.655] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:17:0) after 1m0s
[15:04:27.684] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:17:0)
[15:04:27.684] Timer tick, asking Buildbucket for the build status
[15:04:27.895] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:27.895] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:18:0) after 1m0s
[15:05:27.904] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:18:0)
[15:05:27.904] Timer tick, asking Buildbucket for the build status
[15:05:28.270] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:28.270] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:19:0) after 1m0s
[15:06:28.357] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:19:0)
[15:06:28.357] Timer tick, asking Buildbucket for the build status
[15:06:28.784] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:28.784] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:20:0) after 1m0s
[15:07:29.073] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:20:0)
[15:07:29.073] Timer tick, asking Buildbucket for the build status
[15:07:29.286] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:29.286] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:21:0) after 1m0s
[15:08:29.366] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:21:0)
[15:08:29.366] Timer tick, asking Buildbucket for the build status
[15:08:30.597] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:30.597] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:22:0) after 1m0s
[15:09:30.671] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:22:0)
[15:09:30.671] Timer tick, asking Buildbucket for the build status
[15:09:30.892] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:30.892] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:23:0) after 1m0s
[15:10:30.997] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:23:0)
[15:10:30.997] Timer tick, asking Buildbucket for the build status
[15:10:31.390] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:31.390] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:24:0) after 1m0s
[15:11:31.441] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:24:0)
[15:11:31.441] Timer tick, asking Buildbucket for the build status
[15:11:32.398] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:32.398] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:25:0) after 1m0s
[15:12:32.466] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:25:0)
[15:12:32.466] Timer tick, asking Buildbucket for the build status
[15:12:33.048] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:33.048] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:26:0) after 1m0s
[15:13:33.270] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:26:0)
[15:13:33.270] Timer tick, asking Buildbucket for the build status
[15:13:33.801] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:33.801] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:27:0) after 1m0s
[15:14:33.856] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:27:0)
[15:14:33.856] Timer tick, asking Buildbucket for the build status
[15:14:34.085] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:34.085] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:28:0) after 1m0s
[15:15:34.282] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:28:0)
[15:15:34.282] Timer tick, asking Buildbucket for the build status
[15:15:34.717] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:34.717] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:29:0) after 1m0s
[15:16:34.781] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:29:0)
[15:16:34.781] Timer tick, asking Buildbucket for the build status
[15:16:35.875] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:35.875] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:30:0) after 1m0s
[15:17:36.003] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:30:0)
[15:17:36.003] Timer tick, asking Buildbucket for the build status
[15:17:36.769] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:36.769] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:31:0) after 1m0s
[15:18:36.793] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:31:0)
[15:18:36.793] Timer tick, asking Buildbucket for the build status
[15:18:37.603] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:37.603] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:32:0) after 1m0s
[15:19:37.633] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:32:0)
[15:19:37.633] Timer tick, asking Buildbucket for the build status
[15:19:38.073] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:38.073] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:33:0) after 1m0s
[15:20:38.163] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:33:0)
[15:20:38.163] Timer tick, asking Buildbucket for the build status
[15:20:38.624] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:38.624] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:34:0) after 1m0s
[15:21:38.650] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:34:0)
[15:21:38.650] Timer tick, asking Buildbucket for the build status
[15:21:38.788] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:38.788] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:35:0) after 1m0s
[15:22:38.912] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:35:0)
[15:22:38.912] Timer tick, asking Buildbucket for the build status
[15:22:39.477] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:39.477] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:36:0) after 1m0s
[15:23:39.541] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:36:0)
[15:23:39.541] Timer tick, asking Buildbucket for the build status
[15:23:40.044] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:40.044] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:37:0) after 1m0s
[15:24:40.175] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:37:0)
[15:24:40.175] Timer tick, asking Buildbucket for the build status
[15:24:40.351] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:40.351] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:38:0) after 1m0s
[15:25:40.409] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:38:0)
[15:25:40.409] Timer tick, asking Buildbucket for the build status
[15:25:40.893] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:40.893] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:39:0) after 1m0s
[15:26:40.926] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:39:0)
[15:26:40.926] Timer tick, asking Buildbucket for the build status
[15:26:41.239] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:41.239] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:40:0) after 1m0s
[15:27:41.382] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:40:0)
[15:27:41.382] Timer tick, asking Buildbucket for the build status
[15:27:41.805] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:41.805] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:41:0) after 1m0s
[15:28:41.979] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:41:0)
[15:28:41.979] Timer tick, asking Buildbucket for the build status
[15:28:42.230] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:42.230] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:42:0) after 1m0s
[15:29:42.308] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:42:0)
[15:29:42.308] Timer tick, asking Buildbucket for the build status
[15:29:42.514] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:42.514] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:43:0) after 1m0s
[15:30:42.574] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:43:0)
[15:30:42.574] Timer tick, asking Buildbucket for the build status
[15:30:43.635] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:43.635] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:44:0) after 1m0s
[15:31:43.705] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:44:0)
[15:31:43.705] Timer tick, asking Buildbucket for the build status
[15:31:44.502] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:44.502] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:45:0) after 1m0s
[15:32:44.580] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:45:0)
[15:32:44.580] Timer tick, asking Buildbucket for the build status
[15:32:44.958] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:44.958] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:46:0) after 1m0s
[15:33:44.971] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:46:0)
[15:33:44.971] Timer tick, asking Buildbucket for the build status
[15:33:45.302] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:45.302] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:47:0) after 1m0s
[15:34:45.328] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:47:0)
[15:34:45.329] Timer tick, asking Buildbucket for the build status
[15:34:45.638] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:45.638] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:48:0) after 1m0s
[15:35:45.659] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:48:0)
[15:35:45.659] Timer tick, asking Buildbucket for the build status
[15:35:46.003] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:46.003] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:49:0) after 1m0s
[15:36:46.102] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:49:0)
[15:36:46.102] Timer tick, asking Buildbucket for the build status
[15:36:46.285] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:46.285] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:50:0) after 1m0s
[15:37:46.419] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:50:0)
[15:37:46.419] Timer tick, asking Buildbucket for the build status
[15:37:46.816] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:46.816] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:51:0) after 1m0s
[15:38:46.853] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:51:0)
[15:38:46.853] Timer tick, asking Buildbucket for the build status
[15:38:48.043] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:48.043] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:52:0) after 1m0s
[15:39:48.256] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:52:0)
[15:39:48.256] Timer tick, asking Buildbucket for the build status
[15:39:48.542] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:48.542] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:53:0) after 1m0s
[15:40:48.691] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:53:0)
[15:40:48.691] Timer tick, asking Buildbucket for the build status
[15:40:48.869] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:48.869] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:54:0) after 1m0s
[15:41:48.976] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:54:0)
[15:41:48.976] Timer tick, asking Buildbucket for the build status
[15:41:49.147] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:49.147] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:55:0) after 1m0s
[15:42:49.265] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:55:0)
[15:42:49.265] Timer tick, asking Buildbucket for the build status
[15:42:49.510] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:49.510] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:56:0) after 1m0s
[15:43:49.579] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:56:0)
[15:43:49.579] Timer tick, asking Buildbucket for the build status
[15:43:50.367] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:50.367] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:57:0) after 1m0s
[15:44:50.454] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:57:0)
[15:44:50.454] Timer tick, asking Buildbucket for the build status
[15:44:50.678] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:50.678] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:58:0) after 1m0s
[15:45:50.714] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:58:0)
[15:45:50.715] Timer tick, asking Buildbucket for the build status
[15:45:50.931] Build 8893814919400620720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:50.931] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059244981793094384:59:0) after 1m0s
[15:46:20.357] Received PubSub notification, asking Buildbucket for the build status
[15:46:20.556] Build 8893814919400620720: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:46:20.556] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1576597579317506",
"created_by": "project:chromium",
"created_ts": "1576594158704950",
"id": "8893814919400620720",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ca64895e22d3a30b6d74ac4eac4fcfafd73466bc\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-22-d9gx\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 166210, \"got_angle_revision\": \"7587588595901010a7b8d8683d06796ae420edb8\", \"got_dawn_revision\": \"1bbbe8f52da580bd245af733e068eaabacf72731\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"ca64895e22d3a30b6d74ac4eac4fcfafd73466bc\", \"got_revision_cp\": \"refs/heads/master@{#725517}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"fbbfeb700295d1fe1ec6419a93594e5e1540ea32\", \"got_v8_revision\": \"3e618bf6f5df3bdcb906db22d65671971c74038d\", \"got_v8_revision_cp\": \"refs/heads/8.1.48@{#1}\", \"got_webrtc_revision\": \"00a1bcb441d61b81e605121dfb1a7342f83ac638\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30105}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ca64895e22d3a30b6d74ac4eac4fcfafd73466bc\", \"swarm_hashes_refs/heads/master(at){#725517}_without_patch\": {\"accessibility_unittests\": \"85689920c68a6f16481b8a3476dd3b82a1659105\", \"angle_unittests\": \"eb27a8405f5a6f2873f82bd755d7f2861e6df9e1\", \"app_shell_unittests\": \"ed7a2ea980471ff97ecb2624a66147e2afcb3dea\", \"aura_unittests\": \"d22f44e570eba45e1fb496cfa2b526dd8a40d8e2\", \"base_unittests\": \"cb346d7ab6feb57b1433ca347bdaba87c99dc94c\", \"base_util_unittests\": \"d63537b581dbdb1a4e700d94c4732b5af7458dfa\", \"blink_common_unittests\": \"1ade5c54e6b5283d552af1195b5f30f640e54c30\", \"blink_fuzzer_unittests\": \"825d99f16eaba596bc3484200c545a243493b2bc\", \"blink_heap_unittests\": \"ae2428b4990959e8110d005b1a46985cde7451df\", \"blink_platform_unittests\": \"b253cdc1228cbae70818ccfd9375f141036e0f8c\", \"blink_python_tests\": \"a1717e09b120567f83db7704283a157e68c43445\", \"blink_unittests\": \"151b7294a545505e135be5033758176e87754a2c\", \"blink_web_tests\": \"519b11c88f02ee69fedcfc6bbc30f85a55035a65\", \"boringssl_crypto_tests\": \"2b3438fae1f5284809a2e15e686fb9ea0ab43ef7\", \"boringssl_ssl_tests\": \"4419018ff4c026bf8aacc01ac79419b72cd84f25\", \"browser_tests\": \"18c78392da3837e84edf5724907912468222bccb\", \"cacheinvalidation_unittests\": \"4684c5482aeb8ee957277a0d31462af78a23cba2\", \"capture_unittests\": \"0c12a30353d34156ff3ab12e6c1ca59d531e60a8\", \"cast_unittests\": \"63c9855a50e35700be3731d4a12b0b7f7d598b3d\", \"cc_unittests\": \"8378e5283b078354150ea089f80e6da12dbfb988\", \"chrome_app_unittests\": \"0399a8861fffc1b0fbb37dcc5dd9c2573e10e24a\", \"chromedriver_unittests\": \"86ac370a5c37926682f7cab1bed3b9d165c1aa2c\", \"components_browsertests\": \"f0fcee56394b4b4126edfe0c4bf2b01131d4646d\", \"components_unittests\": \"77b234fa6d2e63a766cadbef5203156a2ca88f62\", \"compositor_unittests\": \"d4cabcbf3886c5414384bed50ca912f60dde7fd6\", \"content_browsertests\": \"f908944864e425e9ff0d3be27090ed459be9b615\", \"content_shell_crash_test\": \"03007556529547ff9c7f52905312f58b5a71bed5\", \"content_unittests\": \"85b2cfba045c21cb381c8dadc10c5093d9df9f5a\", \"crashpad_tests\": \"ccf664b07e088fc054e8ab166b2e4048ff18552b\", \"cronet_tests\": \"f6e733bf811d1d47f821bb84116ecb15375a7395\", \"cronet_unittests\": \"048b086fd45332b26babda3ecbf39beb37fc2202\", \"crypto_unittests\": \"d676d6e57641766941de1a2f10dc6dc0e985b7bc\", \"dbus_unittests\": \"0e1c6879ddc0b9216303ae0fc9750275dac6bab3\", \"device_unittests\": \"32681a7a49c5c70a48a1ff80ab851191043b4757\", \"devtools_lint_check\": \"4835397faf90a3182262520aa392124ccc9b34a4\", \"devtools_type_check\": \"bb351818296bb1df85df9ace85b0b7ad57f2ec6c\", \"display_unittests\": \"9f3209ef9bfffc1520658526b559c171f0e0935e\", \"events_unittests\": \"e3c98f5013ce98d53d2656163779453ce9c21950\", \"extensions_browsertests\": \"d44c041a0f9e3938ff36d108463bd5e52280c705\", \"extensions_unittests\": \"6a877b746ba1ef6da42925c548d9349fcd6e9de8\", \"filesystem_service_unittests\": \"0542bc850280ecdab0b452586d431912493db214\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"6e62ec37a006450b190a7604042781766cec42c4\", \"gfx_unittests\": \"50a0359c98d37282c82355f9098b974c78912941\", \"gin_unittests\": \"b5c0134a7cbf9aa48a75603bb521690a4494d809\", \"google_apis_unittests\": \"c47e4052805bd88156d78e055633b955a7c220a9\", \"gpu_unittests\": \"e1fa7186d28b96057ca6b6a49289c281858ccb92\", \"grit_python_unittests\": \"25dff9ef6327c596609d376409bc1d0e605b6d43\", \"gwp_asan_unittests\": \"ca32531f1781ed40df41a978c4eca4e257257e01\", \"headless_browsertests\": \"7a845134b153bc67987944ae480d03ef23631425\", \"headless_unittests\": \"3fb5c54ed9c6977feaf15da1fa7223b080eb4c8e\", \"interactive_ui_tests\": \"45d87e8c57edaf6cbf3566854b7b46d63ab2b1ef\", \"ipc_tests\": \"d438ed1937241e7cd72022d2153d1f85e7cba321\", \"jingle_unittests\": \"d8ce864180d36ac9ecdb10a5c6b75b39dc787407\", \"latency_unittests\": \"3c3b22828da661acb3bb6952b4e4c46d3385ec06\", \"libjingle_xmpp_unittests\": \"93697c1b0f9ef3e34fa5c6b9dd38dd9a25f1727d\", \"media_blink_unittests\": \"9ca9f360276d571862633bb38b45d17c29bf7b55\", \"media_service_unittests\": \"41cdd80e4780ba35a181f3987d26861d020a6b80\", \"media_unittests\": \"cbb8e22a04e15a091f2e91c3c776d0910d82c0d1\", \"message_center_unittests\": \"9a6bf9e0a8ed3b0b838d7e1c1798c40018ecda1c\", \"metrics_python_tests\": \"750d227852bd5ebd94041d9758f4893ee93cd22d\", \"midi_unittests\": \"fd49b31d066938b305a06fbaf176159292b37649\", \"mojo_core_unittests\": \"6346723486534d5be796cba288ec1655698c12b0\", \"mojo_unittests\": \"4ee0a9b22a6dd50111c976cf28f2bebe083b8921\", \"nacl_helper_nonsfi_unittests\": \"3d8558f43cc7a071137e0d48cadb0c46a3efb997\", \"nacl_loader_unittests\": \"2d0c9d52e9649fe5c376caeb731b647173f462d1\", \"native_theme_unittests\": \"14c01bde99ec60724077a2af460e2088991a6d58\", \"net_unittests\": \"c33149c554166b4b48abdc1af9e64e1b96efd989\", \"pdf_unittests\": \"6ec772b72692cb4313c73a3865d6796cd5085f99\", \"perfetto_unittests\": \"0132c3935d929934b10ad398b71da1683cf6c1dd\", \"ppapi_unittests\": \"be131bc816e9bf22559a36545834e98eccfbb5fd\", \"printing_unittests\": \"8a86a36e1249c87ed183165a5e06b3852b3f8812\", \"remoting_unittests\": \"af67030099c59017652d463dc2afb43ad042a3db\", \"sandbox_linux_unittests\": \"9540f64c28a57ebd84975d3d85627fc765150c7f\", \"service_manager_unittests\": \"67119757786ba130d9322ae9a8a654651c8ebee2\", \"services_unittests\": \"58774dbcb69c7bd7e7684788454e026cb7127712\", \"shell_dialogs_unittests\": \"e6985189390c1d2a115bdc5decb1335957ddc2e6\", \"skia_unittests\": \"dafc6e22978a933ec09876a50bd2aae92b193424\", \"snapshot_unittests\": \"259e3ef7c8357734da6c8caecd9ddae2fa516e1a\", \"sql_unittests\": \"a4b4d24587de267a5fdc4e62a1ccc64031543ddf\", \"storage_unittests\": \"9727358244f5eea2f4b25734468543263ae6bf3d\", \"sync_integration_tests\": \"ced56a6701a8abe0cc1d86c37027170544e48471\", \"telemetry_gpu_unittests\": \"7350fd5eadaaed6c375cf4598da67c5b87de4d9d\", \"telemetry_perf_unittests\": \"22fee0b37159477834a1baf75957f803780c3f6d\", \"telemetry_unittests\": \"c94ebdb320b8226eafe38a2c9c5d2a2fb83f094a\", \"traffic_annotation_auditor_unittests\": \"e1130ba14fc095873142e910be078a36342b31c1\", \"ui_base_unittests\": \"242b30c82e0de2e23cd47f084e382aec77a4dadd\", \"ui_touch_selection_unittests\": \"02a1dc2f5ff96120e7e9c9b3231c82a52a88874d\", \"unit_tests\": \"b44ffdab4756aa5fc880d597082c8576a715eb2f\", \"url_unittests\": \"9f956c7b19d856c269e848e2dcfe98234447047d\", \"views_perftests\": \"358a840ecb620fe6249ccef57ba8fa3520100c30\", \"views_unittests\": \"51780c00e85c67d42c112473c31907b2ee4ae41e\", \"viz_unittests\": \"8f40e5b089d36c0045b6e023fc2cb7a225eed4bd\", \"vr_common_unittests\": \"86639268587e52c6e63e831fed6e3145e22f425f\", \"vr_pixeltests\": \"3e3f6f98d2919cc3a3842461d5431b68caec4c2b\", \"webdriver_wpt_tests\": \"00261ef32d8ae353d62e84bd82e89eb7e3703208\", \"weblayer_browsertests\": \"5f0f95b7877857c80c9ce42d4bb2bf4393221602\", \"wm_unittests\": \"6b7c9b9630f163ecae4326ccb8ba1740a6ac2a50\", \"wtf_unittests\": \"4b7b9adbf383c37a159db929d3e191135d548579\", \"xr_browser_tests\": \"ddf4d41e3b559a3f7b7b5aa4aaf9f2ab21b28e7a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_016577e073ce9abf0fa10d80f88bc2caf8f81662b409bd6e59fa52089e44a3df_v2\", \"builder_21a338e382a0dbd787bea981107dd7bac336a437920c3cc1b87ea8999b8bcb15_v2\", \"builder_9b311965bcdd39b39aa4e18428ed38a374d2d5d9f2ab24593845b266390676f3_v2\", \"builder_eae3a4dc277b4e2282e9fce002ae6861b5ee5509389502cf3acc9366ec79a2d3_v2\", \"builder_ee0cba3c54f4de5afe190f5f1f18eea3c236437d0f634917582eff9316b81bdc_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-22-d9gx\"], \"image\": [\"chrome-xenial-19120800-09d8782d9a1\"], \"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\": [\"4730-f2fc813\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1576594452904346",
"status": "COMPLETED",
"status_changed_ts": "1576597579757100",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166210",
"builder:Linux Builder (dbg)",
"buildset:commit/git/ca64895e22d3a30b6d74ac4eac4fcfafd73466bc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ca64895e22d3a30b6d74ac4eac4fcfafd73466bc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059244981793094384",
"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/8893814919400620720/+/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:492d274cb803aa10",
"user_agent:luci-scheduler"
],
"updated_ts": "1576597579815830",
"url": "https://ci.chromium.org/b/8893814919400620720",
"utcnow_ts": "1576597580541443"
}
}
[15:46:20.556] Invocation finished in 57m3.517050881s with status SUCCEEDED