[01:26:59.942] New invocation is queued and will start shortly
[01:27:02.286] Starting the invocation (attempt 1)
[01:27:02.338] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:27:02.338] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:27:02.338] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022331894966429504",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"90da65414121110a3d08a49ee9a69b3137e9a45e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9022331894966429504",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/90da65414121110a3d08a49ee9a69b3137e9a45e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/90da65414121110a3d08a49ee9a69b3137e9a45e",
"gitiles_ref:refs/heads/master"
]
}
[01:27:03.740] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611797222494860",
"id": "8856901831584638272",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"90da65414121110a3d08a49ee9a69b3137e9a45e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611797223551783",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180047",
"builder:Linux Builder (dbg)",
"buildset:commit/git/90da65414121110a3d08a49ee9a69b3137e9a45e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/90da65414121110a3d08a49ee9a69b3137e9a45e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022331894966429504",
"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/8856901831584638272/+/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": "1611797223552007",
"url": "https://ci.chromium.org/b/8856901831584638272",
"utcnow_ts": "1611797223724638"
}
}
[01:27:03.740] Task URL: https://ci.chromium.org/b/8856901831584638272
[01:27:03.740] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:2:0) after 1m0s
[01:28:04.383] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:2:0)
[01:28:04.383] Timer tick, asking Buildbucket for the build status
[01:28:05.668] Build 8856901831584638272: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:28:05.668] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:3:0) after 1m0s
[01:28:17.957] Received PubSub notification, asking Buildbucket for the build status
[01:28:18.575] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:05.804] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:3:0)
[01:29:05.804] Timer tick, asking Buildbucket for the build status
[01:29:06.774] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:06.774] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:5:0) after 1m0s
[01:30:07.126] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:5:0)
[01:30:07.126] Timer tick, asking Buildbucket for the build status
[01:30:07.185] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:07.185] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:6:0) after 1m0s
[01:31:07.811] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:6:0)
[01:31:07.811] Timer tick, asking Buildbucket for the build status
[01:31:08.384] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:08.384] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:7:0) after 1m0s
[01:32:08.487] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:7:0)
[01:32:08.487] Timer tick, asking Buildbucket for the build status
[01:32:08.685] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:08.685] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:8:0) after 1m0s
[01:33:08.760] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:8:0)
[01:33:08.760] Timer tick, asking Buildbucket for the build status
[01:33:08.947] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:08.947] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:9:0) after 1m0s
[01:34:09.059] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:9:0)
[01:34:09.059] Timer tick, asking Buildbucket for the build status
[01:34:09.448] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:09.448] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:10:0) after 1m0s
[01:35:09.468] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:10:0)
[01:35:09.468] Timer tick, asking Buildbucket for the build status
[01:35:09.715] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:09.715] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:11:0) after 1m0s
[01:36:09.762] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:11:0)
[01:36:09.762] Timer tick, asking Buildbucket for the build status
[01:36:09.843] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:09.843] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:12:0) after 1m0s
[01:37:09.882] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:12:0)
[01:37:09.882] Timer tick, asking Buildbucket for the build status
[01:37:10.055] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:10.056] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:13:0) after 1m0s
[01:38:10.063] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:13:0)
[01:38:10.063] Timer tick, asking Buildbucket for the build status
[01:38:10.212] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:10.212] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:14:0) after 1m0s
[01:39:11.101] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:14:0)
[01:39:11.101] Timer tick, asking Buildbucket for the build status
[01:39:11.327] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:11.328] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:15:0) after 1m0s
[01:40:11.539] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:15:0)
[01:40:11.539] Timer tick, asking Buildbucket for the build status
[01:40:11.712] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:11.712] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:16:0) after 1m0s
[01:41:12.206] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:16:0)
[01:41:12.206] Timer tick, asking Buildbucket for the build status
[01:41:12.457] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:12.457] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:17:0) after 1m0s
[01:42:12.458] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:17:0)
[01:42:12.458] Timer tick, asking Buildbucket for the build status
[01:42:12.572] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:12.572] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:18:0) after 1m0s
[01:43:12.618] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:18:0)
[01:43:12.618] Timer tick, asking Buildbucket for the build status
[01:43:12.819] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:12.819] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:19:0) after 1m0s
[01:44:12.830] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:19:0)
[01:44:12.830] Timer tick, asking Buildbucket for the build status
[01:44:13.137] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:13.137] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:20:0) after 1m0s
[01:45:13.224] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:20:0)
[01:45:13.224] Timer tick, asking Buildbucket for the build status
[01:45:14.170] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:14.170] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:21:0) after 1m0s
[01:46:14.320] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:21:0)
[01:46:14.320] Timer tick, asking Buildbucket for the build status
[01:46:14.436] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:14.436] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:22:0) after 1m0s
[01:47:14.463] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:22:0)
[01:47:14.463] Timer tick, asking Buildbucket for the build status
[01:47:14.619] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:14.619] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:23:0) after 1m0s
[01:48:14.632] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:23:0)
[01:48:14.632] Timer tick, asking Buildbucket for the build status
[01:48:14.789] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:14.789] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:24:0) after 1m0s
[01:49:14.851] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:24:0)
[01:49:14.851] Timer tick, asking Buildbucket for the build status
[01:49:14.910] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:14.910] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:25:0) after 1m0s
[01:50:14.944] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:25:0)
[01:50:14.944] Timer tick, asking Buildbucket for the build status
[01:50:15.298] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:15.298] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:26:0) after 1m0s
[01:51:15.329] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:26:0)
[01:51:15.329] Timer tick, asking Buildbucket for the build status
[01:51:15.434] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:15.434] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:27:0) after 1m0s
[01:52:15.438] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:27:0)
[01:52:15.438] Timer tick, asking Buildbucket for the build status
[01:52:15.649] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:15.649] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:28:0) after 1m0s
[01:53:15.732] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:28:0)
[01:53:15.732] Timer tick, asking Buildbucket for the build status
[01:53:15.819] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:15.819] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:29:0) after 1m0s
[01:54:16.003] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:29:0)
[01:54:16.003] Timer tick, asking Buildbucket for the build status
[01:54:16.380] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:16.380] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:30:0) after 1m0s
[01:55:16.464] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:30:0)
[01:55:16.464] Timer tick, asking Buildbucket for the build status
[01:55:16.818] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:16.818] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:31:0) after 1m0s
[01:56:16.831] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:31:0)
[01:56:16.831] Timer tick, asking Buildbucket for the build status
[01:56:17.566] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:17.566] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:32:0) after 1m0s
[01:57:17.699] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:32:0)
[01:57:17.699] Timer tick, asking Buildbucket for the build status
[01:57:17.819] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:17.819] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:33:0) after 1m0s
[01:58:17.866] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:33:0)
[01:58:17.866] Timer tick, asking Buildbucket for the build status
[01:58:17.969] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:17.970] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:34:0) after 1m0s
[01:59:17.992] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:34:0)
[01:59:17.992] Timer tick, asking Buildbucket for the build status
[01:59:18.151] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:18.151] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:35:0) after 1m0s
[02:00:18.174] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:35:0)
[02:00:18.174] Timer tick, asking Buildbucket for the build status
[02:00:18.282] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:18.282] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:36:0) after 1m0s
[02:01:18.333] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:36:0)
[02:01:18.333] Timer tick, asking Buildbucket for the build status
[02:01:18.408] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:18.408] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:37:0) after 1m0s
[02:02:18.479] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:37:0)
[02:02:18.479] Timer tick, asking Buildbucket for the build status
[02:02:18.735] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:18.735] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:38:0) after 1m0s
[02:03:18.718] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:38:0)
[02:03:18.718] Timer tick, asking Buildbucket for the build status
[02:03:18.961] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:18.961] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:39:0) after 1m0s
[02:04:19.003] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:39:0)
[02:04:19.003] Timer tick, asking Buildbucket for the build status
[02:04:19.130] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:19.130] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:40:0) after 1m0s
[02:05:19.627] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:40:0)
[02:05:19.627] Timer tick, asking Buildbucket for the build status
[02:05:20.473] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:20.473] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:41:0) after 1m0s
[02:06:21.063] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:41:0)
[02:06:21.063] Timer tick, asking Buildbucket for the build status
[02:06:22.406] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:22.406] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:42:0) after 1m0s
[02:07:22.448] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:42:0)
[02:07:22.448] Timer tick, asking Buildbucket for the build status
[02:07:22.581] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:22.581] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:43:0) after 1m0s
[02:08:22.683] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:43:0)
[02:08:22.683] Timer tick, asking Buildbucket for the build status
[02:08:22.813] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:22.813] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:44:0) after 1m0s
[02:09:23.017] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:44:0)
[02:09:23.017] Timer tick, asking Buildbucket for the build status
[02:09:23.112] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:23.112] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:45:0) after 1m0s
[02:10:23.176] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:45:0)
[02:10:23.176] Timer tick, asking Buildbucket for the build status
[02:10:23.408] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:23.408] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:46:0) after 1m0s
[02:11:23.723] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:46:0)
[02:11:23.723] Timer tick, asking Buildbucket for the build status
[02:11:23.835] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:23.835] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:47:0) after 1m0s
[02:12:23.954] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:47:0)
[02:12:23.954] Timer tick, asking Buildbucket for the build status
[02:12:24.181] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:24.181] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:48:0) after 1m0s
[02:13:24.282] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:48:0)
[02:13:24.282] Timer tick, asking Buildbucket for the build status
[02:13:24.447] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:24.447] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:49:0) after 1m0s
[02:14:24.834] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:49:0)
[02:14:24.834] Timer tick, asking Buildbucket for the build status
[02:14:24.902] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:24.902] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:50:0) after 1m0s
[02:15:24.999] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:50:0)
[02:15:24.999] Timer tick, asking Buildbucket for the build status
[02:15:25.163] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:25.163] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:51:0) after 1m0s
[02:16:25.231] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:51:0)
[02:16:25.231] Timer tick, asking Buildbucket for the build status
[02:16:25.364] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:25.364] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:52:0) after 1m0s
[02:17:25.386] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:52:0)
[02:17:25.386] Timer tick, asking Buildbucket for the build status
[02:17:25.507] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:25.507] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:53:0) after 1m0s
[02:18:25.542] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:53:0)
[02:18:25.542] Timer tick, asking Buildbucket for the build status
[02:18:25.719] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:25.719] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:54:0) after 1m0s
[02:19:25.993] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:54:0)
[02:19:25.993] Timer tick, asking Buildbucket for the build status
[02:19:26.255] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:26.255] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:55:0) after 1m0s
[02:20:26.311] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:55:0)
[02:20:26.311] Timer tick, asking Buildbucket for the build status
[02:20:26.393] Build 8856901831584638272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:26.393] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022331894966429504:56:0) after 1m0s
[02:21:21.065] Received PubSub notification, asking Buildbucket for the build status
[02:21:21.301] Build 8856901831584638272: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:21:21.301] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611800480542469",
"created_by": "project:chromium",
"created_ts": "1611797222494860",
"id": "8856901831584638272",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"90da65414121110a3d08a49ee9a69b3137e9a45e\"}}",
"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\": \"90da65414121110a3d08a49ee9a69b3137e9a45e\", \"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-9inl\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 180047, \"got_angle_revision\": \"52afb6f17cbd13a290c2798b565067f47d47af86\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"90da65414121110a3d08a49ee9a69b3137e9a45e\", \"got_revision_cp\": \"refs/heads/master@{#847908}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"86f939547fc17da2ab1ed5a4b058bddb162dac9b\", \"got_v8_revision_cp\": \"refs/heads/9.0.87@{#1}\", \"got_webrtc_revision\": \"49dbad021e802197b3c0b966c03b4aa766a5b578\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33080}\", \"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\", \"prev_got_revision\": \"a654c6d38aace24d97e4ca18f820fb97cc9f88c9\", \"prev_got_revision_cp\": \"refs/heads/master@{#847655}\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"90da65414121110a3d08a49ee9a69b3137e9a45e\", \"swarm_hashes_refs/heads/master(at){#847908}_without_patch\": {\"absl_hardening_tests\": \"f0b094e395ced7d5962beed0611d457c8dba847c\", \"accessibility_unittests\": \"2ed693a2548ddbf31ba94c0af69d2f63e9039e21\", \"angle_unittests\": \"63777002b20d748d6261269d96ea3047467039ff\", \"app_shell_unittests\": \"61b84b93def206ac8a5b6a7b8bc9211d8ef2a890\", \"aura_unittests\": \"d11bdb740026d5a1200f3995e9bb77a00990c66e\", \"base_unittests\": \"01af19605f705f437191d0e90c045acc51ab2c3b\", \"base_util_unittests\": \"5ba5d5121ae4699e9087fabb9fa8b0c4caf38c40\", \"blink_common_unittests\": \"3a4f8fba867057702cc8cbfd46b732da9239261f\", \"blink_fuzzer_unittests\": \"40a56349d65b25a443e5bf2d27acccf6b5d9954a\", \"blink_heap_unittests\": \"4f7a1b2ad5bc3d2c1b353fab08b6589201f664a4\", \"blink_platform_unittests\": \"2f2f01958145e17f3e45906b9bbb1f76ba1884b1\", \"blink_python_tests\": \"c78138018a0636816f4b85a4c09e32363ee6f0fc\", \"blink_unittests\": \"e0c3563294577bed822ec9ae300006cd7614aaea\", \"blink_web_tests\": \"828b43b61970338054831c430b5edd55e07a2b26\", \"boringssl_crypto_tests\": \"f6888fb06cd2d69932838f4f4b581b4e0284dd96\", \"boringssl_ssl_tests\": \"70e6088db71d5e34e27a8369458b61eb104ff05e\", \"browser_tests\": \"149a896c698d200e72ea3e9b23b63646d49a479a\", \"capture_unittests\": \"e672248672012aa10275dba27e0a653b4ac08656\", \"cast_unittests\": \"44758b0e5adc76cca9c1f52ef0ffa00eaf7113b1\", \"cc_unittests\": \"6cb7a4faec42e30c801b84d8abb1df7b7ae797fb\", \"chrome_app_unittests\": \"7c02d2a00891820c6a78b366518e4c3aa981683e\", \"chromedriver_unittests\": \"0a97b1aaaaf37e85ff525d99de02de7fd56a26d2\", \"components_browsertests\": \"4e0519f0e714171274f0ef55c2f9a20df4d34a85\", \"components_unittests\": \"91ec2b8d22c5d1d3a34ae4b460d266cdd34463ee\", \"compositor_unittests\": \"72572c193bc061665ea7bef646fb65fdd7cc0e29\", \"content_browsertests\": \"8cd60780c16b83302f1b50b3fd6e3f02303ce1e8\", \"content_shell_crash_test\": \"dac8a6b903d2ae5a9ba77d4a0a413c337eb0cde3\", \"content_unittests\": \"f367860915ad5459f147a74f5f4d7b945d35a834\", \"crashpad_tests\": \"898ce942f0629ecfc06dfd79b439a06a994d5dc0\", \"cronet_tests\": \"625f7e26d7838f162798058d139526495866a872\", \"cronet_unittests\": \"5f46a224d84e61d1360db69d74e566ee51d3314a\", \"crypto_unittests\": \"93b5951f24a1157f6a2d852c06d1785d392fbc6b\", \"dbus_unittests\": \"2d998e8eb520d05e7dbaa80d62791aacfc7b9ac6\", \"device_unittests\": \"74751a4481bdd0681eff9578974b9684510c449b\", \"display_unittests\": \"4b476fdb913e2527d742577216764cd6c469609b\", \"events_unittests\": \"f6c3f7f790fd7f308c59d13751e3c7dcab7b9e63\", \"extensions_browsertests\": \"ffbe13f7ffc525a193518d1d7344a37241e2a1a8\", \"extensions_unittests\": \"c3274bd6986bcf585e8bec7b638726fbaff335de\", \"filesystem_service_unittests\": \"0d5cd70c279e1e0fe3e367e6c5ef11fc8ba6c8b9\", \"flatbuffers_unittests\": \"bc94b5892d7faa8d4260fa7d28e336b3c313737d\", \"gcm_unit_tests\": \"9138cd57f39fe096efbda1932dc9ff2c3ca469c9\", \"gfx_unittests\": \"76ece2204015583bab43b2d7541c4aabab54c2bc\", \"gin_unittests\": \"6f095bc6651430e9ed37d1c05dbba46d2b8c0853\", \"google_apis_unittests\": \"7afa3243774f9d57144c2caab83dc46856cfa79c\", \"gpu_unittests\": \"81f49daf4a03c6c980544af18553ba13ee6c274e\", \"grit_python_unittests\": \"b0890b035c0d25a1641db23ae376539c3f64cf64\", \"gwp_asan_unittests\": \"ab78360873871ddb38549bc03a706ea43b08727b\", \"headless_browsertests\": \"602aee1c18351756dbd23ea96d17a0342162f05c\", \"headless_unittests\": \"099520985cfa53b9e5fea84fcc6f245b3a58e5e9\", \"interactive_ui_tests\": \"ac08d70f241e47e55e22be7e771518636b5018f2\", \"ipc_tests\": \"d595f17f72d5295c870e26509e8f11531a6bba08\", \"jingle_unittests\": \"6037284911c7f18c9c15b418fe53e2bb764bda93\", \"latency_unittests\": \"3c180f2eb1aef8b5bf9752f39a2a3dcce236ce85\", \"libjingle_xmpp_unittests\": \"c94c717087697f9306dee5ca399ace6f96fb842f\", \"liburlpattern_unittests\": \"cb4edb74f7583cd527b269dd06350a7ed1ca2d11\", \"media_blink_unittests\": \"83c1e5956f15dfa718e1b9896d96b1a6228ef904\", \"media_unittests\": \"a167dc3daad60769691a0448a26aeff7d0548a4d\", \"message_center_unittests\": \"d9a17f35d4f2b85a7353a5a49431f07bdd31247c\", \"metrics_python_tests\": \"2b75606d45448dbdc96ddfc01f7c5cf7003477fa\", \"midi_unittests\": \"0b179414dfe81a8532fa18c6246ad7781da8734d\", \"mojo_core_unittests\": \"b326e81013f05a9b4f6d5ecd4dac1e55998aaf82\", \"mojo_unittests\": \"1552f8124f9db5845c20e24d516344d22231b289\", \"nacl_helper_nonsfi_unittests\": \"3dce212032fa5ce9baa2d06f9466a3b94e325089\", \"nacl_loader_unittests\": \"24495518d28d4d0b2c45cccc1dc16b2edaf09bcf\", \"native_theme_unittests\": \"a0412a816dfdb654dfff59d45444a6156c7f197d\", \"net_unittests\": \"4c8ae5087218d7af0309e6f4da315f27663ed88c\", \"openscreen_unittests\": \"147ed0c6cdbdc4dd32114a380bc1597b5a110f07\", \"pdf_unittests\": \"ab5378b4d72b0fb49ab4466859c89a969442fc17\", \"perfetto_unittests\": \"c39df60a3af0486d7a934fe05b929631361dc3e8\", \"ppapi_unittests\": \"d6473859a89677c01fc0c7562d9f72b515c9041a\", \"printing_unittests\": \"2597bbd39efdcd61d0a7ea13c91e2513f9594913\", \"remoting_unittests\": \"7789439d00d715d688305949037b4ca84a823c38\", \"sandbox_linux_unittests\": \"6003945f7dcd1d6529fee07e2756c6fb602adfb5\", \"service_manager_unittests\": \"d1387488fcc4627c7e95e91222a38b0af954e8c6\", \"services_unittests\": \"6930df3fd4588e09185c0e22107fa01eb30ddf0f\", \"shell_dialogs_unittests\": \"66858ec7afc0f20a29a6062fc32ad911ace1aa6d\", \"skia_unittests\": \"792f709ea5dad312169d2c2df6e2e6f820751389\", \"snapshot_unittests\": \"e27a6cbac7c8db74f49b08bdbc00887048f315db\", \"sql_unittests\": \"b7e01cac03d9f5f5a4173ac56a3d046553e7b257\", \"storage_unittests\": \"83c26c6e8f09c4278d369a2b4c1d58515896d694\", \"sync_integration_tests\": \"27f50a24fc31ef1c7a2fb8f67815cca3b08abccc\", \"telemetry_gpu_unittests\": \"3846051b6dc7155f41f3d450757ae716ce13bf44\", \"telemetry_perf_unittests\": \"49be0dc6512e3263b647baa479b878060e06ee3f\", \"telemetry_unittests\": \"a2028896337613ea3c6980c1d78f6bc45482e5e7\", \"traffic_annotation_auditor_unittests\": \"c9427ddfe6e4486fc2ba2f31d61b64102af6c5f1\", \"ui_base_unittests\": \"f5f99722c9f64aec77922b761f2614ea1113a00f\", \"ui_touch_selection_unittests\": \"291fe15589411d909a109c8ee1448856fae9fc2c\", \"unit_tests\": \"3de11d8dffb574eef5a907c15e510e41c4547853\", \"url_unittests\": \"72f59be33d37d754f8f39b89b9f8c3b0582ba1c2\", \"views_perftests\": \"ef5d6c6941636f472932b43f9f3c11b5a5ff2c89\", \"views_unittests\": \"ef279be714f4194595692e92022029e2739b6054\", \"viz_unittests\": \"47c3577754d9d6bc5c6e0f90b5a1cdf93b89abd0\", \"vr_common_unittests\": \"e6e8cce92fa991c67a88f564358f99569cc33604\", \"vr_pixeltests\": \"c30bceb3b1fac70484a24d5fc33f648f18df07a7\", \"webdriver_wpt_tests\": \"4b42e60da31dde4c813f267dc3db13cd74c3ff3f\", \"weblayer_browsertests\": \"de3a73c9d5e6e7fb27c8cb35fc62747a9e9b82da\", \"weblayer_unittests\": \"f0d440ce67a8765f01732891e3a7e193ca2f3d63\", \"wm_unittests\": \"1e74c2fb3b66795c9f891051e0ece5d2fc19c4bf\", \"wtf_unittests\": \"78158c7d1af532bcf561911c951311cf35adbcb7\", \"x11_unittests\": \"e43a824f8850e940849ef72b27f163137cb64d6d\", \"xr_browser_tests\": \"debb82e54ff0862fabe9ae53f13fa37bae66380d\", \"zlib_unittests\": \"ccaac3d9c888884a475076027f56b64440bc8cec\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1565bd2e801a59db86c3cb29ff80b2e229b1f19a08963dbe4c2b49186438622b_v2\", \"builder_17616f83fd632132c65ea3dd60ce01b78f71f01fa76c3e3446f88a64858a3864_v2\", \"builder_746f25208a125120dfc7ef61e5ef35531bce9c0f7be870eb0811bf6f56bb01d6_v2\", \"builder_d99b56d6fc6fd15316497c0509dbc8d437110174a09b554c19aeca35e1687cdb_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"builder_fa0031875b7f2f11c46443055ab400bfaeb07a1d2a142b58c631314016c04e0a_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-9inl\"], \"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\": [\"5672-4713de1\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611797296924452",
"status": "COMPLETED",
"status_changed_ts": "1611800480817559",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180047",
"builder:Linux Builder (dbg)",
"buildset:commit/git/90da65414121110a3d08a49ee9a69b3137e9a45e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/90da65414121110a3d08a49ee9a69b3137e9a45e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022331894966429504",
"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/8856901831584638272/+/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:515f6b61e8744810",
"user_agent:luci-scheduler"
],
"updated_ts": "1611800480822343",
"url": "https://ci.chromium.org/b/8856901831584638272",
"utcnow_ts": "1611800481287676"
}
}
[02:21:21.301] Invocation finished in 54m21.416494203s with status SUCCEEDED