[08:42:16.975] New invocation is queued and will start shortly
[08:42:18.069] Starting the invocation (attempt 1)
[08:42:18.116] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:42:18.119] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:42:18.119] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021851524407053312",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5a3ad4823597a66129edb847f70a97de6c9d6d1f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9021851524407053312",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/5a3ad4823597a66129edb847f70a97de6c9d6d1f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a3ad4823597a66129edb847f70a97de6c9d6d1f",
"gitiles_ref:refs/heads/master"
]
}
[08:42:18.752] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612255338227540",
"id": "8856421462421513776",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5a3ad4823597a66129edb847f70a97de6c9d6d1f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612255338606509",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180192",
"builder:Linux Builder (dbg)",
"buildset:commit/git/5a3ad4823597a66129edb847f70a97de6c9d6d1f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a3ad4823597a66129edb847f70a97de6c9d6d1f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021851524407053312",
"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/8856421462421513776/+/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": "1612255338606726",
"url": "https://ci.chromium.org/b/8856421462421513776",
"utcnow_ts": "1612255338738566"
}
}
[08:42:18.752] Task URL: https://ci.chromium.org/b/8856421462421513776
[08:42:18.752] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:2:0) after 1m0s
[08:42:20.831] Received PubSub notification, asking Buildbucket for the build status
[08:42:20.928] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:18.773] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:2:0)
[08:43:18.773] Timer tick, asking Buildbucket for the build status
[08:43:18.886] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:18.886] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:4:0) after 1m0s
[08:44:18.904] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:4:0)
[08:44:18.904] Timer tick, asking Buildbucket for the build status
[08:44:19.047] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:19.047] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:5:0) after 1m0s
[08:45:19.088] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:5:0)
[08:45:19.088] Timer tick, asking Buildbucket for the build status
[08:45:19.194] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:19.194] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:6:0) after 1m0s
[08:46:19.219] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:6:0)
[08:46:19.219] Timer tick, asking Buildbucket for the build status
[08:46:19.316] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:19.316] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:7:0) after 1m0s
[08:47:19.341] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:7:0)
[08:47:19.341] Timer tick, asking Buildbucket for the build status
[08:47:19.849] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:19.849] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:8:0) after 1m0s
[08:48:19.897] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:8:0)
[08:48:19.897] Timer tick, asking Buildbucket for the build status
[08:48:19.996] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:19.996] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:9:0) after 1m0s
[08:49:20.032] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:9:0)
[08:49:20.032] Timer tick, asking Buildbucket for the build status
[08:49:20.092] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:20.092] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:10:0) after 1m0s
[08:50:20.211] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:10:0)
[08:50:20.211] Timer tick, asking Buildbucket for the build status
[08:50:20.299] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:20.299] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:11:0) after 1m0s
[08:51:20.427] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:11:0)
[08:51:20.427] Timer tick, asking Buildbucket for the build status
[08:51:21.155] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:21.155] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:12:0) after 1m0s
[08:52:21.296] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:12:0)
[08:52:21.296] Timer tick, asking Buildbucket for the build status
[08:52:21.438] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:21.438] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:13:0) after 1m0s
[08:53:21.672] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:13:0)
[08:53:21.672] Timer tick, asking Buildbucket for the build status
[08:53:21.940] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:21.940] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:14:0) after 1m0s
[08:54:22.104] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:14:0)
[08:54:22.104] Timer tick, asking Buildbucket for the build status
[08:54:22.158] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:22.158] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:15:0) after 1m0s
[08:55:22.175] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:15:0)
[08:55:22.175] Timer tick, asking Buildbucket for the build status
[08:55:22.245] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:22.245] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:16:0) after 1m0s
[08:56:22.409] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:16:0)
[08:56:22.409] Timer tick, asking Buildbucket for the build status
[08:56:22.477] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:22.478] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:17:0) after 1m0s
[08:57:22.607] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:17:0)
[08:57:22.607] Timer tick, asking Buildbucket for the build status
[08:57:22.909] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:22.909] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:18:0) after 1m0s
[08:58:22.949] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:18:0)
[08:58:22.949] Timer tick, asking Buildbucket for the build status
[08:58:23.220] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:23.220] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:19:0) after 1m0s
[08:59:23.341] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:19:0)
[08:59:23.341] Timer tick, asking Buildbucket for the build status
[08:59:23.601] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:23.601] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:20:0) after 1m0s
[09:00:23.747] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:20:0)
[09:00:23.747] Timer tick, asking Buildbucket for the build status
[09:00:24.137] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:24.137] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:21:0) after 1m0s
[09:01:24.174] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:21:0)
[09:01:24.174] Timer tick, asking Buildbucket for the build status
[09:01:24.448] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:24.448] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:22:0) after 1m0s
[09:02:24.500] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:22:0)
[09:02:24.501] Timer tick, asking Buildbucket for the build status
[09:02:24.608] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:24.608] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:23:0) after 1m0s
[09:03:24.624] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:23:0)
[09:03:24.624] Timer tick, asking Buildbucket for the build status
[09:03:24.684] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:24.684] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:24:0) after 1m0s
[09:04:24.852] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:24:0)
[09:04:24.852] Timer tick, asking Buildbucket for the build status
[09:04:24.919] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:24.919] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:25:0) after 1m0s
[09:05:24.950] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:25:0)
[09:05:24.950] Timer tick, asking Buildbucket for the build status
[09:05:25.081] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:25.081] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:26:0) after 1m0s
[09:06:25.106] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:26:0)
[09:06:25.106] Timer tick, asking Buildbucket for the build status
[09:06:25.173] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:25.173] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:27:0) after 1m0s
[09:07:25.227] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:27:0)
[09:07:25.227] Timer tick, asking Buildbucket for the build status
[09:07:25.383] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:25.383] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:28:0) after 1m0s
[09:08:25.548] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:28:0)
[09:08:25.548] Timer tick, asking Buildbucket for the build status
[09:08:25.675] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:25.676] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:29:0) after 1m0s
[09:09:25.748] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:29:0)
[09:09:25.748] Timer tick, asking Buildbucket for the build status
[09:09:25.819] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:25.819] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:30:0) after 1m0s
[09:10:25.883] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:30:0)
[09:10:25.883] Timer tick, asking Buildbucket for the build status
[09:10:25.954] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:25.954] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:31:0) after 1m0s
[09:11:26.107] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:31:0)
[09:11:26.107] Timer tick, asking Buildbucket for the build status
[09:11:26.178] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:26.178] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:32:0) after 1m0s
[09:12:26.366] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:32:0)
[09:12:26.366] Timer tick, asking Buildbucket for the build status
[09:12:26.515] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:26.515] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:33:0) after 1m0s
[09:13:26.684] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:33:0)
[09:13:26.684] Timer tick, asking Buildbucket for the build status
[09:13:26.746] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:26.746] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:34:0) after 1m0s
[09:14:27.008] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:34:0)
[09:14:27.008] Timer tick, asking Buildbucket for the build status
[09:14:27.129] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:27.129] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:35:0) after 1m0s
[09:15:27.338] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:35:0)
[09:15:27.338] Timer tick, asking Buildbucket for the build status
[09:15:27.446] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:27.446] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:36:0) after 1m0s
[09:16:27.552] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:36:0)
[09:16:27.552] Timer tick, asking Buildbucket for the build status
[09:16:27.617] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:27.617] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:37:0) after 1m0s
[09:17:27.672] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:37:0)
[09:17:27.672] Timer tick, asking Buildbucket for the build status
[09:17:27.839] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:27.839] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:38:0) after 1m0s
[09:18:27.917] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:38:0)
[09:18:27.917] Timer tick, asking Buildbucket for the build status
[09:18:27.985] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:27.985] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:39:0) after 1m0s
[09:19:28.078] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:39:0)
[09:19:28.078] Timer tick, asking Buildbucket for the build status
[09:19:28.174] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:28.174] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:40:0) after 1m0s
[09:20:28.381] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:40:0)
[09:20:28.381] Timer tick, asking Buildbucket for the build status
[09:20:28.442] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:28.442] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:41:0) after 1m0s
[09:21:28.529] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:41:0)
[09:21:28.529] Timer tick, asking Buildbucket for the build status
[09:21:28.618] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:28.618] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:42:0) after 1m0s
[09:22:28.696] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:42:0)
[09:22:28.696] Timer tick, asking Buildbucket for the build status
[09:22:28.795] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:28.795] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:43:0) after 1m0s
[09:23:28.840] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:43:0)
[09:23:28.840] Timer tick, asking Buildbucket for the build status
[09:23:28.907] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:28.907] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:44:0) after 1m0s
[09:24:29.013] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:44:0)
[09:24:29.013] Timer tick, asking Buildbucket for the build status
[09:24:29.151] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:29.151] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:45:0) after 1m0s
[09:25:29.212] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:45:0)
[09:25:29.212] Timer tick, asking Buildbucket for the build status
[09:25:29.349] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:29.349] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:46:0) after 1m0s
[09:26:29.326] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:46:0)
[09:26:29.326] Timer tick, asking Buildbucket for the build status
[09:26:29.393] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:29.393] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:47:0) after 1m0s
[09:27:29.366] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:47:0)
[09:27:29.366] Timer tick, asking Buildbucket for the build status
[09:27:29.473] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:29.473] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:48:0) after 1m0s
[09:28:29.514] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:48:0)
[09:28:29.514] Timer tick, asking Buildbucket for the build status
[09:28:29.626] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:29.626] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:49:0) after 1m0s
[09:29:29.723] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:49:0)
[09:29:29.723] Timer tick, asking Buildbucket for the build status
[09:29:29.852] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:29.852] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:50:0) after 1m0s
[09:30:29.881] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:50:0)
[09:30:29.881] Timer tick, asking Buildbucket for the build status
[09:30:30.043] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:30.043] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:51:0) after 1m0s
[09:31:30.090] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:51:0)
[09:31:30.090] Timer tick, asking Buildbucket for the build status
[09:31:30.154] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:30.154] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:52:0) after 1m0s
[09:32:30.244] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:52:0)
[09:32:30.244] Timer tick, asking Buildbucket for the build status
[09:32:30.374] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:30.374] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:53:0) after 1m0s
[09:33:30.443] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:53:0)
[09:33:30.443] Timer tick, asking Buildbucket for the build status
[09:33:30.503] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:30.503] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:54:0) after 1m0s
[09:34:30.550] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:54:0)
[09:34:30.550] Timer tick, asking Buildbucket for the build status
[09:34:30.622] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:30.622] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:55:0) after 1m0s
[09:35:30.661] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:55:0)
[09:35:30.661] Timer tick, asking Buildbucket for the build status
[09:35:30.803] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:30.803] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:56:0) after 1m0s
[09:36:30.780] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:56:0)
[09:36:30.780] Timer tick, asking Buildbucket for the build status
[09:36:31.242] Build 8856421462421513776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:31.242] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021851524407053312:57:0) after 1m0s
[09:36:33.463] Received PubSub notification, asking Buildbucket for the build status
[09:36:33.586] Build 8856421462421513776: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:36:33.586] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612258592819627",
"created_by": "project:chromium",
"created_ts": "1612255338227540",
"id": "8856421462421513776",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5a3ad4823597a66129edb847f70a97de6c9d6d1f\"}}",
"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\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"5a3ad4823597a66129edb847f70a97de6c9d6d1f\", \"project\": \"chromium/src\"}], \"$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-72-rtao\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 180192, \"got_angle_revision\": \"7e81056a54691bea83cbea37cb6a94dadb7c4bda\", \"got_dawn_revision\": \"9a2174a37cfdc771a9c7a494e6fb773c541bd941\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"5a3ad4823597a66129edb847f70a97de6c9d6d1f\", \"got_revision_cp\": \"refs/heads/master@{#849507}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"51045556acd0fe0a1b687ef00725a57f0f19a91b\", \"got_v8_revision\": \"079f7c53751ac589cf05880522b3fa2fcb5b5645\", \"got_v8_revision_cp\": \"refs/heads/9.0.109@{#1}\", \"got_webrtc_revision\": \"e7c79fd3d66e0f6c54c027f880a8b1682ebae58a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33124}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.linux:linux-clang-tidy-dbg\", \"tryserver.chromium.linux:linux_chromium_dbg_ng\", \"tryserver.chromium.linux:linux_chromium_compile_dbg_ng\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5a3ad4823597a66129edb847f70a97de6c9d6d1f\", \"swarm_hashes_refs/heads/master(at){#849507}_without_patch\": {\"absl_hardening_tests\": \"2a9288dbd5f92441c1676fe50f5d34fa4a709f58\", \"accessibility_unittests\": \"5738d420f2a32206322c156a471a347a747f3ea8\", \"angle_unittests\": \"6b80a57a9d1f93e2a46cf33f6c7b183f52ea6f5c\", \"app_shell_unittests\": \"9339834a8d7e4e30357059ae3cff0429b0f21fbe\", \"aura_unittests\": \"f596f60665bbc349001fa6345adeeb79a96dda6d\", \"base_unittests\": \"86e0cdcfbbd92c20d4ca3f4226c48df8f6071ef0\", \"base_util_unittests\": \"5f7c76ad3d0c21b07002476d08654e999572c421\", \"blink_common_unittests\": \"711f1beb45623122bff13de395f325f4d33fd640\", \"blink_fuzzer_unittests\": \"78a2cc389df8d5f2313cc0cc42902eff4f286445\", \"blink_heap_unittests\": \"b01422dbde7656a26aa759931a7387c5b533d11e\", \"blink_platform_unittests\": \"f0f84e4d5a0aa8cf619056710f007b50df713475\", \"blink_python_tests\": \"09f7bbc7dac6efcf366e768141657688b1dd7c8d\", \"blink_unittests\": \"190c3f5e5988421e888b5eb84a0d0b1983736431\", \"blink_web_tests\": \"c092436b830d5c79cfda53758ba3f6bf286d5ec5\", \"boringssl_crypto_tests\": \"bf68c90023a28efab6d3161bc9d0e91ea05c949d\", \"boringssl_ssl_tests\": \"e75f1ce77af8afeeaad2202d3dc645eadc10c9cd\", \"browser_tests\": \"bdced6d78c7d397000d83002b24c19236f75c86c\", \"capture_unittests\": \"2f2ab5b9a28de96bf7088de73d62c3315b5be910\", \"cast_unittests\": \"fd706e352876c935384507424ef9b8b58df17085\", \"cc_unittests\": \"da4184e6090652af865410bdb329692d10354432\", \"chrome_app_unittests\": \"16b67617922b62776a812e13982ff755c948c4b4\", \"chromedriver_unittests\": \"1f296facc2be13f2058e234f87eb3b7fcc6930b4\", \"components_browsertests\": \"f77b27b69c78674b454a766eb44b09214c261f77\", \"components_unittests\": \"e0439015d46e2df9c8c3919f429f86c3d50e3411\", \"compositor_unittests\": \"1678851eaf2e2b21101452dce61847121fcaffe5\", \"content_browsertests\": \"1c38e6715bd9dac51d9698ff67e09c78418ebc5c\", \"content_shell_crash_test\": \"e2400bb4b9ebe7b9ac18e87d4503f748e9a287cb\", \"content_unittests\": \"97371965007d0cbcd81b0564877be69d6d614d8f\", \"crashpad_tests\": \"cbd9d3f168ba7637872f5e58c7adffe3e290f461\", \"cronet_tests\": \"4218174a2e3b72a93426ea252d05dc8866098f95\", \"cronet_unittests\": \"22bfae7efa1cfc809aa1eb358695e11a254a6025\", \"crypto_unittests\": \"1a32741b858884e088c1354fba0eabad548293a1\", \"dbus_unittests\": \"6a216a63f05be055848923bf510d828ee3b77fea\", \"device_unittests\": \"caa93ba3f6e3e317286649165b182d55f898e859\", \"display_unittests\": \"9038a12f775b13369b57e5e74c63c516077e9c66\", \"events_unittests\": \"6aeabb2f90e1526854e5d9294103075ab3f0006b\", \"extensions_browsertests\": \"5075f422bdb566cb0b59e27347da246c6767c5ca\", \"extensions_unittests\": \"77e3894037d0c82e5dd7fc01e7efe32b68549704\", \"filesystem_service_unittests\": \"50b8882f587aaf4b91b27314f6350e10df01b642\", \"flatbuffers_unittests\": \"abe7737f42a55ed672142d98ab3b938d8384c781\", \"gcm_unit_tests\": \"4c08ae4fdc47979d5eb0f997d5cdf55ca7a3927e\", \"gfx_unittests\": \"37cb9c0f8c4af446c37075463f075a3040ea2435\", \"gin_unittests\": \"c4a3112e9dd6c32ed41390c784a8352335121c8d\", \"google_apis_unittests\": \"79dc8aa278d06ef3f97047f40797a1d58e153316\", \"gpu_unittests\": \"a13664fa28effe9cec25bb2f60c1384b8f7db1ed\", \"grit_python_unittests\": \"0532571aa381198443b89c1ec536d42cf612fc22\", \"gwp_asan_unittests\": \"f414441496951780a5d06508aeb7287019c775d4\", \"headless_browsertests\": \"907fcbaa0ac0038ac59da1a03d37893bdaf6a7b1\", \"headless_unittests\": \"01196afe05b5a9b9d9e05c1f637425e1d8ea9d9b\", \"interactive_ui_tests\": \"83e35960a8ccd90082aecda8e88be814fc2259c8\", \"ipc_tests\": \"486dfbacb30690360c3bb320d6d0d05a67ffd653\", \"jingle_unittests\": \"933d5255ce6b7c56c7e964750c71255cf9cd7505\", \"latency_unittests\": \"f0519db47df2aa9a5927ec02dc074dd2ef882753\", \"libjingle_xmpp_unittests\": \"dea17b6d468eb7259478d491c43cddbccf98ef07\", \"liburlpattern_unittests\": \"e71cc43860992be9cbff44c76584633bdbc8d2f9\", \"media_blink_unittests\": \"0d83b486884188e98f9a9802d2fcedb4913290a5\", \"media_unittests\": \"077b5e2b90e2902266989414a3c2225a19782e27\", \"message_center_unittests\": \"6a15204edd420a90dd886e0a339160264c08223d\", \"metrics_python_tests\": \"c04b3bda50428747524b68b45a38e8d89d39221c\", \"midi_unittests\": \"de467d61b2f0f114247bafcc6ee2dce3469742f4\", \"mojo_core_unittests\": \"971385c7f2ed71f5ae6d8e36476fdad842ddac40\", \"mojo_unittests\": \"3d678c29b86601c732ac246872c445ac95957006\", \"nacl_helper_nonsfi_unittests\": \"a99e7ba39ec37fd2f19801da2ef1f642e3a9007b\", \"nacl_loader_unittests\": \"fc2f77326e2ba0a7608c549a55e3b2bcaa06bf0e\", \"native_theme_unittests\": \"9c79917e508331391ca0b29a9028031d8bbdc700\", \"net_unittests\": \"31803bb83209b35032c70bfd6207717574bfccbd\", \"openscreen_unittests\": \"926992221b67e2addd1b24122a77be6a9b80529d\", \"pdf_unittests\": \"95d96848138aa72478158bb9ad80359c23aec9ab\", \"perfetto_unittests\": \"28c6ba5e92300f5bbda6c6bbd8d79bb28c909644\", \"ppapi_unittests\": \"2233df1e988c504c8ef57748449867c222c69e9b\", \"printing_unittests\": \"0483de2f410d442b635dc9869b1320462812cc42\", \"remoting_unittests\": \"bfb16ad0ea3e5bf76d7d71f46b339e70fb55a508\", \"sandbox_linux_unittests\": \"68eb2b82ca34f738eac1187e09660b5fe9a57495\", \"service_manager_unittests\": \"7a12ff89267f03854ebf96a0738f9715d2e5c6fd\", \"services_unittests\": \"9e61213919a562cd86c5bd1242ecbb7d75ac91cc\", \"shell_dialogs_unittests\": \"64ee7520a1f69a2008fddbbfb5715c86380fd4c1\", \"skia_unittests\": \"59b907e8f36bc0cbf7d93888beb02ca400438689\", \"snapshot_unittests\": \"9f2ddbf7443d286fee93276ab8d17b7c65cae5e0\", \"sql_unittests\": \"61f8bf01beb2d64611b695df0607d6594ae1eb0b\", \"storage_unittests\": \"497dac11462999f46579746acbeeb48c0459f2e8\", \"sync_integration_tests\": \"f6e674aa8ccfb197d19040663ccd94e67eaf6b71\", \"telemetry_gpu_unittests\": \"ef5b84ab02704b65db2e21d433a7ac854cc1e76a\", \"telemetry_perf_unittests\": \"a516d565af2ca81fbafb855522d445f910244f9d\", \"telemetry_unittests\": \"2006186a745d8773e94f9b44d0a8454b92cc7605\", \"traffic_annotation_auditor_unittests\": \"d20884736126b338b6bffc155cb74c923a4ea2cb\", \"ui_base_unittests\": \"a3f6dc5b9d236653a30b8d396dcd383db346abc9\", \"ui_touch_selection_unittests\": \"8443f09d8d7ff6c34aaeb793110b6f0e8afba722\", \"unit_tests\": \"193d85f45a5bfcc16c99323370959d9cc1fadcd0\", \"url_unittests\": \"ed09388433a41c48d0a980a88c69ae0de223c3be\", \"views_perftests\": \"2e06b62a2ec20be75595d5c62230e5e6da63319d\", \"views_unittests\": \"571b1cba0558d8e2b56392dbd669153bb4d390ff\", \"viz_unittests\": \"510322a6d09b8b8ae6f2024bdbccbf11fc3b6055\", \"vr_common_unittests\": \"0e56438930b393d4cf1db64831a205a0be129507\", \"vr_pixeltests\": \"dfe7d3cf32cd2be33d593de059f7e8c03bd65ffd\", \"webdriver_wpt_tests\": \"3b3e8087c6e9054a2b3345d6f5a8327cf03c42f9\", \"weblayer_browsertests\": \"6b884091c60079f54bae7679d81b371683f478e9\", \"weblayer_unittests\": \"bd012109adf444afde9fe1aa83773376e4629166\", \"wm_unittests\": \"8eb3a86681f21915a7c0c864ef882dd8dfeb451f\", \"wtf_unittests\": \"9854ee63a8710228075954c24d24fed44082dc95\", \"x11_unittests\": \"c1282c53ab77411ef29b9a5285ab0d8e967c8700\", \"xr_browser_tests\": \"97488ff436294558ed19bd2ed36cb002733d48f7\", \"zlib_unittests\": \"8c3e85093fef9a9e49b1217db212c20f40551830\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_9ab1612fdb00db29a8d60b17a842600a8e29ecb37944be685dafbc3ddd01329a_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-72-rtao\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612255340282669",
"status": "COMPLETED",
"status_changed_ts": "1612258593225778",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180192",
"builder:Linux Builder (dbg)",
"buildset:commit/git/5a3ad4823597a66129edb847f70a97de6c9d6d1f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a3ad4823597a66129edb847f70a97de6c9d6d1f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021851524407053312",
"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/8856421462421513776/+/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:517ab9aa14bc4c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612258593230803",
"url": "https://ci.chromium.org/b/8856421462421513776",
"utcnow_ts": "1612258593543437"
}
}
[09:36:33.587] Invocation finished in 54m16.636108053s with status SUCCEEDED