[19:30:10.167] New invocation is queued and will start shortly
[19:30:11.294] Starting the invocation (attempt 1)
[19:30:11.393] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:30:11.393] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:30:11.393] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022535538632538960",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"631b644f06b3ae3fafc5329b2a5423dcd2a9d89e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9022535538632538960",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/631b644f06b3ae3fafc5329b2a5423dcd2a9d89e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/631b644f06b3ae3fafc5329b2a5423dcd2a9d89e",
"gitiles_ref:refs/heads/master"
]
}
[19:30:12.045] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1611603011523544",
"id": "8857105476547168880",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"631b644f06b3ae3fafc5329b2a5423dcd2a9d89e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611603011904659",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44892",
"builder:linux-chromeos-rel",
"buildset:commit/git/631b644f06b3ae3fafc5329b2a5423dcd2a9d89e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/631b644f06b3ae3fafc5329b2a5423dcd2a9d89e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022535538632538960",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8857105476547168880/+/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": "1611603011904821",
"url": "https://ci.chromium.org/b/8857105476547168880",
"utcnow_ts": "1611603012037274"
}
}
[19:30:12.045] Task URL: https://ci.chromium.org/b/8857105476547168880
[19:30:12.045] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:2:0) after 1m0s
[19:30:35.737] Received PubSub notification, asking Buildbucket for the build status
[19:30:36.766] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:12.097] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:2:0)
[19:31:12.097] Timer tick, asking Buildbucket for the build status
[19:31:12.455] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:12.455] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:4:0) after 1m0s
[19:32:12.518] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:4:0)
[19:32:12.518] Timer tick, asking Buildbucket for the build status
[19:32:12.662] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:12.662] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:5:0) after 1m0s
[19:33:12.664] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:5:0)
[19:33:12.664] Timer tick, asking Buildbucket for the build status
[19:33:12.743] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:12.743] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:6:0) after 1m0s
[19:34:12.749] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:6:0)
[19:34:12.749] Timer tick, asking Buildbucket for the build status
[19:34:12.818] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:12.818] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:7:0) after 1m0s
[19:35:12.842] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:7:0)
[19:35:12.842] Timer tick, asking Buildbucket for the build status
[19:35:13.090] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:13.090] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:8:0) after 1m0s
[19:36:13.155] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:8:0)
[19:36:13.155] Timer tick, asking Buildbucket for the build status
[19:36:13.296] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:13.296] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:9:0) after 1m0s
[19:37:13.353] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:9:0)
[19:37:13.353] Timer tick, asking Buildbucket for the build status
[19:37:14.429] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:14.429] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:10:0) after 1m0s
[19:38:14.500] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:10:0)
[19:38:14.500] Timer tick, asking Buildbucket for the build status
[19:38:14.691] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:14.691] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:11:0) after 1m0s
[19:39:14.760] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:11:0)
[19:39:14.760] Timer tick, asking Buildbucket for the build status
[19:39:14.847] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:14.847] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:12:0) after 1m0s
[19:40:14.951] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:12:0)
[19:40:14.951] Timer tick, asking Buildbucket for the build status
[19:40:15.031] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:15.031] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:13:0) after 1m0s
[19:41:15.072] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:13:0)
[19:41:15.072] Timer tick, asking Buildbucket for the build status
[19:41:15.172] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:15.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:14:0) after 1m0s
[19:42:15.248] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:14:0)
[19:42:15.248] Timer tick, asking Buildbucket for the build status
[19:42:15.368] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:15.368] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:15:0) after 1m0s
[19:43:16.358] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:15:0)
[19:43:16.358] Timer tick, asking Buildbucket for the build status
[19:43:16.441] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:16.441] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:16:0) after 1m0s
[19:44:17.458] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:16:0)
[19:44:17.458] Timer tick, asking Buildbucket for the build status
[19:44:17.567] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:17.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:17:0) after 1m0s
[19:45:18.241] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:17:0)
[19:45:18.241] Timer tick, asking Buildbucket for the build status
[19:45:18.566] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:18.566] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:18:0) after 1m0s
[19:46:18.811] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:18:0)
[19:46:18.811] Timer tick, asking Buildbucket for the build status
[19:46:18.882] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:18.882] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:19:0) after 1m0s
[19:47:19.021] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:19:0)
[19:47:19.021] Timer tick, asking Buildbucket for the build status
[19:47:19.161] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:19.161] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:20:0) after 1m0s
[19:48:19.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:20:0)
[19:48:19.726] Timer tick, asking Buildbucket for the build status
[19:48:19.836] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:19.836] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:21:0) after 1m0s
[19:49:20.054] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:21:0)
[19:49:20.054] Timer tick, asking Buildbucket for the build status
[19:49:20.143] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:20.143] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:22:0) after 1m0s
[19:50:20.273] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:22:0)
[19:50:20.273] Timer tick, asking Buildbucket for the build status
[19:50:20.412] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:20.412] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:23:0) after 1m0s
[19:51:20.832] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:23:0)
[19:51:20.832] Timer tick, asking Buildbucket for the build status
[19:51:20.960] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:20.960] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:24:0) after 1m0s
[19:52:21.360] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:24:0)
[19:52:21.360] Timer tick, asking Buildbucket for the build status
[19:52:21.459] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:21.459] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:25:0) after 1m0s
[19:53:22.015] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:25:0)
[19:53:22.015] Timer tick, asking Buildbucket for the build status
[19:53:22.296] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:22.296] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:26:0) after 1m0s
[19:54:22.421] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:26:0)
[19:54:22.421] Timer tick, asking Buildbucket for the build status
[19:54:22.670] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:22.670] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:27:0) after 1m0s
[19:55:22.782] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:27:0)
[19:55:22.782] Timer tick, asking Buildbucket for the build status
[19:55:22.910] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:22.910] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:28:0) after 1m0s
[19:56:23.382] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:28:0)
[19:56:23.382] Timer tick, asking Buildbucket for the build status
[19:56:23.469] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:23.469] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:29:0) after 1m0s
[19:57:23.513] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:29:0)
[19:57:23.513] Timer tick, asking Buildbucket for the build status
[19:57:23.578] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:23.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:30:0) after 1m0s
[19:58:23.659] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:30:0)
[19:58:23.659] Timer tick, asking Buildbucket for the build status
[19:58:23.787] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:23.787] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:31:0) after 1m0s
[19:59:23.827] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:31:0)
[19:59:23.827] Timer tick, asking Buildbucket for the build status
[19:59:23.901] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:23.901] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:32:0) after 1m0s
[20:00:24.457] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:32:0)
[20:00:24.458] Timer tick, asking Buildbucket for the build status
[20:00:26.286] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:26.286] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:33:0) after 1m0s
[20:01:26.715] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:33:0)
[20:01:26.715] Timer tick, asking Buildbucket for the build status
[20:01:26.927] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:26.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:34:0) after 1m0s
[20:02:26.955] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:34:0)
[20:02:26.955] Timer tick, asking Buildbucket for the build status
[20:02:27.032] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:27.032] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:35:0) after 1m0s
[20:03:27.350] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:35:0)
[20:03:27.350] Timer tick, asking Buildbucket for the build status
[20:03:27.490] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:27.490] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:36:0) after 1m0s
[20:04:27.587] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:36:0)
[20:04:27.587] Timer tick, asking Buildbucket for the build status
[20:04:27.708] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:27.708] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:37:0) after 1m0s
[20:05:27.825] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:37:0)
[20:05:27.825] Timer tick, asking Buildbucket for the build status
[20:05:28.425] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:28.425] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:38:0) after 1m0s
[20:06:28.488] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:38:0)
[20:06:28.488] Timer tick, asking Buildbucket for the build status
[20:06:28.630] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:28.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:39:0) after 1m0s
[20:07:28.686] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:39:0)
[20:07:28.686] Timer tick, asking Buildbucket for the build status
[20:07:28.812] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:28.812] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:40:0) after 1m0s
[20:08:28.853] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:40:0)
[20:08:28.853] Timer tick, asking Buildbucket for the build status
[20:08:29.060] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:29.060] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:41:0) after 1m0s
[20:09:29.205] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:41:0)
[20:09:29.205] Timer tick, asking Buildbucket for the build status
[20:09:29.349] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:29.349] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:42:0) after 1m0s
[20:10:29.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:42:0)
[20:10:29.570] Timer tick, asking Buildbucket for the build status
[20:10:29.718] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:29.718] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:43:0) after 1m0s
[20:11:29.910] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:43:0)
[20:11:29.910] Timer tick, asking Buildbucket for the build status
[20:11:30.093] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:30.093] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:44:0) after 1m0s
[20:12:30.127] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:44:0)
[20:12:30.127] Timer tick, asking Buildbucket for the build status
[20:12:30.225] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:30.225] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:45:0) after 1m0s
[20:13:30.314] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:45:0)
[20:13:30.314] Timer tick, asking Buildbucket for the build status
[20:13:30.418] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:30.418] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:46:0) after 1m0s
[20:14:30.418] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:46:0)
[20:14:30.418] Timer tick, asking Buildbucket for the build status
[20:14:30.699] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:30.699] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:47:0) after 1m0s
[20:15:30.818] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:47:0)
[20:15:30.818] Timer tick, asking Buildbucket for the build status
[20:15:30.956] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:30.956] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:48:0) after 1m0s
[20:16:30.980] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:48:0)
[20:16:30.980] Timer tick, asking Buildbucket for the build status
[20:16:31.238] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:31.238] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:49:0) after 1m0s
[20:17:31.239] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:49:0)
[20:17:31.239] Timer tick, asking Buildbucket for the build status
[20:17:31.538] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:31.538] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:50:0) after 1m0s
[20:18:31.572] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:50:0)
[20:18:31.573] Timer tick, asking Buildbucket for the build status
[20:18:31.694] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:31.694] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:51:0) after 1m0s
[20:19:31.752] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:51:0)
[20:19:31.752] Timer tick, asking Buildbucket for the build status
[20:19:32.164] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:32.164] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:52:0) after 1m0s
[20:20:32.244] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:52:0)
[20:20:32.244] Timer tick, asking Buildbucket for the build status
[20:20:32.804] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:32.804] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:53:0) after 1m0s
[20:21:32.800] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:53:0)
[20:21:32.800] Timer tick, asking Buildbucket for the build status
[20:21:32.965] Build 8857105476547168880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:32.965] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022535538632538960:54:0) after 1m0s
[20:22:08.162] Received PubSub notification, asking Buildbucket for the build status
[20:22:08.251] Build 8857105476547168880: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[20:22:08.251] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1611606127619909",
"created_by": "project:chromium",
"created_ts": "1611603011523544",
"failure_reason": "BUILD_FAILURE",
"id": "8857105476547168880",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"631b644f06b3ae3fafc5329b2a5423dcd2a9d89e\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- TotalInputDelayIntegrationTest.MultipleInputEvents\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"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\": \"631b644f06b3ae3fafc5329b2a5423dcd2a9d89e\", \"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-207-xtuo\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44892, \"got_angle_revision\": \"3623868e656d0685f0ec3f7d13a2e9c1e348ba53\", \"got_dawn_revision\": \"c00276b2029b85b0cca43ced05173993beaf43e7\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"631b644f06b3ae3fafc5329b2a5423dcd2a9d89e\", \"got_revision_cp\": \"refs/heads/master@{#846828}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"413f7cb17c77c95e4cc3ba141f3c0f049ac2ecb2\", \"got_v8_revision\": \"07aeb34c380e82877710ad10e53596c41c49abd3\", \"got_v8_revision_cp\": \"refs/heads/9.0.61@{#1}\", \"got_webrtc_revision\": \"ded6636cf43904448ee926d1f2b4352c8a957ca6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33068}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-inverse-fieldtrials-fyi-rel\", \"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"631b644f06b3ae3fafc5329b2a5423dcd2a9d89e\", \"swarm_hashes_refs/heads/master(at){#846828}_without_patch\": {\"absl_hardening_tests\": \"96593f3862d26d163aafe9539849b76ad196c80e\", \"accessibility_unittests\": \"6b6187296a9ebbfb612d976ca47250079f35676a\", \"angle_unittests\": \"e34f54a055f0bc748adf488019ba3d3b8984df22\", \"app_list_unittests\": \"338ce38fec7539d6f78f49b4c27469ba57334af4\", \"app_shell_unittests\": \"4a542aeea4c0f148f9b01c91863cfc8e50fb4709\", \"ash_unittests\": \"7fd77711895654f32fde40ed8b0d118b1a8588c8\", \"aura_unittests\": \"50cfcf9c1bc1c86bfcfc67ca94c102af9785d120\", \"base_unittests\": \"7b50cef5a86f812ab9e66256b2ecfa503bf097b1\", \"base_util_unittests\": \"a26a9bbd2890d76a4a2ac3c6ada67dbab1b5d341\", \"blink_common_unittests\": \"19a65251b8530761b7d5cf9af99e2bdb1b7ed1ee\", \"blink_fuzzer_unittests\": \"42d9556e4edda7f5e37058734efa5e8e89707111\", \"blink_heap_unittests\": \"135fc3cc9c4ca4f3c7d3015822b5c5405d3404b8\", \"blink_platform_unittests\": \"e3d6951adb433e16cd1fe9693de6ce54542dde99\", \"blink_unittests\": \"874897415aec7c5ff462e0f29ed9ed4b92fcd335\", \"boringssl_crypto_tests\": \"433b055cbbb6f7a6071874dcf6f0a8d1c7ad9d64\", \"boringssl_ssl_tests\": \"b39f93e4797cf80f0a5e3d5d8ecb0a9bc7d39636\", \"browser_tests\": \"a58ba268193d083fc835f83854e7bbb3592f87c9\", \"capture_unittests\": \"1ab3411ba69f5bafb9271f073528f236526aea22\", \"cast_unittests\": \"912e718d6bfdf128ba60613ee72c54e3b4b7ea1a\", \"cc_unittests\": \"2833e5780a7d6dd71df4b7de95bcc8d7acb5e99a\", \"chrome_app_unittests\": \"e7e7af1e8f511e3b3badcd1e445495fc81a7437c\", \"chromedriver_unittests\": \"3c8be9c48618a4e486f44e766502c1573adee7ac\", \"chromeos_components_unittests\": \"4a3c9197d8ac190d699cc50197082ee39f2c47c4\", \"chromeos_unittests\": \"2636fdd322755701b733c1931388563c059e49f7\", \"components_browsertests\": \"dd30c9d0fa46e8688a95e97d0e2cdf313a43ff90\", \"components_unittests\": \"fa16c862d6d38a0fcd1daa862e52b376e84c8a3a\", \"compositor_unittests\": \"ee79bae925f87b4bc797b47ad50519824471609d\", \"content_browsertests\": \"d36f0e8cb30c2059e6b10a853e97aaada6585156\", \"content_unittests\": \"78d6e6e9bf5d07924f8d817d5b1ecbae8fcda077\", \"crashpad_tests\": \"506c39980e06dd000967b2a8ee061e2527bcbfbb\", \"crypto_unittests\": \"f080103480aa6cffa5f199ce2028d31b4cddea67\", \"dbus_unittests\": \"24d7bed5f77ebd1824d04bc573b5f8f141e2e2e0\", \"device_unittests\": \"334e5ed821d5fa464dab1410b904853170899fd3\", \"display_unittests\": \"72dbba05b5f12d46ea1c66f0d595d3429cc570cf\", \"events_unittests\": \"457732f539251da64234f59633c316cf111b1c3b\", \"exo_unittests\": \"612131ae8594b7c585ddcfee92e0367abed08722\", \"extensions_browsertests\": \"ad9cec01c6d478ad9a8b893c82f00eedfebce4a0\", \"extensions_unittests\": \"1571362471ff2917afe881e04ace5fbbcbcee2e8\", \"filesystem_service_unittests\": \"f5656a6dda3772344c0fc5559c027784a74dc61b\", \"gcm_unit_tests\": \"001d391e71638ef62ee9657cacfcea418cc60cfe\", \"gfx_unittests\": \"7c7bb75dd394f69ecaababa49ad8e772e71e081e\", \"gin_unittests\": \"9f3079985528fcf8173418bdb243a97fcd6d5931\", \"gl_unittests_ozone\": \"5c475fb747d459ec5fdfab764ef1541cc3067901\", \"google_apis_unittests\": \"e873ebdbafdf481d35f66b21ebdd0235d5dd7e4a\", \"gpu_unittests\": \"8826fb2ade819db0c3054bfc4e93bbff0f66c758\", \"gwp_asan_unittests\": \"ceceaeabb503eabcb1d79a88e1f5627395d49fd2\", \"interactive_ui_tests\": \"4e5d700356c9b5191bde620128b10cffae004c80\", \"ipc_tests\": \"5866ee72ca97f2da528d78a9a780a80b8f41c57f\", \"jingle_unittests\": \"2e56d04340a4ecb6f5315294af901511c316af88\", \"keyboard_unittests\": \"9c8931826b8a53f639149f5e48ad9add25fb3705\", \"latency_unittests\": \"8d3ac8c9258650f41079c3f9dfc18f2510e32037\", \"libjingle_xmpp_unittests\": \"686f2e602b908eae362eac186b6bfdd224b6f7d5\", \"liburlpattern_unittests\": \"242ef21377740dcbf59c4fe4f2ad13b903e5e0b1\", \"media_blink_unittests\": \"9dd40f6d43cc07f3f73ef90edc96f98a58956a70\", \"media_unittests\": \"9481582c634a21465d571aa7c32851f4986cd35d\", \"message_center_unittests\": \"72749b5ac0d414eb9043f69d5ba66b864bbf5259\", \"midi_unittests\": \"3511a78e6cd58177883532a556fb1583ae9e6f01\", \"mojo_core_unittests\": \"ca95b0613eb4070e10e5b52c2992cf30b94d36e0\", \"mojo_unittests\": \"35de53f32a3ecc5864aed68d193bc19a67811766\", \"nacl_helper_nonsfi_unittests\": \"0b8cacbd8e0c8407c1b8a94f88dfb39f63022e49\", \"nacl_loader_unittests\": \"e3239970f13b0c5c6e1f7794628a62301bf49be6\", \"native_theme_unittests\": \"d7851f00a1d5e77775c94132b8984de4c547511e\", \"net_unittests\": \"8d8cb593015188201cb70778ff2bee9b9abad5cd\", \"ozone_gl_unittests\": \"d6031cf4b5d2496ccaa2af3324ad3dea91f0aa1a\", \"ozone_unittests\": \"1b4f74c313ed33b4bd1ce6cb7522f00f9ee44fec\", \"ozone_x11_unittests\": \"a2e0e12524951af1598e6b616234a02f6463810e\", \"pdf_unittests\": \"564a1f6c22d309ab835af3922cfc409b26a69776\", \"perfetto_unittests\": \"52ee783aa9364cf2480a940ecbf8f465b78a0da7\", \"ppapi_unittests\": \"84bbcef1a76daa9e9153c6e4af81e950935215f7\", \"printing_unittests\": \"2c42333b9771dcf6d8fbdfda92a141aff8aeb044\", \"remoting_unittests\": \"388ed8573a101d53b6d019258d4e038a5423d4fd\", \"sandbox_linux_unittests\": \"daf8415fab74362a2a941da2c16d6bb71feed773\", \"service_manager_unittests\": \"66dc92267d2c4ac7a311b9fa3a8ff35027c17555\", \"services_unittests\": \"313f713d935357e6e75d3849087c9badafc6b42e\", \"shell_dialogs_unittests\": \"7a5f1c2db2f06160f730ce468644f501e76a0ae1\", \"shell_encryption_unittests\": \"556420bd0ec7847096df8aa588a9c73387429ccf\", \"skia_unittests\": \"d0653efae7bcccbf3b81a21a84cb86a4ece2ca0d\", \"snapshot_unittests\": \"b4c7cbc430ac62206057b2a069d4ae6ada0a3f46\", \"sql_unittests\": \"4db67cf5b0f031daa0213f18f1e9bc9fe7d66a7f\", \"storage_unittests\": \"2a3ed2839a6e7cdd36a74357fbb2046f8cfaa0b3\", \"sync_integration_tests\": \"ccc95fc50e557262549bae53413cef2b65939852\", \"traffic_annotation_auditor_unittests\": \"efd0214eec72e40514caafc6f8979590e70e60e0\", \"ui_base_unittests\": \"28c87a9cf21afdd44f7fb59d2360a99ad2b57c8c\", \"ui_chromeos_unittests\": \"02d2f4f453a163a6765a7f6d2f5b7def83ea49bd\", \"ui_touch_selection_unittests\": \"498a0f9c671d706a4d4e7be98ebb915c2545813f\", \"unit_tests\": \"679ad310f542cb4463e793738de5defc5c452210\", \"url_unittests\": \"e5279485c6e38630b21be906c4a39b413b4ccbff\", \"usage_time_limit_unittests\": \"0b7e23ae74d428fcbdd29215f638aba6b7a52154\", \"views_unittests\": \"a59d65ebd4b38ba83383062f897181c254a0ee03\", \"viz_unittests\": \"1d763c767f00ff3231275aef3d371693e4a345b1\", \"wayland_client_perftests\": \"b3f2e536b06167945866fdc40b80607c2fbb2f92\", \"wm_unittests\": \"23a9ead3d219875713bd5d850988a643f89e5e2f\", \"wtf_unittests\": \"b304bd3489c2ac2b1b4c70d65c45ad85a3decab0\", \"zlib_unittests\": \"5d776b4d312991aad8f640bb144494101e9b47c4\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_25c22b29ff9525bbfd333bb12e92a9faf5d35886dc9a7dacce03d872c45df5d1_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_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-207-xtuo\"], \"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\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- TotalInputDelayIntegrationTest.MultipleInputEvents\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611603034947048",
"status": "COMPLETED",
"status_changed_ts": "1611603035526889",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44892",
"builder:linux-chromeos-rel",
"buildset:commit/git/631b644f06b3ae3fafc5329b2a5423dcd2a9d89e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/631b644f06b3ae3fafc5329b2a5423dcd2a9d89e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022535538632538960",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8857105476547168880/+/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:5153d7fd75743410",
"user_agent:luci-scheduler"
],
"updated_ts": "1611606127869179",
"url": "https://ci.chromium.org/b/8857105476547168880",
"utcnow_ts": "1611606128239588"
}
}
[20:22:08.251] Invocation finished in 51m58.09993152s with status FAILED