[08:56:53.521] New invocation is queued and will start shortly
[08:56:55.407] Starting the invocation (attempt 1)
[08:56:55.535] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:56:55.535] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:56:55.535] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9055190288908557872",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"64ab230d19f4338c6dac04be8c768b9b6fa91ef4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9055190288908557872",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/64ab230d19f4338c6dac04be8c768b9b6fa91ef4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/64ab230d19f4338c6dac04be8c768b9b6fa91ef4",
"gitiles_ref:refs/heads/master"
]
}
[08:56:56.250] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1580461015678116",
"id": "8889760225982598272",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"64ab230d19f4338c6dac04be8c768b9b6fa91ef4\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1580461016105140",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168071",
"builder:Linux Builder (dbg)",
"buildset:commit/git/64ab230d19f4338c6dac04be8c768b9b6fa91ef4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/64ab230d19f4338c6dac04be8c768b9b6fa91ef4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055190288908557872",
"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/8889760225982598272/+/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": "1580461016105318",
"url": "https://ci.chromium.org/b/8889760225982598272",
"utcnow_ts": "1580461016243030"
}
}
[08:56:56.250] Task URL: https://ci.chromium.org/b/8889760225982598272
[08:56:56.250] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:2:0) after 1m0s
[08:57:56.458] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:2:0)
[08:57:56.458] Timer tick, asking Buildbucket for the build status
[08:57:57.312] Build 8889760225982598272: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:57:57.312] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:3:0) after 1m0s
[08:58:57.393] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:3:0)
[08:58:57.393] Timer tick, asking Buildbucket for the build status
[08:58:57.616] Build 8889760225982598272: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:58:57.616] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:4:0) after 1m0s
[08:59:57.617] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:4:0)
[08:59:57.617] Timer tick, asking Buildbucket for the build status
[08:59:57.776] Build 8889760225982598272: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:59:57.776] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:5:0) after 1m0s
[09:00:57.829] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:5:0)
[09:00:57.829] Timer tick, asking Buildbucket for the build status
[09:01:00.142] Build 8889760225982598272: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:01:00.142] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:6:0) after 1m0s
[09:01:19.886] Received PubSub notification, asking Buildbucket for the build status
[09:01:20.389] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:00.371] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:6:0)
[09:02:00.371] Timer tick, asking Buildbucket for the build status
[09:02:00.763] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:00.763] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:8:0) after 1m0s
[09:03:00.914] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:8:0)
[09:03:00.914] Timer tick, asking Buildbucket for the build status
[09:03:01.375] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:01.375] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:9:0) after 1m0s
[09:04:01.398] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:9:0)
[09:04:01.398] Timer tick, asking Buildbucket for the build status
[09:04:01.958] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:01.958] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:10:0) after 1m0s
[09:05:02.066] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:10:0)
[09:05:02.066] Timer tick, asking Buildbucket for the build status
[09:05:02.637] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:02.637] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:11:0) after 1m0s
[09:06:02.759] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:11:0)
[09:06:02.759] Timer tick, asking Buildbucket for the build status
[09:06:03.093] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:03.093] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:12:0) after 1m0s
[09:07:03.254] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:12:0)
[09:07:03.254] Timer tick, asking Buildbucket for the build status
[09:07:03.451] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:03.451] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:13:0) after 1m0s
[09:08:03.568] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:13:0)
[09:08:03.568] Timer tick, asking Buildbucket for the build status
[09:08:03.883] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:03.883] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:14:0) after 1m0s
[09:09:03.977] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:14:0)
[09:09:03.977] Timer tick, asking Buildbucket for the build status
[09:09:04.286] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:04.286] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:15:0) after 1m0s
[09:10:04.445] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:15:0)
[09:10:04.445] Timer tick, asking Buildbucket for the build status
[09:10:05.136] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:05.136] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:16:0) after 1m0s
[09:11:05.184] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:16:0)
[09:11:05.184] Timer tick, asking Buildbucket for the build status
[09:11:05.325] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:05.325] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:17:0) after 1m0s
[09:12:05.453] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:17:0)
[09:12:05.453] Timer tick, asking Buildbucket for the build status
[09:12:05.636] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:05.636] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:18:0) after 1m0s
[09:13:05.673] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:18:0)
[09:13:05.673] Timer tick, asking Buildbucket for the build status
[09:13:05.818] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:05.818] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:19:0) after 1m0s
[09:14:05.854] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:19:0)
[09:14:05.854] Timer tick, asking Buildbucket for the build status
[09:14:06.033] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:06.033] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:20:0) after 1m0s
[09:15:06.082] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:20:0)
[09:15:06.082] Timer tick, asking Buildbucket for the build status
[09:15:06.659] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:06.659] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:21:0) after 1m0s
[09:16:06.738] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:21:0)
[09:16:06.738] Timer tick, asking Buildbucket for the build status
[09:16:06.994] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:06.994] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:22:0) after 1m0s
[09:17:07.033] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:22:0)
[09:17:07.033] Timer tick, asking Buildbucket for the build status
[09:17:07.914] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:07.914] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:23:0) after 1m0s
[09:18:07.964] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:23:0)
[09:18:07.964] Timer tick, asking Buildbucket for the build status
[09:18:08.236] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:08.236] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:24:0) after 1m0s
[09:19:08.257] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:24:0)
[09:19:08.257] Timer tick, asking Buildbucket for the build status
[09:19:08.638] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:08.638] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:25:0) after 1m0s
[09:20:08.774] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:25:0)
[09:20:08.774] Timer tick, asking Buildbucket for the build status
[09:20:11.840] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:11.840] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:26:0) after 1m0s
[09:21:11.920] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:26:0)
[09:21:11.920] Timer tick, asking Buildbucket for the build status
[09:21:12.229] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:12.229] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:27:0) after 1m0s
[09:22:12.255] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:27:0)
[09:22:12.255] Timer tick, asking Buildbucket for the build status
[09:22:12.863] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:12.863] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:28:0) after 1m0s
[09:23:12.896] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:28:0)
[09:23:12.896] Timer tick, asking Buildbucket for the build status
[09:23:13.355] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:13.355] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:29:0) after 1m0s
[09:24:13.412] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:29:0)
[09:24:13.412] Timer tick, asking Buildbucket for the build status
[09:24:13.572] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:13.572] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:30:0) after 1m0s
[09:25:13.817] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:30:0)
[09:25:13.817] Timer tick, asking Buildbucket for the build status
[09:25:14.069] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:14.069] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:31:0) after 1m0s
[09:26:14.098] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:31:0)
[09:26:14.098] Timer tick, asking Buildbucket for the build status
[09:26:14.308] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:14.308] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:32:0) after 1m0s
[09:27:14.358] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:32:0)
[09:27:14.358] Timer tick, asking Buildbucket for the build status
[09:27:15.164] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:15.164] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:33:0) after 1m0s
[09:28:15.216] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:33:0)
[09:28:15.216] Timer tick, asking Buildbucket for the build status
[09:28:15.404] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:15.404] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:34:0) after 1m0s
[09:29:15.475] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:34:0)
[09:29:15.475] Timer tick, asking Buildbucket for the build status
[09:29:15.662] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:15.662] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:35:0) after 1m0s
[09:30:15.720] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:35:0)
[09:30:15.720] Timer tick, asking Buildbucket for the build status
[09:30:16.526] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:16.526] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:36:0) after 1m0s
[09:31:16.545] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:36:0)
[09:31:16.545] Timer tick, asking Buildbucket for the build status
[09:31:16.908] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:16.908] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:37:0) after 1m0s
[09:32:16.966] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:37:0)
[09:32:16.966] Timer tick, asking Buildbucket for the build status
[09:32:17.188] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:17.188] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:38:0) after 1m0s
[09:33:17.302] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:38:0)
[09:33:17.302] Timer tick, asking Buildbucket for the build status
[09:33:18.099] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:18.099] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:39:0) after 1m0s
[09:34:18.100] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:39:0)
[09:34:18.100] Timer tick, asking Buildbucket for the build status
[09:34:19.685] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:19.685] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:40:0) after 1m0s
[09:35:19.830] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:40:0)
[09:35:19.830] Timer tick, asking Buildbucket for the build status
[09:35:20.056] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:20.056] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:41:0) after 1m0s
[09:36:20.119] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:41:0)
[09:36:20.119] Timer tick, asking Buildbucket for the build status
[09:36:20.339] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:20.339] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:42:0) after 1m0s
[09:37:20.407] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:42:0)
[09:37:20.407] Timer tick, asking Buildbucket for the build status
[09:37:20.847] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:20.847] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:43:0) after 1m0s
[09:38:20.969] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:43:0)
[09:38:20.969] Timer tick, asking Buildbucket for the build status
[09:38:21.145] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:21.145] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:44:0) after 1m0s
[09:39:21.179] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:44:0)
[09:39:21.179] Timer tick, asking Buildbucket for the build status
[09:39:21.955] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:21.955] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:45:0) after 1m0s
[09:40:22.092] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:45:0)
[09:40:22.092] Timer tick, asking Buildbucket for the build status
[09:40:22.746] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:22.746] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:46:0) after 1m0s
[09:41:22.917] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:46:0)
[09:41:22.917] Timer tick, asking Buildbucket for the build status
[09:41:23.301] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:23.301] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:47:0) after 1m0s
[09:42:23.298] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:47:0)
[09:42:23.298] Timer tick, asking Buildbucket for the build status
[09:42:23.882] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:23.882] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:48:0) after 1m0s
[09:43:24.095] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:48:0)
[09:43:24.095] Timer tick, asking Buildbucket for the build status
[09:43:24.676] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:24.676] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:49:0) after 1m0s
[09:44:24.794] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:49:0)
[09:44:24.794] Timer tick, asking Buildbucket for the build status
[09:44:26.686] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:26.686] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:50:0) after 1m0s
[09:45:26.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:50:0)
[09:45:26.933] Timer tick, asking Buildbucket for the build status
[09:45:27.079] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:27.079] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:51:0) after 1m0s
[09:46:27.141] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:51:0)
[09:46:27.141] Timer tick, asking Buildbucket for the build status
[09:46:27.706] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:27.706] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:52:0) after 1m0s
[09:47:27.743] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:52:0)
[09:47:27.743] Timer tick, asking Buildbucket for the build status
[09:47:28.478] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:28.478] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:53:0) after 1m0s
[09:48:28.675] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:53:0)
[09:48:28.675] Timer tick, asking Buildbucket for the build status
[09:48:29.201] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:29.201] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:54:0) after 1m0s
[09:49:29.327] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:54:0)
[09:49:29.327] Timer tick, asking Buildbucket for the build status
[09:49:29.545] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:29.545] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:55:0) after 1m0s
[09:50:29.602] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:55:0)
[09:50:29.602] Timer tick, asking Buildbucket for the build status
[09:50:30.459] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:30.459] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:56:0) after 1m0s
[09:51:30.516] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:56:0)
[09:51:30.516] Timer tick, asking Buildbucket for the build status
[09:51:30.846] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:30.846] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:57:0) after 1m0s
[09:52:30.943] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:57:0)
[09:52:30.943] Timer tick, asking Buildbucket for the build status
[09:52:31.148] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:31.148] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:58:0) after 1m0s
[09:53:31.184] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:58:0)
[09:53:31.184] Timer tick, asking Buildbucket for the build status
[09:53:31.853] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:31.853] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:59:0) after 1m0s
[09:54:31.994] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:59:0)
[09:54:31.994] Timer tick, asking Buildbucket for the build status
[09:54:32.184] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:32.184] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:60:0) after 1m0s
[09:55:32.323] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:60:0)
[09:55:32.323] Timer tick, asking Buildbucket for the build status
[09:55:32.567] Build 8889760225982598272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:32.567] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055190288908557872:61:0) after 1m0s
[09:55:34.263] Received PubSub notification, asking Buildbucket for the build status
[09:55:34.424] Build 8889760225982598272: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:55:34.424] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1580464533587974",
"created_by": "project:chromium",
"created_ts": "1580461015678116",
"id": "8889760225982598272",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"64ab230d19f4338c6dac04be8c768b9b6fa91ef4\"}}",
"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-99-w4dd\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168071, \"got_angle_revision\": \"088b1baa948a27c487ee247e11d29ce3c2e04656\", \"got_dawn_revision\": \"f44a809f9a3b6506df5cc1105198ed136c6b26ec\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"64ab230d19f4338c6dac04be8c768b9b6fa91ef4\", \"got_revision_cp\": \"refs/heads/master@{#737251}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"79b36b640692e3b4561642c06ec36a9e02a1636c\", \"got_v8_revision\": \"a4dcd39d521d14c4b1cac020812e44ee04a7f244\", \"got_v8_revision_cp\": \"refs/heads/8.1.307@{#1}\", \"got_webrtc_revision\": \"ec47b57f1440c1e8566546466dd8d22231d39755\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30433}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"64ab230d19f4338c6dac04be8c768b9b6fa91ef4\", \"swarm_hashes_refs/heads/master(at){#737251}_without_patch\": {\"accessibility_unittests\": \"a36ad3bccf9337d01e12d11d78d3f5b646e3d28c\", \"angle_unittests\": \"45fe035fbbf107ab93c219e872ca96fc3c75b1b4\", \"app_shell_unittests\": \"70dad4750804c74373c44bb9b99e63925d2cbeff\", \"aura_unittests\": \"9f72071fca4211e0be46ba0bfcf857b590bdd62f\", \"base_unittests\": \"7e4afd3205219e7297d41cfa4ecc2d821039134a\", \"base_util_unittests\": \"9df72150f520e47a065e34ffbbd95547d3e78aca\", \"blink_common_unittests\": \"64f67ab49811496369df7cc46eb81543108b5a35\", \"blink_fuzzer_unittests\": \"e2fca2f3a801c98fcb51a969bb0321a9e7ba74f7\", \"blink_heap_unittests\": \"85c5ac6684e714ba1c64deda7e4f579192e84cb2\", \"blink_platform_unittests\": \"6dffa65cab1ba58c1ec59a3c769170d152c018fd\", \"blink_python_tests\": \"ddc664c6102fa55cae6fd527d4a9dc33478702c9\", \"blink_unittests\": \"9606b2ef53b5068ca657fe37c77417785b227d10\", \"blink_web_tests\": \"c18d375242d0e8afaaea797dcfa33ffbbdae1b2f\", \"boringssl_crypto_tests\": \"bd108cbf56997e0091bac875a9e008b99fc33987\", \"boringssl_ssl_tests\": \"5a90601a93ef567a1413055e26bf68959ad98b3c\", \"browser_tests\": \"7fce7fffdae9dc22142032b16aea3956ded20fc6\", \"cacheinvalidation_unittests\": \"30510a4e082b91caa83f9ec66c076c8b47370c7c\", \"capture_unittests\": \"6a94ba28394c64efa40e3250a1ce2c0127a7ffd7\", \"cast_unittests\": \"b4d851324cffb5dd7355ff05407020f8016521d0\", \"cc_unittests\": \"3f99474f8a7f9847b5c087bcfaf69e7b40d664a1\", \"chrome_app_unittests\": \"b37bd4605caf63e33f4096bf645a8f4c30f3f692\", \"chromedriver_unittests\": \"c2bcba7cea32b88864ab1083d18778c1a424eacd\", \"components_browsertests\": \"519a464b52069d5b0b6e03949d60d22eb9ba66f6\", \"components_unittests\": \"d3f76e3bb3774f2dc0845ab4588021ea7d343538\", \"compositor_unittests\": \"1f84ecf1b85de5c9bf84a06bd4d69e6aa5fcfa03\", \"content_browsertests\": \"dbd2f506d805b1f5a222e3d3043fc2d057f4e962\", \"content_shell_crash_test\": \"454c067872f0ed58ee0f7bf861cc4428d454bdb5\", \"content_unittests\": \"c8fed590c91fe72ecc8a26043ea0188e8c03a263\", \"crashpad_tests\": \"caf6e253e1ca9134ed839ea29b532506a07abaed\", \"cronet_tests\": \"21ee15e8679a12094806431c180a3d1f604b7965\", \"cronet_unittests\": \"8038c796e7e6a7e6e50e8547b9a8ea0a1b176f26\", \"crypto_unittests\": \"6c9f7b02eb547862dd4750cb9c28ee4cd87cee24\", \"dbus_unittests\": \"5e1980c7a68ac27f35060eb5f54592b68c1fd199\", \"device_unittests\": \"ceceb79b8041c8e201d17b624afc4c0e33daa949\", \"display_unittests\": \"9514c439bd8e2df1024b00728590bdbe7320c4b0\", \"events_unittests\": \"47372890aa32b3ae24754105dcc5245e89d0ef10\", \"extensions_browsertests\": \"dc611204158dbe66e368a4e48935a64c8f5f7466\", \"extensions_unittests\": \"773ad216e8a55ad07c37012420063b467de4af3f\", \"filesystem_service_unittests\": \"11de83ff39a097be684961758948f07c80aecf68\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"5def09490fc65364a4a446d2ea6e1e202b28c3bc\", \"gfx_unittests\": \"b2aee9fab3827422a428ee694a482b3dea809266\", \"gin_unittests\": \"6e3e1792ca0682a62d06326b42160d8c95022437\", \"google_apis_unittests\": \"4af5bcc868276cab499a1bf17d7231c0c3345015\", \"gpu_unittests\": \"98f8a5a37a152f7a9acfd56a6db573ee6d0677bc\", \"grit_python_unittests\": \"a906de1211aa1e6d0b6deb8a9a162d6998fb6336\", \"gwp_asan_unittests\": \"9c61eafa7efd31c23606691a5cfe5a4ba29bfcfd\", \"headless_browsertests\": \"a90ff9154b4849f035439c1e802e11f873b0cd6c\", \"headless_unittests\": \"00df51938f77b705ad07114e1af3312aa593bc90\", \"interactive_ui_tests\": \"9da73cf194e9633793f541d3fed677e2bec90446\", \"ipc_tests\": \"a54afb99283620b9d7e439f8c477fbb77e1b06df\", \"jingle_unittests\": \"9a7f9040aa87586642f6582ee441d5aefd605341\", \"latency_unittests\": \"3c574c6fe2f3ebdffd6bb6a1731d7c29f29cfcab\", \"libjingle_xmpp_unittests\": \"49585a8a7efdbcdb92bfd582de674d9a27f31af4\", \"media_blink_unittests\": \"45aae2feeb3e74a3f11bfdeefd6bd8019e205df4\", \"media_service_unittests\": \"ce02affb9c65614189cf839c3850b39497b7ca00\", \"media_unittests\": \"942eea65abf2defd5a200619d800e86d2961cd52\", \"message_center_unittests\": \"164f35fbcfb41c82d3ebc161796d84206a56ec5f\", \"metrics_python_tests\": \"aac0f6df66da5aefe828b41623331e220de1aeef\", \"midi_unittests\": \"141fa196443ca39238393e94f19d85367983b4d4\", \"mojo_core_unittests\": \"33061139d2472f1aaed98106dc39ec5c82dbdc4b\", \"mojo_unittests\": \"78b74a7e7f9e7646b902b5f999a986a7867dde63\", \"nacl_helper_nonsfi_unittests\": \"b38cf9a1686b6244e7c9103410b90dd9cea96356\", \"nacl_loader_unittests\": \"5803c368f936b4c51730dc7c13b444ea4b00f53e\", \"native_theme_unittests\": \"8e9b2bc01874aa6e3aaa09ba6833e5b4cb9f2758\", \"net_unittests\": \"55f70611e1e983f9ce47861dbb5e73c8b5adef99\", \"pdf_unittests\": \"24028ff58bae5b99e7154219ba9877e9de5e717f\", \"perfetto_unittests\": \"5a61ee5762056e3e7d1357afc493a8b09376a4a9\", \"ppapi_unittests\": \"9b168a48192611f6fc94a992f04d4d3c736f715c\", \"printing_unittests\": \"a2adc7cfdc196f95e9757037a19afb3612cc812f\", \"remoting_unittests\": \"dcbcecaf4241f6c43c520bbfcc60b58757173571\", \"sandbox_linux_unittests\": \"af75a11607e896bc93f7cc4489c7f0f7da01f839\", \"service_manager_unittests\": \"dd3cf8abc90320d8b916ece53af327fc77ff7da6\", \"services_unittests\": \"10da3231bd66b55ff03cc0bae04b576663e781f2\", \"shell_dialogs_unittests\": \"2a986855872cb4d8c20a9c8d54ed5c085b3d03a6\", \"skia_unittests\": \"36f7248732c8f38d4712ba386fd8f656c73ac29a\", \"snapshot_unittests\": \"674fb0be49822ecacbe785b825c163ac01c2dd4b\", \"sql_unittests\": \"f9ddcdee568d40e96d49027b79fad7f9c5be09d2\", \"storage_unittests\": \"726253f02c0df4c20b3c4f2bac3a913dc145ed03\", \"sync_integration_tests\": \"54acbf00e71d407d3c1839f04efc3a7a1d24f629\", \"telemetry_gpu_unittests\": \"b196d5cea619fc21d292d9c04e6c2e488efda852\", \"telemetry_perf_unittests\": \"f7c7c46de26fc142e4079590a1b59a5d58ed55c3\", \"telemetry_unittests\": \"147c2fa86f80b911defa0f59209ef49649382ec8\", \"traffic_annotation_auditor_unittests\": \"906babba50a42f89c6c8b8e8944a9197ca7a3782\", \"ui_base_unittests\": \"ea03c4a6f1ba3c3c7a5fa79c52059ad806f819d2\", \"ui_touch_selection_unittests\": \"d67c270d4254bf54ddd8c1246bd764b9d4055472\", \"unit_tests\": \"dbf625e56f3c3cb068061dbe0c61569b9372698a\", \"url_unittests\": \"323938815a8a38d869b9731bf3968ee2abf23d8a\", \"views_perftests\": \"e97c8d9f1bb7bca4a5797c225f00f9e7fefb6253\", \"views_unittests\": \"c2e90a59cc95dc742a146a081c6f3999d6f91a68\", \"viz_unittests\": \"acefdbc6d0791a8854477954089001b346613b17\", \"vr_common_unittests\": \"6e3343d44b590b5f4ca40b86551ccaecceac4ff2\", \"vr_pixeltests\": \"58afd3f44d481785152fe28f0768e7d62a4b1a5f\", \"webdriver_wpt_tests\": \"ae6d9224640f99803b3a3a171a3ab1cd5fe641f3\", \"weblayer_browsertests\": \"76bf6695256ba0aa8a4089ba89e9a4bd366f9c2a\", \"wm_unittests\": \"9b96153762da9f327d6161a792cf0cf72abc474f\", \"wtf_unittests\": \"fce5fe5d538172301055b6cba9457aae5ae7b159\", \"xr_browser_tests\": \"68a819f1437dd981b40937323918d3cb8ced73d4\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_16895d03945fefa0a0694b70850ae78b1bb8ca61113b6e89bc39b6edfabe662c_v2\", \"builder_65ed874bd033a5984c3cbb528261ef1b136b40627f077f473aaaca27b3fa3caf_v2\", \"builder_84395cd86c4615c28c38755187d54d1bbe2dcea268d302d74191d04ce3a055d0_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-99-w4dd\"], \"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\": [\"4804-5c544e0\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1580461278398714",
"status": "COMPLETED",
"status_changed_ts": "1580464533893654",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168071",
"builder:Linux Builder (dbg)",
"buildset:commit/git/64ab230d19f4338c6dac04be8c768b9b6fa91ef4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/64ab230d19f4338c6dac04be8c768b9b6fa91ef4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055190288908557872",
"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/8889760225982598272/+/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:4a13a2cd16f43910",
"user_agent:luci-scheduler"
],
"updated_ts": "1580464533896983",
"url": "https://ci.chromium.org/b/8889760225982598272",
"utcnow_ts": "1580464534413045"
}
}
[09:55:34.424] Invocation finished in 58m40.919134535s with status SUCCEEDED