[18:40:45.256] New invocation is queued and will start shortly
[18:40:47.105] Starting the invocation (attempt 1)
[18:40:47.164] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:40:47.164] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:40:47.165] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9050442513217137696",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3e8a448e8f8fff7949820850d0a2df80e5a3595f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9050442513217137696",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/3e8a448e8f8fff7949820850d0a2df80e5a3595f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3e8a448e8f8fff7949820850d0a2df80e5a3595f",
"gitiles_ref:refs/heads/master"
]
}
[18:40:47.963] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1584988847297367",
"id": "8885012450414670592",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3e8a448e8f8fff7949820850d0a2df80e5a3595f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1584988847766530",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/170085",
"builder:Linux Builder (dbg)",
"buildset:commit/git/3e8a448e8f8fff7949820850d0a2df80e5a3595f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3e8a448e8f8fff7949820850d0a2df80e5a3595f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9050442513217137696",
"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/8885012450414670592/+/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": "1584988847766691",
"url": "https://ci.chromium.org/b/8885012450414670592",
"utcnow_ts": "1584988847953071"
}
}
[18:40:47.963] Task URL: https://ci.chromium.org/b/8885012450414670592
[18:40:47.963] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:2:0) after 1m0s
[18:41:47.964] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:2:0)
[18:41:47.964] Timer tick, asking Buildbucket for the build status
[18:41:48.486] Build 8885012450414670592: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:41:48.486] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:3:0) after 1m0s
[18:42:48.909] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:3:0)
[18:42:48.910] Timer tick, asking Buildbucket for the build status
[18:42:49.105] Build 8885012450414670592: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:42:49.105] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:4:0) after 1m0s
[18:43:49.984] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:4:0)
[18:43:49.984] Timer tick, asking Buildbucket for the build status
[18:43:50.080] Build 8885012450414670592: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:43:50.080] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:5:0) after 1m0s
[18:44:50.359] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:5:0)
[18:44:50.359] Timer tick, asking Buildbucket for the build status
[18:44:50.472] Build 8885012450414670592: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:44:50.472] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:6:0) after 1m0s
[18:45:38.861] Received PubSub notification, asking Buildbucket for the build status
[18:45:39.007] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:50.549] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:6:0)
[18:45:50.549] Timer tick, asking Buildbucket for the build status
[18:45:50.648] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:50.648] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:8:0) after 1m0s
[18:46:51.085] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:8:0)
[18:46:51.085] Timer tick, asking Buildbucket for the build status
[18:46:51.413] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:51.413] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:9:0) after 1m0s
[18:47:51.585] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:9:0)
[18:47:51.585] Timer tick, asking Buildbucket for the build status
[18:47:51.712] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:51.712] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:10:0) after 1m0s
[18:48:52.139] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:10:0)
[18:48:52.139] Timer tick, asking Buildbucket for the build status
[18:48:52.283] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:52.283] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:11:0) after 1m0s
[18:49:52.428] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:11:0)
[18:49:52.428] Timer tick, asking Buildbucket for the build status
[18:49:52.681] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:52.681] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:12:0) after 1m0s
[18:50:52.841] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:12:0)
[18:50:52.841] Timer tick, asking Buildbucket for the build status
[18:50:53.201] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:53.201] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:13:0) after 1m0s
[18:51:53.504] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:13:0)
[18:51:53.504] Timer tick, asking Buildbucket for the build status
[18:51:53.814] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:53.814] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:14:0) after 1m0s
[18:52:54.215] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:14:0)
[18:52:54.215] Timer tick, asking Buildbucket for the build status
[18:52:54.498] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:54.498] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:15:0) after 1m0s
[18:53:55.666] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:15:0)
[18:53:55.666] Timer tick, asking Buildbucket for the build status
[18:53:56.105] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:56.105] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:16:0) after 1m0s
[18:54:56.425] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:16:0)
[18:54:56.425] Timer tick, asking Buildbucket for the build status
[18:54:56.596] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:56.596] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:17:0) after 1m0s
[18:55:57.433] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:17:0)
[18:55:57.433] Timer tick, asking Buildbucket for the build status
[18:55:57.809] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:57.809] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:18:0) after 1m0s
[18:56:58.018] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:18:0)
[18:56:58.018] Timer tick, asking Buildbucket for the build status
[18:56:58.201] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:58.201] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:19:0) after 1m0s
[18:57:58.305] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:19:0)
[18:57:58.305] Timer tick, asking Buildbucket for the build status
[18:57:58.429] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:58.429] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:20:0) after 1m0s
[18:58:58.509] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:20:0)
[18:58:58.509] Timer tick, asking Buildbucket for the build status
[18:58:58.683] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:58.683] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:21:0) after 1m0s
[18:59:58.794] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:21:0)
[18:59:58.794] Timer tick, asking Buildbucket for the build status
[18:59:59.146] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:59.146] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:22:0) after 1m0s
[19:00:59.195] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:22:0)
[19:00:59.195] Timer tick, asking Buildbucket for the build status
[19:00:59.476] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:59.476] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:23:0) after 1m0s
[19:01:59.725] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:23:0)
[19:01:59.725] Timer tick, asking Buildbucket for the build status
[19:01:59.870] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:59.871] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:24:0) after 1m0s
[19:02:59.981] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:24:0)
[19:02:59.981] Timer tick, asking Buildbucket for the build status
[19:03:00.144] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:00.144] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:25:0) after 1m0s
[19:04:00.243] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:25:0)
[19:04:00.243] Timer tick, asking Buildbucket for the build status
[19:04:00.811] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:00.811] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:26:0) after 1m0s
[19:05:00.909] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:26:0)
[19:05:00.909] Timer tick, asking Buildbucket for the build status
[19:05:01.334] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:01.334] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:27:0) after 1m0s
[19:06:01.352] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:27:0)
[19:06:01.352] Timer tick, asking Buildbucket for the build status
[19:06:01.453] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:01.453] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:28:0) after 1m0s
[19:07:01.491] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:28:0)
[19:07:01.491] Timer tick, asking Buildbucket for the build status
[19:07:01.771] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:01.771] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:29:0) after 1m0s
[19:08:01.812] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:29:0)
[19:08:01.812] Timer tick, asking Buildbucket for the build status
[19:08:02.015] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:02.015] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:30:0) after 1m0s
[19:09:02.061] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:30:0)
[19:09:02.061] Timer tick, asking Buildbucket for the build status
[19:09:02.326] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:02.326] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:31:0) after 1m0s
[19:10:02.411] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:31:0)
[19:10:02.411] Timer tick, asking Buildbucket for the build status
[19:10:02.863] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:02.863] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:32:0) after 1m0s
[19:11:02.850] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:32:0)
[19:11:02.850] Timer tick, asking Buildbucket for the build status
[19:11:03.047] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:03.047] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:33:0) after 1m0s
[19:12:03.099] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:33:0)
[19:12:03.100] Timer tick, asking Buildbucket for the build status
[19:12:03.222] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:03.222] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:34:0) after 1m0s
[19:13:03.325] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:34:0)
[19:13:03.325] Timer tick, asking Buildbucket for the build status
[19:13:03.765] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:03.765] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:35:0) after 1m0s
[19:14:03.934] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:35:0)
[19:14:03.934] Timer tick, asking Buildbucket for the build status
[19:14:04.624] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:04.624] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:36:0) after 1m0s
[19:15:04.676] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:36:0)
[19:15:04.676] Timer tick, asking Buildbucket for the build status
[19:15:05.046] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:05.046] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:37:0) after 1m0s
[19:16:05.105] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:37:0)
[19:16:05.105] Timer tick, asking Buildbucket for the build status
[19:16:05.199] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:05.199] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:38:0) after 1m0s
[19:17:05.278] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:38:0)
[19:17:05.278] Timer tick, asking Buildbucket for the build status
[19:17:05.713] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:05.713] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:39:0) after 1m0s
[19:18:05.804] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:39:0)
[19:18:05.804] Timer tick, asking Buildbucket for the build status
[19:18:06.405] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:06.405] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:40:0) after 1m0s
[19:19:06.523] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:40:0)
[19:19:06.523] Timer tick, asking Buildbucket for the build status
[19:19:06.749] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:06.749] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:41:0) after 1m0s
[19:20:06.780] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:41:0)
[19:20:06.780] Timer tick, asking Buildbucket for the build status
[19:20:06.886] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:06.886] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:42:0) after 1m0s
[19:21:07.165] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:42:0)
[19:21:07.165] Timer tick, asking Buildbucket for the build status
[19:21:08.124] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:08.124] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:43:0) after 1m0s
[19:22:08.169] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:43:0)
[19:22:08.169] Timer tick, asking Buildbucket for the build status
[19:22:08.719] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:08.719] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:44:0) after 1m0s
[19:23:08.860] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:44:0)
[19:23:08.860] Timer tick, asking Buildbucket for the build status
[19:23:09.567] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:09.567] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:45:0) after 1m0s
[19:24:09.614] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:45:0)
[19:24:09.614] Timer tick, asking Buildbucket for the build status
[19:24:10.039] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:10.039] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:46:0) after 1m0s
[19:25:10.114] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:46:0)
[19:25:10.114] Timer tick, asking Buildbucket for the build status
[19:25:10.334] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:10.334] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:47:0) after 1m0s
[19:26:10.524] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:47:0)
[19:26:10.524] Timer tick, asking Buildbucket for the build status
[19:26:10.873] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:10.873] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:48:0) after 1m0s
[19:27:11.087] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:48:0)
[19:27:11.087] Timer tick, asking Buildbucket for the build status
[19:27:11.369] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:11.369] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:49:0) after 1m0s
[19:28:11.405] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:49:0)
[19:28:11.405] Timer tick, asking Buildbucket for the build status
[19:28:11.647] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:11.647] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:50:0) after 1m0s
[19:29:11.740] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:50:0)
[19:29:11.740] Timer tick, asking Buildbucket for the build status
[19:29:12.282] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:12.282] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:51:0) after 1m0s
[19:30:12.274] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:51:0)
[19:30:12.274] Timer tick, asking Buildbucket for the build status
[19:30:12.775] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:12.775] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:52:0) after 1m0s
[19:31:12.965] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:52:0)
[19:31:12.966] Timer tick, asking Buildbucket for the build status
[19:31:13.139] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:13.139] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:53:0) after 1m0s
[19:32:13.242] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:53:0)
[19:32:13.242] Timer tick, asking Buildbucket for the build status
[19:32:13.496] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:13.496] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:54:0) after 1m0s
[19:33:13.535] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:54:0)
[19:33:13.535] Timer tick, asking Buildbucket for the build status
[19:33:14.002] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:14.002] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:55:0) after 1m0s
[19:34:14.012] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:55:0)
[19:34:14.012] Timer tick, asking Buildbucket for the build status
[19:34:14.143] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:14.144] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:56:0) after 1m0s
[19:35:14.414] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:56:0)
[19:35:14.414] Timer tick, asking Buildbucket for the build status
[19:35:16.396] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:16.396] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:57:0) after 1m0s
[19:36:16.490] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:57:0)
[19:36:16.490] Timer tick, asking Buildbucket for the build status
[19:36:16.722] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:16.722] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:58:0) after 1m0s
[19:37:16.903] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:58:0)
[19:37:16.903] Timer tick, asking Buildbucket for the build status
[19:37:17.715] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:17.715] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:59:0) after 1m0s
[19:38:17.782] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:59:0)
[19:38:17.782] Timer tick, asking Buildbucket for the build status
[19:38:17.840] Build 8885012450414670592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:17.840] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050442513217137696:60:0) after 1m0s
[19:38:46.928] Received PubSub notification, asking Buildbucket for the build status
[19:38:47.064] Build 8885012450414670592: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:38:47.065] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1584992326083064",
"created_by": "project:chromium",
"created_ts": "1584988847297367",
"id": "8885012450414670592",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3e8a448e8f8fff7949820850d0a2df80e5a3595f\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/chromium_tests\": {\"bucketed_triggers\": true}, \"$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-151-nn9q\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 170085, \"got_angle_revision\": \"31e36a6313457e32bcb7da0a85ed5a73504dd231\", \"got_dawn_revision\": \"11652ff8f8b3c3104eb2627717fa652d432d5b84\", \"got_nacl_revision\": \"35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\", \"got_revision\": \"3e8a448e8f8fff7949820850d0a2df80e5a3595f\", \"got_revision_cp\": \"refs/heads/master@{#752514}\", \"got_swarming_client_revision\": \"cc958279ffd6853e0a1b227a7e957ca334fe56af\", \"got_swiftshader_revision\": \"95b77b44a8a80b1311dc6f3bf197e931cd3d7083\", \"got_v8_revision\": \"079cda55d7c1c902a41a052480cbc2cf9efd5433\", \"got_v8_revision_cp\": \"refs/heads/8.3.51@{#1}\", \"got_webrtc_revision\": \"8d1f72852e9839abb7cb96271bc367339dfee518\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30855}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3e8a448e8f8fff7949820850d0a2df80e5a3595f\", \"swarm_hashes_refs/heads/master(at){#752514}_without_patch\": {\"accessibility_unittests\": \"a2282ba5c3ee17a544d56c9b3b6a43e3626d6031\", \"angle_unittests\": \"aa2f7c50a692b5155e97d332be50df946d411716\", \"app_shell_unittests\": \"31c16d77f8cce76e7799c2dcd262ae427e6c75a0\", \"aura_unittests\": \"b9463a1c543fe46dbb660554ac1054ad5ad2f4fb\", \"base_unittests\": \"40a8813c30f0f8f2db5991bdb5d55ee229598f55\", \"base_util_unittests\": \"6586f96e0b0e8f92388da0617f924bb4b994a030\", \"blink_common_unittests\": \"98bb08e3a796faeeb22d912db9507a9638e7cafe\", \"blink_fuzzer_unittests\": \"49619fc1ca95851573add65782c904b9037bf469\", \"blink_heap_unittests\": \"bdc11d0547b7f89d27e5d74ab0752e7839dbebd5\", \"blink_platform_unittests\": \"3936442f1b9c46ca0b25017bcb495af8cc035a2f\", \"blink_python_tests\": \"bceecf9125376040f6021b829aec8e0aa40cc115\", \"blink_unittests\": \"697d60a19790f141c8407f9ff413d285c6cad5d9\", \"blink_web_tests\": \"5d64c3c72d7947c4c25df3acffe5e20a3e1ad954\", \"boringssl_crypto_tests\": \"b1f2f6acdf203b25ca444183ae698e71785dae15\", \"boringssl_ssl_tests\": \"2bd0dc56278afbd98e21a8d583d68665872a6845\", \"browser_tests\": \"243f4231dcc59a8a59d967bb03ed4f99a75e8154\", \"cacheinvalidation_unittests\": \"8b36889384f6fac45dd8eb9aaf5ef9648f8c473f\", \"capture_unittests\": \"5e51439cb012732ba16dd735dffe96cc3b42f9bb\", \"cast_unittests\": \"42124f818578b5cb9495c7d39c231d8998a93a6a\", \"cc_unittests\": \"5ffc6b367816911f256afb49b6e7facdce282353\", \"chrome_app_unittests\": \"7cf96fd45096744f45cdcb80df1d5ca839d55fcd\", \"chromedriver_unittests\": \"92aa5cd17ccc32bbf30e78b5c07f9db6b583bec7\", \"components_browsertests\": \"d34c6d746354c2e5be769802c43e6fed57cbaaec\", \"components_unittests\": \"d93e38860354aed88abe28855ddf6f04ad5c4872\", \"compositor_unittests\": \"f95197d0cf8044209f63d2caeb40698b05b8cf0c\", \"content_browsertests\": \"7a5fed21eac00a66e1ffaa0f08ec425aecba8fee\", \"content_shell_crash_test\": \"cad4a14b55113717d2304209f90239a3e6a67e9f\", \"content_unittests\": \"2f1c3ae991779c971d6b7bb1d7e1640a5b1a10ea\", \"crashpad_tests\": \"4be6a89c9c170e4fdaee22539ae1076d1e18d8d2\", \"cronet_tests\": \"a39ea0d24df287323f50c73c3a14037d102b5f61\", \"cronet_unittests\": \"b258bc44de7f7d2c1e36979caa3896ae917d0f42\", \"crypto_unittests\": \"25a20779634bfe1b532e42b86274f20af2801bae\", \"dbus_unittests\": \"49985a57fdd6703084f6ccf7e672378ae8a5418e\", \"device_unittests\": \"2361d45db8ce3341693cfa1bceacd3d2934e9d66\", \"display_unittests\": \"8764472d3f0240b064ddd4ae0bc29280ef0340eb\", \"events_unittests\": \"0b0486efffa3024909abbe57ccda4c038a410a56\", \"extensions_browsertests\": \"ebf5dd3288159848088bf71a6cefb419c33e8b9a\", \"extensions_unittests\": \"46f8ce92113a1072278e537269e438f4fda235fe\", \"filesystem_service_unittests\": \"b64e24ebd34135b1cecef0cb5bcced10cd1b6074\", \"flatbuffers_unittests\": \"7455b24b5a4a82227e2c07affd5e02713b03af86\", \"gcm_unit_tests\": \"7b9a002c7d18ce6e6e2dbd4ba57b3fd79e671344\", \"gfx_unittests\": \"651fec8fa766e7f74b985e46f5de14b830fdb431\", \"gin_unittests\": \"036829fed751a1e2d825f95d65e7e9009c78da2c\", \"google_apis_unittests\": \"b51ac5a418c0fb64c6db27c70bdffe9c4cde8033\", \"gpu_unittests\": \"23455236ec5cf46e80e07de2e6a58bd11be5fedf\", \"grit_python_unittests\": \"afbbe1df7c81809157ec9d2af582b8cb9256fe66\", \"gwp_asan_unittests\": \"e9a5729590a53d0f54100def956add1324d5a9ef\", \"headless_browsertests\": \"41799ae7c470c869e5af575fd80eeaa1b7b39970\", \"headless_unittests\": \"d1d9c0c3a4ad61766ae971b886095219efb7d32e\", \"interactive_ui_tests\": \"296fe6e9b5385d264f165f38917e792a023f6a90\", \"ipc_tests\": \"7af61f8d5de00ea01c653ad68da467cfc37f65d3\", \"jingle_unittests\": \"2e101a2bfbcc678bfe59104378c7b782dc61b697\", \"latency_unittests\": \"83821f9cf6bccd3ec20c8b657f4a0c82da78601e\", \"libjingle_xmpp_unittests\": \"66a9e4ceff81976230b9606d194eac79553f518e\", \"media_blink_unittests\": \"526d865b2d0c764ac2f7f12f023765a0ba827f20\", \"media_service_unittests\": \"2549592f9e79f45eb9894087f0e353a1b83aa19e\", \"media_unittests\": \"1d3faad15d6b40c394b8a006d64630addbf90180\", \"message_center_unittests\": \"45861a618fd05c4dab9cb3ea067d63f59f716c4f\", \"metrics_python_tests\": \"47e9631bbcc985cb10c77a9f776d67cb318c62c1\", \"midi_unittests\": \"98ab07d537edab9b16d37833f144e53fa388662a\", \"mojo_core_unittests\": \"fcc7d42404cd17917098f56a0035202b783f96a6\", \"mojo_unittests\": \"5c771dfa140c93eb6b8d7fe74147594ffbbf10b1\", \"nacl_helper_nonsfi_unittests\": \"0c02d8a9299e653f4dda2a408fa9ac26f9e5e689\", \"nacl_loader_unittests\": \"07c113187ae3deca022606dffee7fa3fc192f0c8\", \"native_theme_unittests\": \"0a1c2f9e581ed7c43334dc83aa055b8c1af39669\", \"net_unittests\": \"833fc06be2113dcfa3c17d0f834ac961d6c0a9b0\", \"pdf_unittests\": \"80d11a4103cce438c12cdf03e2323f2d05f246ce\", \"perfetto_unittests\": \"3f0baaa5e474ab394302272955bacceafa9ea2ee\", \"ppapi_unittests\": \"d86a9f75af683a9d92e31a3fbb1f6e298ce9feef\", \"printing_unittests\": \"97de16a0f62b1ed0b192f90c696023c3258340b9\", \"remoting_unittests\": \"2c7c44f48d5fedd884ad16c15011df792711189f\", \"sandbox_linux_unittests\": \"be32ea41a17135bd1d53102d065e196791421f09\", \"service_manager_unittests\": \"cfbc1b843dc4f1de076b43ef6bb58e23173eb590\", \"services_unittests\": \"10f7897f72192e30fc8f2a3905693a4906711256\", \"shell_dialogs_unittests\": \"8ee53feaf9d54e819f1f23a6426a00a86115bba7\", \"skia_unittests\": \"fa2237426632232a83ecfcc37028098f3d3c1cbc\", \"snapshot_unittests\": \"72a26317728c37a9d01687a19d6c7ff66b0ba905\", \"sql_unittests\": \"d02729e24641baee8038e1fbffed45a40cb225a9\", \"storage_unittests\": \"55f00f334e5713fe56b2d54a6a267d52e41af261\", \"sync_integration_tests\": \"9407b48d155bbc7e3c2968e20738e7e3c28edf4a\", \"telemetry_gpu_unittests\": \"49507fb2d4d774a932e43d6990030873d9eedfd5\", \"telemetry_perf_unittests\": \"1957bf10e273b9a01895ba19c1e90b57eab76e5f\", \"telemetry_unittests\": \"6ade2d6b2ff3fce8d392ab2b5a1b839f7f357c63\", \"traffic_annotation_auditor_unittests\": \"22cb89249095e17aa1c1c6dd76905d403728bec9\", \"ui_base_unittests\": \"18acfd6cfb1f895da89585df3055703a013d2801\", \"ui_touch_selection_unittests\": \"57e02d5c8f479d9c340201a92e6dda4372d60654\", \"unit_tests\": \"132a0f1e636ae044039baa7311f0f8d0b8525748\", \"url_unittests\": \"2ef341d95e0103a2ef7babd1fb2a00f7a5df34c6\", \"views_perftests\": \"55afa47842212f970dd4fddb87eedf9999b151bd\", \"views_unittests\": \"02bbeefc456c6146105200fb6b4f7fcbab36f3c9\", \"viz_unittests\": \"eb517e73a490ba71b76fb40584a6853b8f04010b\", \"vr_common_unittests\": \"50e86893bf9ea4f167c235579bf993895f4f5ca2\", \"vr_pixeltests\": \"765c6c35e1ee993e9f7d76cabd83a8ed0e60e393\", \"webdriver_wpt_tests\": \"bd4468dc9486397e37c145195961a1b64bdab8c1\", \"weblayer_browsertests\": \"aad05ddd62e72c89529ea15a26cdf86bae3053d3\", \"wm_unittests\": \"6099eaa0558e92754adc049749256c202a2a58e9\", \"wtf_unittests\": \"82251286711559dbc8bedda69fe0824b0a03ee5d\", \"xr_browser_tests\": \"2035811883f978e8698bc3f5d4ec9e2d94d891ed\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_58f95b007ea60c168bc176676490abae5757dcf141e4048bdc7ee9af9ae02cf5_v2\", \"builder_c9091f6bc58db7983401393b6dacc93b3db8fae2c2ae9b40177b9e0904c8bc80_v2\", \"builder_fac2959e29eeee13eb43de2791ff2fcb7aa7ab15952f0beca21f4615b55fa2a2_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-151-nn9q\"], \"image\": [\"chrome-xenial-20031500-6b26172a85c\"], \"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\": [\"4874-3598771\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1584989138183572",
"status": "COMPLETED",
"status_changed_ts": "1584992326656337",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/170085",
"builder:Linux Builder (dbg)",
"buildset:commit/git/3e8a448e8f8fff7949820850d0a2df80e5a3595f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3e8a448e8f8fff7949820850d0a2df80e5a3595f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9050442513217137696",
"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/8885012450414670592/+/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:4b218437608f6e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1584992326660496",
"url": "https://ci.chromium.org/b/8885012450414670592",
"utcnow_ts": "1584992327049313"
}
}
[19:38:47.065] Invocation finished in 58m1.824626757s with status SUCCEEDED