[02:02:22.899] New invocation is queued and will start shortly
[02:02:24.432] Starting the invocation (attempt 1)
[02:02:24.464] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:02:24.464] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:02:24.464] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054219801031574992",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f4949c487ded2f72f5f7fde6cc08bb333a9208db\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054219801031574992",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/f4949c487ded2f72f5f7fde6cc08bb333a9208db",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f4949c487ded2f72f5f7fde6cc08bb333a9208db",
"gitiles_ref:refs/heads/master"
]
}
[02:02:26.620] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581386545997614",
"id": "8888789737103321664",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f4949c487ded2f72f5f7fde6cc08bb333a9208db\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581386546492296",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168468",
"builder:Linux Builder (dbg)",
"buildset:commit/git/f4949c487ded2f72f5f7fde6cc08bb333a9208db",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f4949c487ded2f72f5f7fde6cc08bb333a9208db",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054219801031574992",
"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/8888789737103321664/+/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": "1581386546492415",
"url": "https://ci.chromium.org/b/8888789737103321664",
"utcnow_ts": "1581386546611222"
}
}
[02:02:26.620] Task URL: https://ci.chromium.org/b/8888789737103321664
[02:02:26.620] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:2:0) after 1m0s
[02:03:26.671] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:2:0)
[02:03:26.671] Timer tick, asking Buildbucket for the build status
[02:03:26.823] Build 8888789737103321664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:03:26.823] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:3:0) after 1m0s
[02:04:26.840] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:3:0)
[02:04:26.841] Timer tick, asking Buildbucket for the build status
[02:04:27.651] Build 8888789737103321664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:04:27.651] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:4:0) after 1m0s
[02:05:27.668] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:4:0)
[02:05:27.668] Timer tick, asking Buildbucket for the build status
[02:05:28.450] Build 8888789737103321664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:05:28.450] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:5:0) after 1m0s
[02:06:28.517] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:5:0)
[02:06:28.517] Timer tick, asking Buildbucket for the build status
[02:06:28.668] Build 8888789737103321664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:06:28.668] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:6:0) after 1m0s
[02:07:28.767] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:6:0)
[02:07:28.767] Timer tick, asking Buildbucket for the build status
[02:07:29.823] Build 8888789737103321664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:07:29.823] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:7:0) after 1m0s
[02:07:46.267] Received PubSub notification, asking Buildbucket for the build status
[02:07:46.391] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:29.955] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:7:0)
[02:08:29.955] Timer tick, asking Buildbucket for the build status
[02:08:30.144] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:30.144] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:9:0) after 1m0s
[02:09:30.169] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:9:0)
[02:09:30.169] Timer tick, asking Buildbucket for the build status
[02:09:30.306] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:30.306] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:10:0) after 1m0s
[02:10:30.342] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:10:0)
[02:10:30.342] Timer tick, asking Buildbucket for the build status
[02:10:31.136] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:31.136] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:11:0) after 1m0s
[02:11:31.259] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:11:0)
[02:11:31.259] Timer tick, asking Buildbucket for the build status
[02:11:31.494] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:31.494] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:12:0) after 1m0s
[02:12:31.667] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:12:0)
[02:12:31.667] Timer tick, asking Buildbucket for the build status
[02:12:31.829] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:31.829] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:13:0) after 1m0s
[02:13:32.024] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:13:0)
[02:13:32.024] Timer tick, asking Buildbucket for the build status
[02:13:32.944] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:32.944] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:14:0) after 1m0s
[02:14:33.138] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:14:0)
[02:14:33.138] Timer tick, asking Buildbucket for the build status
[02:14:33.334] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:33.334] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:15:0) after 1m0s
[02:15:33.476] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:15:0)
[02:15:33.476] Timer tick, asking Buildbucket for the build status
[02:15:33.721] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:33.721] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:16:0) after 1m0s
[02:16:33.902] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:16:0)
[02:16:33.902] Timer tick, asking Buildbucket for the build status
[02:16:34.059] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:34.059] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:17:0) after 1m0s
[02:17:34.146] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:17:0)
[02:17:34.146] Timer tick, asking Buildbucket for the build status
[02:17:34.345] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:34.345] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:18:0) after 1m0s
[02:18:34.359] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:18:0)
[02:18:34.359] Timer tick, asking Buildbucket for the build status
[02:18:34.531] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:34.531] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:19:0) after 1m0s
[02:19:34.609] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:19:0)
[02:19:34.609] Timer tick, asking Buildbucket for the build status
[02:19:34.775] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:34.775] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:20:0) after 1m0s
[02:20:34.813] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:20:0)
[02:20:34.813] Timer tick, asking Buildbucket for the build status
[02:20:35.421] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:35.421] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:21:0) after 1m0s
[02:21:35.534] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:21:0)
[02:21:35.534] Timer tick, asking Buildbucket for the build status
[02:21:35.760] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:35.760] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:22:0) after 1m0s
[02:22:35.834] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:22:0)
[02:22:35.834] Timer tick, asking Buildbucket for the build status
[02:22:35.982] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:35.982] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:23:0) after 1m0s
[02:23:36.053] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:23:0)
[02:23:36.053] Timer tick, asking Buildbucket for the build status
[02:23:37.105] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:37.105] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:24:0) after 1m0s
[02:24:37.194] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:24:0)
[02:24:37.194] Timer tick, asking Buildbucket for the build status
[02:24:37.363] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:37.363] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:25:0) after 1m0s
[02:25:37.447] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:25:0)
[02:25:37.447] Timer tick, asking Buildbucket for the build status
[02:25:37.657] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:37.657] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:26:0) after 1m0s
[02:26:37.714] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:26:0)
[02:26:37.714] Timer tick, asking Buildbucket for the build status
[02:26:38.336] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:38.336] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:27:0) after 1m0s
[02:27:38.441] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:27:0)
[02:27:38.441] Timer tick, asking Buildbucket for the build status
[02:27:38.585] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:38.585] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:28:0) after 1m0s
[02:28:38.651] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:28:0)
[02:28:38.651] Timer tick, asking Buildbucket for the build status
[02:28:39.232] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:39.232] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:29:0) after 1m0s
[02:29:39.325] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:29:0)
[02:29:39.325] Timer tick, asking Buildbucket for the build status
[02:29:39.577] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:39.577] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:30:0) after 1m0s
[02:30:39.681] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:30:0)
[02:30:39.681] Timer tick, asking Buildbucket for the build status
[02:30:40.054] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:40.054] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:31:0) after 1m0s
[02:31:40.247] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:31:0)
[02:31:40.247] Timer tick, asking Buildbucket for the build status
[02:31:40.591] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:40.591] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:32:0) after 1m0s
[02:32:40.753] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:32:0)
[02:32:40.753] Timer tick, asking Buildbucket for the build status
[02:32:41.462] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:41.462] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:33:0) after 1m0s
[02:33:41.605] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:33:0)
[02:33:41.605] Timer tick, asking Buildbucket for the build status
[02:33:41.791] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:41.791] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:34:0) after 1m0s
[02:34:42.039] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:34:0)
[02:34:42.039] Timer tick, asking Buildbucket for the build status
[02:34:42.221] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:42.221] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:35:0) after 1m0s
[02:35:42.432] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:35:0)
[02:35:42.432] Timer tick, asking Buildbucket for the build status
[02:35:42.582] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:42.582] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:36:0) after 1m0s
[02:36:42.662] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:36:0)
[02:36:42.662] Timer tick, asking Buildbucket for the build status
[02:36:43.022] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:43.022] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:37:0) after 1m0s
[02:37:43.199] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:37:0)
[02:37:43.199] Timer tick, asking Buildbucket for the build status
[02:37:43.672] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:43.672] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:38:0) after 1m0s
[02:38:43.849] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:38:0)
[02:38:43.849] Timer tick, asking Buildbucket for the build status
[02:38:44.222] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:44.222] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:39:0) after 1m0s
[02:39:44.350] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:39:0)
[02:39:44.350] Timer tick, asking Buildbucket for the build status
[02:39:45.080] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:45.080] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:40:0) after 1m0s
[02:40:45.100] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:40:0)
[02:40:45.100] Timer tick, asking Buildbucket for the build status
[02:40:46.081] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:46.081] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:41:0) after 1m0s
[02:41:46.061] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:41:0)
[02:41:46.061] Timer tick, asking Buildbucket for the build status
[02:41:46.305] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:46.305] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:42:0) after 1m0s
[02:42:46.355] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:42:0)
[02:42:46.355] Timer tick, asking Buildbucket for the build status
[02:42:47.299] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:47.299] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:43:0) after 1m0s
[02:43:47.385] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:43:0)
[02:43:47.385] Timer tick, asking Buildbucket for the build status
[02:43:47.761] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:47.761] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:44:0) after 1m0s
[02:44:47.874] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:44:0)
[02:44:47.874] Timer tick, asking Buildbucket for the build status
[02:44:48.058] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:48.058] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:45:0) after 1m0s
[02:45:48.141] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:45:0)
[02:45:48.141] Timer tick, asking Buildbucket for the build status
[02:45:48.656] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:48.656] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:46:0) after 1m0s
[02:46:48.723] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:46:0)
[02:46:48.723] Timer tick, asking Buildbucket for the build status
[02:46:49.317] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:49.317] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:47:0) after 1m0s
[02:47:49.304] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:47:0)
[02:47:49.304] Timer tick, asking Buildbucket for the build status
[02:47:49.877] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:49.877] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:48:0) after 1m0s
[02:48:49.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:48:0)
[02:48:49.933] Timer tick, asking Buildbucket for the build status
[02:48:50.150] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:50.150] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:49:0) after 1m0s
[02:49:50.194] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:49:0)
[02:49:50.194] Timer tick, asking Buildbucket for the build status
[02:49:50.838] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:50.838] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:50:0) after 1m0s
[02:50:50.869] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:50:0)
[02:50:50.869] Timer tick, asking Buildbucket for the build status
[02:50:51.250] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:51.250] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:51:0) after 1m0s
[02:51:51.312] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:51:0)
[02:51:51.312] Timer tick, asking Buildbucket for the build status
[02:51:51.464] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:51.464] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:52:0) after 1m0s
[02:52:51.553] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:52:0)
[02:52:51.554] Timer tick, asking Buildbucket for the build status
[02:52:51.748] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:51.748] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:53:0) after 1m0s
[02:53:51.792] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:53:0)
[02:53:51.792] Timer tick, asking Buildbucket for the build status
[02:53:51.950] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:51.950] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:54:0) after 1m0s
[02:54:52.066] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:54:0)
[02:54:52.066] Timer tick, asking Buildbucket for the build status
[02:54:52.276] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:52.276] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:55:0) after 1m0s
[02:55:52.285] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:55:0)
[02:55:52.285] Timer tick, asking Buildbucket for the build status
[02:55:52.589] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:52.589] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:56:0) after 1m0s
[02:56:52.639] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:56:0)
[02:56:52.639] Timer tick, asking Buildbucket for the build status
[02:56:53.274] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:53.274] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:57:0) after 1m0s
[02:57:53.322] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:57:0)
[02:57:53.322] Timer tick, asking Buildbucket for the build status
[02:57:53.477] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:53.478] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:58:0) after 1m0s
[02:58:53.501] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:58:0)
[02:58:53.501] Timer tick, asking Buildbucket for the build status
[02:58:53.788] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:53.788] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:59:0) after 1m0s
[02:59:53.810] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:59:0)
[02:59:53.810] Timer tick, asking Buildbucket for the build status
[02:59:53.934] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:53.934] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:60:0) after 1m0s
[03:00:54.055] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:60:0)
[03:00:54.055] Timer tick, asking Buildbucket for the build status
[03:00:54.841] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:54.841] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:61:0) after 1m0s
[03:01:54.848] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:61:0)
[03:01:54.848] Timer tick, asking Buildbucket for the build status
[03:01:55.062] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:55.062] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:62:0) after 1m0s
[03:02:55.113] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:62:0)
[03:02:55.113] Timer tick, asking Buildbucket for the build status
[03:02:55.684] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:55.684] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:63:0) after 1m0s
[03:03:55.729] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:63:0)
[03:03:55.729] Timer tick, asking Buildbucket for the build status
[03:03:56.783] Build 8888789737103321664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:56.783] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054219801031574992:64:0) after 1m0s
[03:04:50.212] Received PubSub notification, asking Buildbucket for the build status
[03:04:50.481] Build 8888789737103321664: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:04:50.481] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581390288904860",
"created_by": "project:chromium",
"created_ts": "1581386545997614",
"id": "8888789737103321664",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f4949c487ded2f72f5f7fde6cc08bb333a9208db\"}}",
"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-96-81mi\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168468, \"got_angle_revision\": \"fc1e8503d88b8cfaa35610c382f0265be163f54a\", \"got_dawn_revision\": \"713d9cbf939e525e025a69a17bda0bf51b449c47\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"f4949c487ded2f72f5f7fde6cc08bb333a9208db\", \"got_revision_cp\": \"refs/heads/master@{#740131}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"196d81774ef86d1c6c2d028dd5407b47eaa51a99\", \"got_v8_revision\": \"d0da7b4db4d335d91a374ec3be53199c482e7174\", \"got_v8_revision_cp\": \"refs/heads/8.2.56@{#1}\", \"got_webrtc_revision\": \"d4c3c3a454215b21f02d2a772a729b4c87d6b33e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30486}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f4949c487ded2f72f5f7fde6cc08bb333a9208db\", \"swarm_hashes_refs/heads/master(at){#740131}_without_patch\": {\"accessibility_unittests\": \"80bc29ec8efd9257b5114854db9e0d7e8f2c8fb8\", \"angle_unittests\": \"2089540ba9926cefd0ab3dfe877044b3a6163038\", \"app_shell_unittests\": \"d9a335dbd4f8f4c23e3989a38baa8956427d40aa\", \"aura_unittests\": \"c8c3d001e7b85c2f793cf8352bbb09a9005828c7\", \"base_unittests\": \"dd71979f80f08586866a1ccc5aa3716aadea39e5\", \"base_util_unittests\": \"c05025d6be5cb337a3dbd3063e68ed2a1628689d\", \"blink_common_unittests\": \"c1ecf12c321a095c105c4c7702ea97f4db435fe7\", \"blink_fuzzer_unittests\": \"4a7ac605168815e04e3c3ad49a38d271a371d7c9\", \"blink_heap_unittests\": \"5bd3ba7e081154a7289615786fc4a9867aa7288e\", \"blink_platform_unittests\": \"0962a1a7f49d0da070e15a908741f80a501f9b98\", \"blink_python_tests\": \"264ecb7303d7c8ca4bcbd9147a4ffe7707afed8d\", \"blink_unittests\": \"83dd53b448f44611d7a99ae443b8f071abe3a619\", \"blink_web_tests\": \"3cfaceb797e3bc848989a905df6d421e492653fb\", \"boringssl_crypto_tests\": \"95e11b3ad1485ef5ca655ac0b86d8ff3dba78047\", \"boringssl_ssl_tests\": \"8866b6c4ad6d38ade44b9b37bae3ea235aadd8cc\", \"browser_tests\": \"a0cb3d49d5364471f4bf921e7818de6a23dc6723\", \"cacheinvalidation_unittests\": \"1d10521a9370a4731b63a49784b78475af69f943\", \"capture_unittests\": \"86f6d5b1883b0b43b2fb9115efc610e14ac1923c\", \"cast_unittests\": \"e6ab88d91c7af87846acb9aa5bcf6059e56b5fe3\", \"cc_unittests\": \"595480d0065bf9d0c915fa417d6eb6c03d434999\", \"chrome_app_unittests\": \"2cb9473548c5ccee66d506ba95ac0c37c2051460\", \"chromedriver_unittests\": \"353510ad80a05deea81afe75ac3e56bbc0fef0eb\", \"components_browsertests\": \"594fdc09fc61f27ed88cf3fe0a3ae92764771b5a\", \"components_unittests\": \"5ca64cdaf35088e6e5c6038ac536c8649d74c23a\", \"compositor_unittests\": \"ceb676795601e68d2695f3719d1d8363b11c4f82\", \"content_browsertests\": \"d065c20da2ba68fd4662c9542015ea23ffad5ab5\", \"content_shell_crash_test\": \"997c13bd109a2bda3b24509de445983a0f53fabe\", \"content_unittests\": \"1ee63b707a1c3430bbd05e87eb9e1c60781640db\", \"crashpad_tests\": \"023803b06a5c1d9219866baadc639dd403479e66\", \"cronet_tests\": \"7933193c629e4b4d072ada3e3a4590abb0304b32\", \"cronet_unittests\": \"23cbcd019961bf3ada6c7862d318b3737e055b9f\", \"crypto_unittests\": \"d22b6be1d474b603123318a330a4f75492406c0b\", \"dbus_unittests\": \"c6c5b33b5c4c1bd03bc012e23724f7a0b2e043ce\", \"device_unittests\": \"48e12f61ba7e52d5ef52f1f74e42131b550d3915\", \"display_unittests\": \"290caffa0f41e6797fe27256bcd46d6809a8e92e\", \"events_unittests\": \"ba5461448fda23f4ef4ef0490a97bfdfadbf0619\", \"extensions_browsertests\": \"c7894e3902cd0cce5f340debcc24da1ee864a2bd\", \"extensions_unittests\": \"5e0d6152bf388e78a32b46b72a4052c69466b8e0\", \"filesystem_service_unittests\": \"8b362cdc42dead66990593904a9b647d78f4682c\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"2eba334b4a99266435dc1fb2b03b80148343daeb\", \"gfx_unittests\": \"0886355bd4dea3d25d121481345fee117c94d426\", \"gin_unittests\": \"660e6221e90e5b24fdfd4797224b2ccce057d423\", \"google_apis_unittests\": \"b5eebefdb600d84a902aa8ed21ae85863ba9b5cb\", \"gpu_unittests\": \"83bc60f3a28cd72f7da192057784841bf45dca4c\", \"grit_python_unittests\": \"2c197a1bd065e31b00ed1c7636679f90a901aabe\", \"gwp_asan_unittests\": \"6fba2dccc1827b6afc3e6376d168a769b8d93843\", \"headless_browsertests\": \"b0450742c1bd7d7bfa733f97611840ea4cfa99c3\", \"headless_unittests\": \"105584913e2dda47d674202c7c7427cecfe05c4e\", \"interactive_ui_tests\": \"793d6ed9fecb2b2791f9f016db0e959942a7e140\", \"ipc_tests\": \"f3d5a29fe966506f19b2ce45db8aa74b12d03d18\", \"jingle_unittests\": \"143cd694db8ea752ae599a835e438448227dfc2f\", \"latency_unittests\": \"e125ccb0b128b5a214f6ef5270aabebda3ac0018\", \"libjingle_xmpp_unittests\": \"e9a128a14f8c998fe9e82a43f7418dafac36e1a3\", \"media_blink_unittests\": \"5fcb8a84426f2b66ff6d3ed69398167ef72b7970\", \"media_service_unittests\": \"ac2358047f7f1bbc16a65ee4efbf43bbfb9a65e1\", \"media_unittests\": \"129e7376d89d99dee01dc19f4b9ba4abdb69e01f\", \"message_center_unittests\": \"91f259c07d8a2ceb9d41e4a9ec73a071043d0bd9\", \"metrics_python_tests\": \"d50b9f264e0b770773c8d4824d509babdf0e068a\", \"midi_unittests\": \"8e14b45d72fa7b3de17fc06a8b113af6d0328d72\", \"mojo_core_unittests\": \"f88b9a3addfffc8a12d94a8ed58ea0f43f9a433a\", \"mojo_unittests\": \"990cf23cf2fd5a0b7767675e24a82faeeafa00a9\", \"nacl_helper_nonsfi_unittests\": \"2ebd60291f651fd7b65f4c47daa251c4ae4148b0\", \"nacl_loader_unittests\": \"5a6d3055234e064f9d2e150a8f20cffd32219968\", \"native_theme_unittests\": \"86f3370850bbe2585fa7c7c76f74b41758b6eeff\", \"net_unittests\": \"c41696d785b8ec90beb4a9930279d47a649ec7bc\", \"pdf_unittests\": \"1c85116b41d0a37801d888595eb9d881e0e9c89b\", \"perfetto_unittests\": \"3ee544ec1f12955df0e818e3bb6a4ee4e63f4805\", \"ppapi_unittests\": \"61f74b49d42a3ab2fc175d2decdbf0f5c9bd7967\", \"printing_unittests\": \"71fd756b7b9bae2a01606c89826b5573b29d20ef\", \"remoting_unittests\": \"75b1f31512d08e72aeba1ba803dea0ff4dcb6024\", \"sandbox_linux_unittests\": \"e8d4ed764c6c7c4768ad6000eb2e73ddb502bc6a\", \"service_manager_unittests\": \"c3af9d470fd778818d9883140a2558a1959e24d6\", \"services_unittests\": \"1a283d3fa980129753c1fbf572c8608b02902b6d\", \"shell_dialogs_unittests\": \"307e2666c9dc4284d5a644d1e8aed3d588fdc017\", \"skia_unittests\": \"4b25d44e8c1ffe22b2b0d60201c6a2970ed54822\", \"snapshot_unittests\": \"da02bb5296f455f0b3e07c0d23279ef12ef56357\", \"sql_unittests\": \"1d3e703df90a5bbda98999360d9009e9aefeebd2\", \"storage_unittests\": \"5f4a30cbbc5c90dc4806a29b6152502f3ace4944\", \"sync_integration_tests\": \"7eaa1c032c7203aa308925e5618cfca1f60790e7\", \"telemetry_gpu_unittests\": \"3274fcd909b2bfb70d12cf1f6eb3da4cd590c1af\", \"telemetry_perf_unittests\": \"b121b9cb1b9e58afed55099c25978bbfe9235230\", \"telemetry_unittests\": \"e61a3334cddfc7baeaf77a63f78efb39f98b58ab\", \"traffic_annotation_auditor_unittests\": \"e5d92524611892f7593cc41a33435f7b10b6b859\", \"ui_base_unittests\": \"cd003baf2d17ad241c6112a1a87d259d18e7655d\", \"ui_touch_selection_unittests\": \"dc6519cb93312073cc2cd18f9e459e0ac586a2a2\", \"unit_tests\": \"b05f0d9381662680f71c387cd5ec03e08bef6a89\", \"url_unittests\": \"62f67d8b7464581607fdcdb3ddd0ce2741955703\", \"views_perftests\": \"3b98776d0f2fa29f76739cd978344fcd7bd34d19\", \"views_unittests\": \"648d06275751e8ab775631d375346d85fb8bd265\", \"viz_unittests\": \"d3c4cc6931ee24af3982e1e74ce45c3b9532827f\", \"vr_common_unittests\": \"44d44887d05aa9dd6f165637911814ba10d43c59\", \"vr_pixeltests\": \"526840964b5c01dff906cd25ac7217ffab32a6e8\", \"webdriver_wpt_tests\": \"907e708da6cefa45fd17a42dc8927882aed88f59\", \"weblayer_browsertests\": \"ad36526340b65d56cafb6cbce6f1998d7572a399\", \"wm_unittests\": \"91e00c415eca17cc3bab66cf700b73c56aee43e0\", \"wtf_unittests\": \"e341a1c333ad2ff10c44c657438eb769f2914811\", \"xr_browser_tests\": \"525d8c8286cbbf72d7f6fbcc6e59b79a13a5202c\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3d74f5cea4cbf91e4b26f92a522c5c74e61b738440ef29ba16f5c9a596a5b201_v2\", \"builder_45c5b54513fd8d251ddf48af87bd70552077e6f51953669d04eaa4296b96a344_v2\", \"builder_497278b5819f10111ab639898a894ad19d361b24d185228ca986cf767de7a7ef_v2\", \"builder_8851890e994e29b56fba2042f90feba99073c8e56d0ea67af5ee13ebe9f87cf6_v2\", \"builder_9ccb21220795a1d1972030c6b5d9b4569472a28790e1458f6676edb8e4495e8f_v2\", \"builder_a1b9c6892397d61c3624774723419f5ae48fcf776e7d2304bd4682bf5cd4c049_v2\", \"builder_abe2adbd32ad911c3e8d1f384e915329cf1f65e427edb6b91a9fbb61a55ed743_v2\", \"builder_d0ba3cd99c36b697d8651140d51bf4b4f4b9cd9aa25af11363af5ede5f6e35b1_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-96-81mi\"], \"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\": [\"4812-ea7e8fc\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581386865231239",
"status": "COMPLETED",
"status_changed_ts": "1581390289603880",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168468",
"builder:Linux Builder (dbg)",
"buildset:commit/git/f4949c487ded2f72f5f7fde6cc08bb333a9208db",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f4949c487ded2f72f5f7fde6cc08bb333a9208db",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054219801031574992",
"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/8888789737103321664/+/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:4a4acd464c215e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1581390289696195",
"url": "https://ci.chromium.org/b/8888789737103321664",
"utcnow_ts": "1581390290470977"
}
}
[03:04:50.481] Invocation finished in 1h2m27.614029187s with status SUCCEEDED