[01:00:28.969] New invocation is queued and will start shortly
[01:00:30.083] Starting the invocation (attempt 1)
[01:00:30.146] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:00:30.146] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:00:30.146] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9057756977044320416",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5ba46cd5bada9e152b6c2f29c205ee456463285c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9057756977044320416",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/5ba46cd5bada9e152b6c2f29c205ee456463285c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5ba46cd5bada9e152b6c2f29c205ee456463285c",
"gitiles_ref:refs/heads/master"
]
}
[01:00:31.440] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1578013230310273",
"id": "8892326914972235792",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5ba46cd5bada9e152b6c2f29c205ee456463285c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1578013231028179",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166887",
"builder:Linux Builder (dbg)",
"buildset:commit/git/5ba46cd5bada9e152b6c2f29c205ee456463285c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5ba46cd5bada9e152b6c2f29c205ee456463285c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9057756977044320416",
"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/8892326914972235792/+/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": "1578013231028370",
"url": "https://ci.chromium.org/b/8892326914972235792",
"utcnow_ts": "1578013231428433"
}
}
[01:00:31.440] Task URL: https://ci.chromium.org/b/8892326914972235792
[01:00:31.440] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:2:0) after 1m0s
[01:01:31.557] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:2:0)
[01:01:31.557] Timer tick, asking Buildbucket for the build status
[01:01:32.166] Build 8892326914972235792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:32.166] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:3:0) after 1m0s
[01:02:32.301] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:3:0)
[01:02:32.301] Timer tick, asking Buildbucket for the build status
[01:02:32.435] Build 8892326914972235792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:32.435] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:4:0) after 1m0s
[01:03:32.458] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:4:0)
[01:03:32.458] Timer tick, asking Buildbucket for the build status
[01:03:32.606] Build 8892326914972235792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:32.606] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:5:0) after 1m0s
[01:04:32.747] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:5:0)
[01:04:32.747] Timer tick, asking Buildbucket for the build status
[01:04:33.031] Build 8892326914972235792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:33.031] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:6:0) after 1m0s
[01:05:07.210] Received PubSub notification, asking Buildbucket for the build status
[01:05:07.306] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:33.180] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:6:0)
[01:05:33.180] Timer tick, asking Buildbucket for the build status
[01:05:34.173] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:34.173] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:8:0) after 1m0s
[01:06:34.241] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:8:0)
[01:06:34.241] Timer tick, asking Buildbucket for the build status
[01:06:34.410] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:34.410] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:9:0) after 1m0s
[01:07:34.467] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:9:0)
[01:07:34.467] Timer tick, asking Buildbucket for the build status
[01:07:34.780] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:34.780] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:10:0) after 1m0s
[01:08:34.846] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:10:0)
[01:08:34.846] Timer tick, asking Buildbucket for the build status
[01:08:35.866] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:35.866] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:11:0) after 1m0s
[01:09:35.951] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:11:0)
[01:09:35.951] Timer tick, asking Buildbucket for the build status
[01:09:37.016] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:37.016] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:12:0) after 1m0s
[01:10:37.045] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:12:0)
[01:10:37.045] Timer tick, asking Buildbucket for the build status
[01:10:37.825] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:37.825] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:13:0) after 1m0s
[01:11:37.939] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:13:0)
[01:11:37.939] Timer tick, asking Buildbucket for the build status
[01:11:38.211] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:38.211] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:14:0) after 1m0s
[01:12:38.358] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:14:0)
[01:12:38.358] Timer tick, asking Buildbucket for the build status
[01:12:38.909] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:38.909] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:15:0) after 1m0s
[01:13:39.089] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:15:0)
[01:13:39.089] Timer tick, asking Buildbucket for the build status
[01:13:39.687] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:39.687] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:16:0) after 1m0s
[01:14:39.750] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:16:0)
[01:14:39.750] Timer tick, asking Buildbucket for the build status
[01:14:39.922] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:39.922] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:17:0) after 1m0s
[01:15:39.983] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:17:0)
[01:15:39.983] Timer tick, asking Buildbucket for the build status
[01:15:40.172] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:40.172] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:18:0) after 1m0s
[01:16:40.290] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:18:0)
[01:16:40.290] Timer tick, asking Buildbucket for the build status
[01:16:40.547] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:40.547] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:19:0) after 1m0s
[01:17:40.521] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:19:0)
[01:17:40.521] Timer tick, asking Buildbucket for the build status
[01:17:40.725] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:40.725] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:20:0) after 1m0s
[01:18:40.746] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:20:0)
[01:18:40.746] Timer tick, asking Buildbucket for the build status
[01:18:40.919] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:40.919] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:21:0) after 1m0s
[01:19:40.969] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:21:0)
[01:19:40.969] Timer tick, asking Buildbucket for the build status
[01:19:41.815] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:41.816] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:22:0) after 1m0s
[01:20:41.918] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:22:0)
[01:20:41.918] Timer tick, asking Buildbucket for the build status
[01:20:42.171] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:42.171] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:23:0) after 1m0s
[01:21:42.176] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:23:0)
[01:21:42.176] Timer tick, asking Buildbucket for the build status
[01:21:42.385] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:42.385] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:24:0) after 1m0s
[01:22:42.416] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:24:0)
[01:22:42.416] Timer tick, asking Buildbucket for the build status
[01:22:42.608] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:42.608] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:25:0) after 1m0s
[01:23:42.708] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:25:0)
[01:23:42.708] Timer tick, asking Buildbucket for the build status
[01:23:42.990] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:42.990] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:26:0) after 1m0s
[01:24:43.170] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:26:0)
[01:24:43.170] Timer tick, asking Buildbucket for the build status
[01:24:43.422] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:43.422] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:27:0) after 1m0s
[01:25:43.543] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:27:0)
[01:25:43.543] Timer tick, asking Buildbucket for the build status
[01:25:43.692] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:43.692] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:28:0) after 1m0s
[01:26:43.799] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:28:0)
[01:26:43.799] Timer tick, asking Buildbucket for the build status
[01:26:44.162] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:44.162] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:29:0) after 1m0s
[01:27:44.182] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:29:0)
[01:27:44.182] Timer tick, asking Buildbucket for the build status
[01:27:45.137] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:45.137] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:30:0) after 1m0s
[01:28:45.125] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:30:0)
[01:28:45.125] Timer tick, asking Buildbucket for the build status
[01:28:46.283] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:46.284] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:31:0) after 1m0s
[01:29:46.507] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:31:0)
[01:29:46.507] Timer tick, asking Buildbucket for the build status
[01:29:47.227] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:47.227] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:32:0) after 1m0s
[01:30:47.288] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:32:0)
[01:30:47.288] Timer tick, asking Buildbucket for the build status
[01:30:48.283] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:48.283] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:33:0) after 1m0s
[01:31:48.340] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:33:0)
[01:31:48.340] Timer tick, asking Buildbucket for the build status
[01:31:49.010] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:49.010] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:34:0) after 1m0s
[01:32:49.106] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:34:0)
[01:32:49.106] Timer tick, asking Buildbucket for the build status
[01:32:50.342] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:50.342] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:35:0) after 1m0s
[01:33:50.395] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:35:0)
[01:33:50.395] Timer tick, asking Buildbucket for the build status
[01:33:50.630] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:50.630] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:36:0) after 1m0s
[01:34:50.718] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:36:0)
[01:34:50.718] Timer tick, asking Buildbucket for the build status
[01:34:50.958] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:50.958] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:37:0) after 1m0s
[01:35:51.092] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:37:0)
[01:35:51.092] Timer tick, asking Buildbucket for the build status
[01:35:52.154] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:52.154] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:38:0) after 1m0s
[01:36:52.224] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:38:0)
[01:36:52.224] Timer tick, asking Buildbucket for the build status
[01:36:52.483] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:52.483] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:39:0) after 1m0s
[01:37:52.574] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:39:0)
[01:37:52.574] Timer tick, asking Buildbucket for the build status
[01:37:52.721] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:52.721] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:40:0) after 1m0s
[01:38:52.796] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:40:0)
[01:38:52.796] Timer tick, asking Buildbucket for the build status
[01:38:53.376] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:53.376] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:41:0) after 1m0s
[01:39:53.449] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:41:0)
[01:39:53.449] Timer tick, asking Buildbucket for the build status
[01:39:54.107] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:54.107] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:42:0) after 1m0s
[01:40:54.150] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:42:0)
[01:40:54.150] Timer tick, asking Buildbucket for the build status
[01:40:54.447] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:54.447] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:43:0) after 1m0s
[01:41:54.468] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:43:0)
[01:41:54.468] Timer tick, asking Buildbucket for the build status
[01:41:54.599] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:54.599] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:44:0) after 1m0s
[01:42:54.644] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:44:0)
[01:42:54.644] Timer tick, asking Buildbucket for the build status
[01:42:54.783] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:54.783] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:45:0) after 1m0s
[01:43:54.780] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:45:0)
[01:43:54.780] Timer tick, asking Buildbucket for the build status
[01:43:55.442] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:55.442] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:46:0) after 1m0s
[01:44:55.462] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:46:0)
[01:44:55.462] Timer tick, asking Buildbucket for the build status
[01:44:55.985] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:55.985] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:47:0) after 1m0s
[01:45:56.007] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:47:0)
[01:45:56.007] Timer tick, asking Buildbucket for the build status
[01:45:56.167] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:56.167] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:48:0) after 1m0s
[01:46:56.221] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:48:0)
[01:46:56.221] Timer tick, asking Buildbucket for the build status
[01:46:56.378] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:56.378] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:49:0) after 1m0s
[01:47:56.358] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:49:0)
[01:47:56.358] Timer tick, asking Buildbucket for the build status
[01:47:56.735] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:56.735] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:50:0) after 1m0s
[01:48:56.762] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:50:0)
[01:48:56.762] Timer tick, asking Buildbucket for the build status
[01:48:56.919] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:56.919] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:51:0) after 1m0s
[01:49:56.984] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:51:0)
[01:49:56.984] Timer tick, asking Buildbucket for the build status
[01:49:57.993] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:57.993] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:52:0) after 1m0s
[01:50:58.019] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:52:0)
[01:50:58.019] Timer tick, asking Buildbucket for the build status
[01:50:58.633] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:58.633] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:53:0) after 1m0s
[01:51:58.650] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:53:0)
[01:51:58.650] Timer tick, asking Buildbucket for the build status
[01:51:58.879] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:58.879] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:54:0) after 1m0s
[01:52:58.963] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:54:0)
[01:52:58.963] Timer tick, asking Buildbucket for the build status
[01:52:59.487] Build 8892326914972235792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:59.487] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057756977044320416:55:0) after 1m0s
[01:53:55.753] Received PubSub notification, asking Buildbucket for the build status
[01:53:55.874] Build 8892326914972235792: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:53:55.874] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1578016435062912",
"created_by": "project:chromium",
"created_ts": "1578013230310273",
"id": "8892326914972235792",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5ba46cd5bada9e152b6c2f29c205ee456463285c\"}}",
"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-18-bx8f\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 166887, \"got_angle_revision\": \"1fdf6ca5141d8e349e875eab6e51d93d929a7f0e\", \"got_dawn_revision\": \"87d31381588b26edb3da1d75dd0588b2df4dd968\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"5ba46cd5bada9e152b6c2f29c205ee456463285c\", \"got_revision_cp\": \"refs/heads/master@{#728096}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"10a900e5ffaffdffe2806b1507af43a74acdfe9e\", \"got_v8_revision\": \"6c428fce79e4a570f1316353702ddb4541c2793c\", \"got_v8_revision_cp\": \"refs/heads/8.1.88@{#1}\", \"got_webrtc_revision\": \"a43777dead46df4575a58c5ca2733c5f3ff0212b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30130}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5ba46cd5bada9e152b6c2f29c205ee456463285c\", \"swarm_hashes_refs/heads/master(at){#728096}_without_patch\": {\"accessibility_unittests\": \"09dda13fd21580f674cd2006c78437bb30664de4\", \"angle_unittests\": \"cb0f36925004ffc1a07465cb14e5299a028e5913\", \"app_shell_unittests\": \"a0ef2bb98fc06609b7b5947525a519705970decd\", \"aura_unittests\": \"475e0220dde40365a6cde2964dd2a22d7899d308\", \"base_unittests\": \"a69233ff0953bfa77fdc33024a02255ae37bc488\", \"base_util_unittests\": \"972b27d73ccc127bf2fc93cca7231df124d17d65\", \"blink_common_unittests\": \"dbb09b292170f64bfcdc0bd626c86677cc4e74f4\", \"blink_fuzzer_unittests\": \"75f775397b39fd3d9b574a736b060a2f1270e046\", \"blink_heap_unittests\": \"d8b03b6b9d8cc152bd283af904b4a88cdc494b1b\", \"blink_platform_unittests\": \"d9fc632d5dc8b34919f34ee11708d71761b6f18a\", \"blink_python_tests\": \"763fc913ca4b3586cad8eb1d84d6bde8de0cabb6\", \"blink_unittests\": \"8b1b246843239f09e881ac83663aa0c9114a7773\", \"blink_web_tests\": \"d48b055e4c6520931bac4cda79f816b8747d784f\", \"boringssl_crypto_tests\": \"c3488172450e852f5a79754d607a356aa57153a2\", \"boringssl_ssl_tests\": \"f4ecf3223a2fd8db83bd59d6307a7974c8050521\", \"browser_tests\": \"6340a2db308808b9a7dcc937d6cc504a2054ef25\", \"cacheinvalidation_unittests\": \"f107176ff902ff677b7b1e24e61803a33cd322b8\", \"capture_unittests\": \"b45165d11ee0d2cd3022dde1129395a2b962e3fe\", \"cast_unittests\": \"7728f935edd4210857396e33c6491309dfb65979\", \"cc_unittests\": \"48eeca55ef7428e929a0702c69b32bc532e58714\", \"chrome_app_unittests\": \"9fbfe8d3a33bfdbdf329fa1f71715aa855480556\", \"chromedriver_unittests\": \"cbe3ca9425ed2fecaf50249daf56a47dbfead622\", \"components_browsertests\": \"762d41a7f732a9a2470bb391742230883cb7c0ad\", \"components_unittests\": \"74a381209be2cd0bd83ae0386930865e7b446251\", \"compositor_unittests\": \"121451bac42f96768d8da5d9e33cf60460fbc4fc\", \"content_browsertests\": \"f1ffabd4b469397997994891795659fe451173d3\", \"content_shell_crash_test\": \"22608729b16a52fb8d700a3a95ce2da38e4f3f1a\", \"content_unittests\": \"7170f1164c0e3b47516e718b7d6083e425285dec\", \"crashpad_tests\": \"5ff4ebbdce3cf3c07a98baccebfccb30395e1e75\", \"cronet_tests\": \"60a44b0775e29fdb0c8161ec5a9a8958d4b3a45f\", \"cronet_unittests\": \"e7a898e61f38cfab2d50a644397401ca24c884e6\", \"crypto_unittests\": \"202ecea992006442f6a0b5006d6acd69b3d0228c\", \"dbus_unittests\": \"77d972ce86ab934366fe47af17ef254d185bd384\", \"device_unittests\": \"46f1817c6cecb8ea15fca690beaf87b74115a890\", \"devtools_lint_check\": \"a417660dc3d53b9917c063d17eab36c95ce0bb78\", \"devtools_type_check\": \"9641eb45630d56b910356e59942262518da1ed46\", \"display_unittests\": \"5a5b8412fe4d702f6698e5da81184f9decbf3b08\", \"events_unittests\": \"2634798549d806f665064234e9a350657d2f76b3\", \"extensions_browsertests\": \"98cf0826f8fa31dc7cfe219c22db92c91e7f2691\", \"extensions_unittests\": \"1ba1f4b0d227384d86b8c4627eb982ef2e256e73\", \"filesystem_service_unittests\": \"c0624b239db8a83ec0af5efbbeaef2d85682fe18\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"aad2f966280c1e7db0c37975b1779019ae809be2\", \"gfx_unittests\": \"ff2ffa580f0bef4f7c2a8cdf5335843db57e910c\", \"gin_unittests\": \"7f032dde47913bc0671f4428b3a086f06f3d3aa9\", \"google_apis_unittests\": \"5981b42a06f803f55c05d77889b09e914fe7d2b6\", \"gpu_unittests\": \"05d923819c1be64bf32ba36ac574eb0fcf12e5b5\", \"grit_python_unittests\": \"1da91f6b17b8cbf82d765a1785ee42636bd93959\", \"gwp_asan_unittests\": \"050bc714abb114022e32adcc32b0fee7ef4da622\", \"headless_browsertests\": \"8270dc72bb706c37be863471a40793fb10dcd792\", \"headless_unittests\": \"e124e706242b7badca1ee898735ce05c562bfd45\", \"interactive_ui_tests\": \"80e93407b3a9419c3ff82dcc223c9882129c2fdf\", \"ipc_tests\": \"d20faf69eb1cf5ec0c332cef4754e55145c8a0b1\", \"jingle_unittests\": \"e37c20a4b819b55b247f1b054bf8d3d55a485799\", \"latency_unittests\": \"76c9e204a8c8f33caab5326be39b9a65aa8666e6\", \"libjingle_xmpp_unittests\": \"47565f19b57c2936961e48221b2e56e862c28c45\", \"media_blink_unittests\": \"382c43ef019b0873203eb5a02806d59be4c584a6\", \"media_service_unittests\": \"a4116a3d40def63aa2114e073868c27269a1867b\", \"media_unittests\": \"d6cd10e4534b40f469d2970c7363c533f2b0f76b\", \"message_center_unittests\": \"f06d5e355711c4322ee6e05eb2e2c1113c607d27\", \"metrics_python_tests\": \"541fdba0073f220115c1843accb416118509bd89\", \"midi_unittests\": \"8819bc3b34495da4bd2c1876448947e5e67cee94\", \"mojo_core_unittests\": \"88142d958bc715fceb5ea3215a612d3580239cc8\", \"mojo_unittests\": \"54d912f38af5beb65ac57011c4ecf4b5a6f3148e\", \"nacl_helper_nonsfi_unittests\": \"0b857dcb70c58be886f0ce34596911acceacf3d4\", \"nacl_loader_unittests\": \"c0d77d9d4b1550f80f5f12a77f608675977d1782\", \"native_theme_unittests\": \"15102d40bd562ad5ced6ca761d570c1d4e0dcffc\", \"net_unittests\": \"5699a2a15f7fa2492dc0b8b983c6e57676798cd6\", \"pdf_unittests\": \"ecb7ebf823348a9ea7c7fec4c947e8906fb06ff2\", \"perfetto_unittests\": \"5751a7f4d22dffdc08010b3158e541e29814d851\", \"ppapi_unittests\": \"95c7640b780597d28fd81a359fa9327ff134c4d0\", \"printing_unittests\": \"40f2216b1cae9b3632e174f6342c727517aa5ace\", \"remoting_unittests\": \"227106379e322a7ae0f8a56e9035d11780434250\", \"sandbox_linux_unittests\": \"7a30962cc3849420a2aa05b5299fc2b94d226a00\", \"service_manager_unittests\": \"8062f65078c467036a249c7338a3c06b9af2554d\", \"services_unittests\": \"783eeb06bce4de8d32b62fd67b9d4f878bb5719e\", \"shell_dialogs_unittests\": \"12c7f254b03be35cfac3476a734f4a09e8076056\", \"skia_unittests\": \"72cfb32c68d1275704ae879e2507fd19d3289428\", \"snapshot_unittests\": \"7723781cb0dc47f2b331d6c80f5a7af20d069e0f\", \"sql_unittests\": \"dc36d5760a3ebb8f109935dc07d991d838d1b786\", \"storage_unittests\": \"b349ea829591536d65b1e6518ba4fcede3722f3d\", \"sync_integration_tests\": \"a6cb5173766da8fe6ed6bb4431a7e79fd63cfe24\", \"telemetry_gpu_unittests\": \"c122dc3c67a1dc1b08d3c41160258d65b468b806\", \"telemetry_perf_unittests\": \"eb772e76f08d427cc5d17e334077ec1afcbf96eb\", \"telemetry_unittests\": \"6d85fedd0c6b13be4ac7cfbeee2acc98f7307b2d\", \"traffic_annotation_auditor_unittests\": \"5faab5c857cb48ffb77b898fee684400f087263d\", \"ui_base_unittests\": \"c78a981b105d500e46c353572156d81d239c1fbb\", \"ui_touch_selection_unittests\": \"1b3002e9bf87439a18874892ec0d8ed1a3a58b96\", \"unit_tests\": \"c30df395fc742bfa1f02f66d4c54cb8a7b4ead52\", \"url_unittests\": \"944d3f1947fed89aceadb9f96c762a61861b82f0\", \"views_perftests\": \"2b215724ba1aa60ad98985c87da8020ac2102cfa\", \"views_unittests\": \"3455b2dc33ab52f998bf84be3879278c8bf6a90c\", \"viz_unittests\": \"5ac4074c5a010bafc033f421d725a3cef1cc9faa\", \"vr_common_unittests\": \"df12f754574bb22a528f2c54a1d4a8e350cf43d7\", \"vr_pixeltests\": \"c8b2b6bd0b2670db5d7658b2c2be3327ded2fa0c\", \"webdriver_wpt_tests\": \"89cf5c77e3f8e263642ffe252083e54ff1c562e6\", \"weblayer_browsertests\": \"c3e9df4a8af6503bb6ff619c95b1b3c2b8ff3949\", \"wm_unittests\": \"12b7bf197723ff193a6e5cf0b2a02c071dcd2a7e\", \"wtf_unittests\": \"f4213b26c06ce4b1e06d82d10468921cc29f8c03\", \"xr_browser_tests\": \"06ad337ace95223a38e80a28033e430c755a8415\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_b224075beee246a5ffe9706a23163fae567137407ac5f1b69c50b4dcd22edd9b_v2\", \"builder_d2010e0a6a440fb89731b061caf7d596c92723d209ffefd62489cc037db4f7a7_v2\", \"builder_f5174a4d80cdc4c8aa1989fa45d69979d28910f768af140dd646791c34143bfe_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-18-bx8f\"], \"image\": [\"chrome-xenial-19121500-b03549ec744\"], \"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\": [\"4733-6bd8c41\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1578013506154100",
"status": "COMPLETED",
"status_changed_ts": "1578016435379955",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166887",
"builder:Linux Builder (dbg)",
"buildset:commit/git/5ba46cd5bada9e152b6c2f29c205ee456463285c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5ba46cd5bada9e152b6c2f29c205ee456463285c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9057756977044320416",
"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/8892326914972235792/+/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:4981bc92016cb810",
"user_agent:luci-scheduler"
],
"updated_ts": "1578016435403456",
"url": "https://ci.chromium.org/b/8892326914972235792",
"utcnow_ts": "1578016435863338"
}
}
[01:53:55.875] Invocation finished in 53m26.923192523s with status SUCCEEDED