[06:41:16.389] New invocation is queued and will start shortly
[06:41:17.621] Starting the invocation (attempt 1)
[06:41:17.658] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:41:17.658] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:41:17.658] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9058007327271104816",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4f8b543266d36fb7459089414e1650d2a71d537c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9058007327271104816",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/4f8b543266d36fb7459089414e1650d2a71d537c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4f8b543266d36fb7459089414e1650d2a71d537c",
"gitiles_ref:refs/heads/master"
]
}
[06:41:18.259] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1577774477787610",
"id": "8892577265138564896",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4f8b543266d36fb7459089414e1650d2a71d537c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1577774478131682",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166774",
"builder:Linux Builder (dbg)",
"buildset:commit/git/4f8b543266d36fb7459089414e1650d2a71d537c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4f8b543266d36fb7459089414e1650d2a71d537c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9058007327271104816",
"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/8892577265138564896/+/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": "1577774478131778",
"url": "https://ci.chromium.org/b/8892577265138564896",
"utcnow_ts": "1577774478251397"
}
}
[06:41:18.259] Task URL: https://ci.chromium.org/b/8892577265138564896
[06:41:18.259] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:2:0) after 1m0s
[06:42:22.601] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:2:0)
[06:42:22.601] Timer tick, asking Buildbucket for the build status
[06:42:23.984] Build 8892577265138564896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:42:23.984] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:3:0) after 1m0s
[06:43:24.379] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:3:0)
[06:43:24.379] Timer tick, asking Buildbucket for the build status
[06:43:24.567] Build 8892577265138564896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:43:24.567] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:4:0) after 1m0s
[06:44:24.995] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:4:0)
[06:44:24.995] Timer tick, asking Buildbucket for the build status
[06:44:25.475] Build 8892577265138564896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:44:25.475] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:5:0) after 1m0s
[06:45:25.629] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:5:0)
[06:45:25.629] Timer tick, asking Buildbucket for the build status
[06:45:26.175] Build 8892577265138564896: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:45:26.175] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:6:0) after 1m0s
[06:46:05.808] Received PubSub notification, asking Buildbucket for the build status
[06:46:06.132] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:26.572] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:6:0)
[06:46:26.572] Timer tick, asking Buildbucket for the build status
[06:46:26.759] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:26.759] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:8:0) after 1m0s
[06:47:27.008] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:8:0)
[06:47:27.008] Timer tick, asking Buildbucket for the build status
[06:47:27.154] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:27.154] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:9:0) after 1m0s
[06:48:27.190] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:9:0)
[06:48:27.190] Timer tick, asking Buildbucket for the build status
[06:48:27.414] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:27.414] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:10:0) after 1m0s
[06:49:27.434] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:10:0)
[06:49:27.434] Timer tick, asking Buildbucket for the build status
[06:49:27.615] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:27.615] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:11:0) after 1m0s
[06:50:27.737] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:11:0)
[06:50:27.737] Timer tick, asking Buildbucket for the build status
[06:50:28.063] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:28.063] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:12:0) after 1m0s
[06:51:28.289] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:12:0)
[06:51:28.289] Timer tick, asking Buildbucket for the build status
[06:51:28.564] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:28.564] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:13:0) after 1m0s
[06:52:28.686] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:13:0)
[06:52:28.686] Timer tick, asking Buildbucket for the build status
[06:52:28.980] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:28.980] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:14:0) after 1m0s
[06:53:29.059] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:14:0)
[06:53:29.059] Timer tick, asking Buildbucket for the build status
[06:53:29.447] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:29.447] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:15:0) after 1m0s
[06:54:29.535] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:15:0)
[06:54:29.535] Timer tick, asking Buildbucket for the build status
[06:54:29.982] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:29.982] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:16:0) after 1m0s
[06:55:30.015] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:16:0)
[06:55:30.015] Timer tick, asking Buildbucket for the build status
[06:55:30.236] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:30.236] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:17:0) after 1m0s
[06:56:30.309] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:17:0)
[06:56:30.309] Timer tick, asking Buildbucket for the build status
[06:56:30.550] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:30.550] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:18:0) after 1m0s
[06:57:30.600] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:18:0)
[06:57:30.600] Timer tick, asking Buildbucket for the build status
[06:57:31.608] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:31.608] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:19:0) after 1m0s
[06:58:31.877] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:19:0)
[06:58:31.877] Timer tick, asking Buildbucket for the build status
[06:58:34.061] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:34.062] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:20:0) after 1m0s
[06:59:34.152] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:20:0)
[06:59:34.152] Timer tick, asking Buildbucket for the build status
[06:59:34.429] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:34.429] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:21:0) after 1m0s
[07:00:34.684] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:21:0)
[07:00:34.684] Timer tick, asking Buildbucket for the build status
[07:00:34.837] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:34.837] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:22:0) after 1m0s
[07:01:35.006] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:22:0)
[07:01:35.006] Timer tick, asking Buildbucket for the build status
[07:01:35.304] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:35.304] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:23:0) after 1m0s
[07:02:35.340] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:23:0)
[07:02:35.340] Timer tick, asking Buildbucket for the build status
[07:02:35.806] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:35.806] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:24:0) after 1m0s
[07:03:35.838] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:24:0)
[07:03:35.838] Timer tick, asking Buildbucket for the build status
[07:03:36.960] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:36.960] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:25:0) after 1m0s
[07:04:36.993] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:25:0)
[07:04:36.993] Timer tick, asking Buildbucket for the build status
[07:04:38.205] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:38.205] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:26:0) after 1m0s
[07:05:38.277] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:26:0)
[07:05:38.277] Timer tick, asking Buildbucket for the build status
[07:05:38.697] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:38.697] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:27:0) after 1m0s
[07:06:38.692] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:27:0)
[07:06:38.692] Timer tick, asking Buildbucket for the build status
[07:06:39.940] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:39.940] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:28:0) after 1m0s
[07:07:40.057] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:28:0)
[07:07:40.057] Timer tick, asking Buildbucket for the build status
[07:07:40.276] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:40.276] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:29:0) after 1m0s
[07:08:40.405] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:29:0)
[07:08:40.405] Timer tick, asking Buildbucket for the build status
[07:08:40.656] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:40.656] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:30:0) after 1m0s
[07:09:40.779] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:30:0)
[07:09:40.779] Timer tick, asking Buildbucket for the build status
[07:09:40.958] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:40.958] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:31:0) after 1m0s
[07:10:41.096] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:31:0)
[07:10:41.096] Timer tick, asking Buildbucket for the build status
[07:10:42.287] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:42.287] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:32:0) after 1m0s
[07:11:42.305] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:32:0)
[07:11:42.306] Timer tick, asking Buildbucket for the build status
[07:11:42.784] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:42.784] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:33:0) after 1m0s
[07:12:42.778] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:33:0)
[07:12:42.778] Timer tick, asking Buildbucket for the build status
[07:12:43.064] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:43.064] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:34:0) after 1m0s
[07:13:43.085] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:34:0)
[07:13:43.086] Timer tick, asking Buildbucket for the build status
[07:13:43.440] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:43.440] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:35:0) after 1m0s
[07:14:43.486] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:35:0)
[07:14:43.486] Timer tick, asking Buildbucket for the build status
[07:14:43.620] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:43.620] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:36:0) after 1m0s
[07:15:43.662] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:36:0)
[07:15:43.662] Timer tick, asking Buildbucket for the build status
[07:15:44.249] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:44.249] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:37:0) after 1m0s
[07:16:44.368] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:37:0)
[07:16:44.368] Timer tick, asking Buildbucket for the build status
[07:16:45.190] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:45.190] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:38:0) after 1m0s
[07:17:45.218] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:38:0)
[07:17:45.218] Timer tick, asking Buildbucket for the build status
[07:17:45.414] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:45.414] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:39:0) after 1m0s
[07:18:45.496] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:39:0)
[07:18:45.496] Timer tick, asking Buildbucket for the build status
[07:18:45.827] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:45.827] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:40:0) after 1m0s
[07:19:45.802] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:40:0)
[07:19:45.802] Timer tick, asking Buildbucket for the build status
[07:19:45.975] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:45.975] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:41:0) after 1m0s
[07:20:46.086] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:41:0)
[07:20:46.086] Timer tick, asking Buildbucket for the build status
[07:20:46.275] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:46.275] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:42:0) after 1m0s
[07:21:46.349] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:42:0)
[07:21:46.349] Timer tick, asking Buildbucket for the build status
[07:21:46.515] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:46.515] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:43:0) after 1m0s
[07:22:46.554] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:43:0)
[07:22:46.554] Timer tick, asking Buildbucket for the build status
[07:22:47.396] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:47.396] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:44:0) after 1m0s
[07:23:47.473] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:44:0)
[07:23:47.473] Timer tick, asking Buildbucket for the build status
[07:23:47.738] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:47.738] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:45:0) after 1m0s
[07:24:47.805] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:45:0)
[07:24:47.805] Timer tick, asking Buildbucket for the build status
[07:24:47.981] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:47.981] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:46:0) after 1m0s
[07:25:48.073] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:46:0)
[07:25:48.073] Timer tick, asking Buildbucket for the build status
[07:25:48.281] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:48.281] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:47:0) after 1m0s
[07:26:48.303] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:47:0)
[07:26:48.303] Timer tick, asking Buildbucket for the build status
[07:26:48.498] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:48.498] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:48:0) after 1m0s
[07:27:48.542] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:48:0)
[07:27:48.542] Timer tick, asking Buildbucket for the build status
[07:27:48.727] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:48.728] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:49:0) after 1m0s
[07:28:48.778] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:49:0)
[07:28:48.778] Timer tick, asking Buildbucket for the build status
[07:28:49.150] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:49.150] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:50:0) after 1m0s
[07:29:49.143] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:50:0)
[07:29:49.143] Timer tick, asking Buildbucket for the build status
[07:29:49.285] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:49.285] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:51:0) after 1m0s
[07:30:49.364] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:51:0)
[07:30:49.364] Timer tick, asking Buildbucket for the build status
[07:30:49.861] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:49.861] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:52:0) after 1m0s
[07:31:49.918] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:52:0)
[07:31:49.918] Timer tick, asking Buildbucket for the build status
[07:31:50.057] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:50.057] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:53:0) after 1m0s
[07:32:50.236] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:53:0)
[07:32:50.236] Timer tick, asking Buildbucket for the build status
[07:32:50.617] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:50.617] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:54:0) after 1m0s
[07:33:50.661] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:54:0)
[07:33:50.661] Timer tick, asking Buildbucket for the build status
[07:33:50.821] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:50.821] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:55:0) after 1m0s
[07:34:50.901] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:55:0)
[07:34:50.901] Timer tick, asking Buildbucket for the build status
[07:34:51.136] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:51.136] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:56:0) after 1m0s
[07:35:51.268] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:56:0)
[07:35:51.268] Timer tick, asking Buildbucket for the build status
[07:35:52.202] Build 8892577265138564896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:52.202] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9058007327271104816:57:0) after 1m0s
[07:36:30.843] Received PubSub notification, asking Buildbucket for the build status
[07:36:31.735] Build 8892577265138564896: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:36:31.735] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1577777789823306",
"created_by": "project:chromium",
"created_ts": "1577774477787610",
"id": "8892577265138564896",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4f8b543266d36fb7459089414e1650d2a71d537c\"}}",
"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-central1-b-8-3-3qau\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 166774, \"got_angle_revision\": \"9fac1817ede6b2f1dc61468e2625cbc30d35077c\", \"got_dawn_revision\": \"87d31381588b26edb3da1d75dd0588b2df4dd968\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"4f8b543266d36fb7459089414e1650d2a71d537c\", \"got_revision_cp\": \"refs/heads/master@{#727852}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"10a900e5ffaffdffe2806b1507af43a74acdfe9e\", \"got_v8_revision\": \"4c53f9a51444393133ff303952f1296603d44ab7\", \"got_v8_revision_cp\": \"refs/heads/8.1.80@{#1}\", \"got_webrtc_revision\": \"9d2c2dba28656b3c913797daaf99b8bf56ca6103\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30128}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4f8b543266d36fb7459089414e1650d2a71d537c\", \"swarm_hashes_refs/heads/master(at){#727852}_without_patch\": {\"accessibility_unittests\": \"da3e038a369c0dc3405009f4668f143f4dcf4b0d\", \"angle_unittests\": \"b60b2186774aea0990dc08944cba884277fb968b\", \"app_shell_unittests\": \"fe1684155c8643dd68da84c025f78b39e14413f6\", \"aura_unittests\": \"f213d66ac1a0a18c8d657dde6fd9b56bf5c8f73b\", \"base_unittests\": \"dec63dc6998d3cc9f46b76b6aa0fa3d3ea1b5b6b\", \"base_util_unittests\": \"d75209d1deb1b5e9894d90fe49baad44f28198db\", \"blink_common_unittests\": \"035625f1b0948204465e684f249631b5c1352f16\", \"blink_fuzzer_unittests\": \"b8a75ce479a205582a7f38876acf3e008a430bc3\", \"blink_heap_unittests\": \"7a856efb9bfafc8c6919041c988ceac5f3fedd19\", \"blink_platform_unittests\": \"105abff8cd5842652d7c2c0be71fa97a2ef47551\", \"blink_python_tests\": \"df381a422f93f663e2c9184969112db3b5082cec\", \"blink_unittests\": \"23e5f157dcc531d1365b74aabb395e9b1bb09186\", \"blink_web_tests\": \"6f5404fc92fd5179121af0ed742a999f7907594c\", \"boringssl_crypto_tests\": \"16bf76d0bd43020de0b0a3a27a0d178802347693\", \"boringssl_ssl_tests\": \"40be03876a4dfd4ccc8c18d1521e3ce976bda311\", \"browser_tests\": \"0c838a19e6e9c40265e1b138807b2cab273fe608\", \"cacheinvalidation_unittests\": \"556c60ab206bb104d474237a914ea13752f44a9a\", \"capture_unittests\": \"7d04929ad2db30dd473c64efb6986ab1e9463b01\", \"cast_unittests\": \"7dd651a945154cf11b5c544fec55376a3e02f388\", \"cc_unittests\": \"83b8a8b61df5e8675b9b3d27db534d1d0dbb4d84\", \"chrome_app_unittests\": \"ebdc096a8b625e380c300e04ffbc5b32fc90e8e4\", \"chromedriver_unittests\": \"6d8f985d134c80cfa47822ddf9a720af094f1077\", \"components_browsertests\": \"de38291435f2d38fdbf04af63890349ace35d40e\", \"components_unittests\": \"7bf5ddb9a4996cf0ecfc3bf1d6d7c6d24251720c\", \"compositor_unittests\": \"455d3dac047fcab421887bbfe9ff66132e5be903\", \"content_browsertests\": \"fcacec7ba0c3614358c6db2af8a5f6ec24c391a8\", \"content_shell_crash_test\": \"08fcdd9598091eecae5f6311c1a77e721701e5d2\", \"content_unittests\": \"f9d7c1fffa368e5531e17daf6ba51cfd667cf3da\", \"crashpad_tests\": \"6483b36da3f759f43d4eacaca3c8f1037a31c56c\", \"cronet_tests\": \"15f5acc48ecb17167cc93017d9dd84381b68bc74\", \"cronet_unittests\": \"0aad83a79a9fb5f54d74565dd06a05811e2f7c1d\", \"crypto_unittests\": \"62b85a206fae8f7fc117bfd87e24e01e6cfadaec\", \"dbus_unittests\": \"b18a20c9059f462936304c0128075027577884b4\", \"device_unittests\": \"f206e47c10c1341471c9987da1c42e754f0a51b7\", \"devtools_lint_check\": \"9f84bf915e135e24c84ed719616fb011977e3d19\", \"devtools_type_check\": \"b10e1a362cd553d9d91285c9d2b6fee980beddbd\", \"display_unittests\": \"25ec2dd31592eb42f55591097c026bf43eba02f6\", \"events_unittests\": \"4119b75547733949a971ba334c36cadaf458282c\", \"extensions_browsertests\": \"2d57062ab3fe55251cd6b1bb801f1b096c8c2b4b\", \"extensions_unittests\": \"54f20ab8626260fa1acf43a123d470d610f133ba\", \"filesystem_service_unittests\": \"4d0b3fc3e3c6e24be94a23fdab0cf1217d0778d9\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"b923a0c91600a1cd98a814ee1826502417de2164\", \"gfx_unittests\": \"a4ddc3fa0492a2ab6e39a8296dd3b812a12e911a\", \"gin_unittests\": \"87d62270ef3a528258fa407330476a7f4a0aac5c\", \"google_apis_unittests\": \"b2d154b4f6788c1897bc09042ff3f9d536a4e6e8\", \"gpu_unittests\": \"a7ab82073f2119134472a453cfa60d340e94f920\", \"grit_python_unittests\": \"c651860184eae0fccc98981c3c79be6f65eaf3fe\", \"gwp_asan_unittests\": \"c5d91102ad9e99913ae55450637a2a25d69b8422\", \"headless_browsertests\": \"179a0a9c768371b77c4292236ee31506ff5358af\", \"headless_unittests\": \"c98b59d5451e5ec0a7bc231d2d28706716d9363b\", \"interactive_ui_tests\": \"1a41c543cea805dbee68ecbf2b34d8935de5e16d\", \"ipc_tests\": \"a11fbdababb3707e1ec23be698b3f38a702be51c\", \"jingle_unittests\": \"8ae0e003f34e42b5fe977168db0d0b74c6dd466d\", \"latency_unittests\": \"3dc8b8dc352542efff02080bd681f8342ecc98f4\", \"libjingle_xmpp_unittests\": \"446c8dad2ea0ba3bb27631c5cd0f905762e1016a\", \"media_blink_unittests\": \"e87642a121b2784fcb53a83ce888f52ae4e17b74\", \"media_service_unittests\": \"a8232eec268c130ce3758377025ccb8508709628\", \"media_unittests\": \"a94f21b343d385a79e4165feb07e5599f64014fb\", \"message_center_unittests\": \"2f9cf65316d55fb5b875155a0285479f218e3701\", \"metrics_python_tests\": \"11255f07aae219f48aec28bf54b0597ee8f32693\", \"midi_unittests\": \"cca488882a0e5de2ba68adb747cd2baa11c2f013\", \"mojo_core_unittests\": \"74cad2c982308d7d83fd3c560a97045d57e06b78\", \"mojo_unittests\": \"29b51091cbfb0ed8130ed12ae72862c6269a7d5b\", \"nacl_helper_nonsfi_unittests\": \"55a89485b120e7f8bc5ce09509896d05a88460cc\", \"nacl_loader_unittests\": \"763658ea0245adfff5fb741cf19a30972ccfd8d4\", \"native_theme_unittests\": \"131e8a1252d398c37c694d976606e780ecb8683b\", \"net_unittests\": \"8afa62eead0cd3cb4888e74c63ce988df4817c1d\", \"pdf_unittests\": \"8db1c3f21ca163cce52410b5820cb1a4909484f0\", \"perfetto_unittests\": \"33a0b5da0a3800cfd66dd4b9fb9b92ea0260efe5\", \"ppapi_unittests\": \"c1e3d7f74c74e813abb62831b5495b11a70c944c\", \"printing_unittests\": \"dae78488f64e62b1e25471115766d7ce2d8dc343\", \"remoting_unittests\": \"602c1132e070f1a65e0d642de65bd77b425ad3b8\", \"sandbox_linux_unittests\": \"4086d03e75d59c4b049a5feaa15fbff78aab120f\", \"service_manager_unittests\": \"c2077fae18310375053564ddc9526f4df384b2df\", \"services_unittests\": \"aec51b5f2c83891d45456523dc2279dff89bc1d9\", \"shell_dialogs_unittests\": \"d16a103b13a339b25968909511d200b9634cc2f8\", \"skia_unittests\": \"70ba7fcad32e96974f6655729338d99aa5c67255\", \"snapshot_unittests\": \"456c9fa0e7d85fb6716e43f83ac54b5b78b015b5\", \"sql_unittests\": \"ec308b65259310647da2da207a5966f418f05f53\", \"storage_unittests\": \"07442a2b2e096159176130e4d3250279b6eda6d6\", \"sync_integration_tests\": \"7fdec773c93b03af53d0d5b8f01e636204259f47\", \"telemetry_gpu_unittests\": \"6c37ec8be32f097d7384ba38825dfca4227dee13\", \"telemetry_perf_unittests\": \"59ffd881544e4733378c4058c2401d908acad285\", \"telemetry_unittests\": \"5237e09a08d6a508035ff295e385dd947709f74a\", \"traffic_annotation_auditor_unittests\": \"5fa370b59d7285eb057f481c4be6bcce97e9bf78\", \"ui_base_unittests\": \"38ba1f4ac4178fde78a76b81a21dbc00a1564b55\", \"ui_touch_selection_unittests\": \"2b1f1367213e7e3fc3c70ee9871e239d1e19213d\", \"unit_tests\": \"69b32bf5d7a809050ac27306c8ea9a31048d6253\", \"url_unittests\": \"f847efdc68e689a7671022a63a946607508a819a\", \"views_perftests\": \"f21193c6f1c26123fc826c499a8e116d7577ddb7\", \"views_unittests\": \"4c3812c468aeb250e4a1766c26613cc6d03e99c1\", \"viz_unittests\": \"2dd44877ab9b8fe47bbb52f3caa6437eb538f42d\", \"vr_common_unittests\": \"b9d7de70aaa71e04db7750f6dc19fe2ac9adac28\", \"vr_pixeltests\": \"062255b5a06862657b7685df3dbcd1b748de2dd1\", \"webdriver_wpt_tests\": \"98efd2643a2ef021033e2c27bc5b2fe9eecae159\", \"weblayer_browsertests\": \"67467b9d201f0d293010302951d71ae059bdbec3\", \"wm_unittests\": \"5e77b6db058f376571d59fd82b9997cadd0cc078\", \"wtf_unittests\": \"ea10745fc8b3ae4b3898431d0cfccbe841019105\", \"xr_browser_tests\": \"6d35b6880ba637a300cdea680e6b0cdadb9222f4\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3f701152d865ce3ec8feb15f973214ad980aeace5cbda24477548a965269fca6_v2\", \"builder_a7ee5da0df0016658701d083d0522e25adae25ba2fa23a313caa54097831117f_v2\", \"builder_b224075beee246a5ffe9706a23163fae567137407ac5f1b69c50b4dcd22edd9b_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-central1-b-8-3-3qau\"], \"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-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1577774764923313",
"status": "COMPLETED",
"status_changed_ts": "1577777790308752",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166774",
"builder:Linux Builder (dbg)",
"buildset:commit/git/4f8b543266d36fb7459089414e1650d2a71d537c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4f8b543266d36fb7459089414e1650d2a71d537c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9058007327271104816",
"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/8892577265138564896/+/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:4973817cb1e2f810",
"user_agent:luci-scheduler"
],
"updated_ts": "1577777790312095",
"url": "https://ci.chromium.org/b/8892577265138564896",
"utcnow_ts": "1577777791643633"
}
}
[07:36:31.735] Invocation finished in 55m15.366658904s with status SUCCEEDED