[06:54:57.139] New invocation is queued and will start shortly
[06:54:58.240] Starting the invocation (attempt 1)
[06:54:58.275] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:54:58.278] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:54:58.278] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022220664921744880",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0c6211fe862e102f95b48f54715f69d066a2c497\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9022220664921744880",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/0c6211fe862e102f95b48f54715f69d066a2c497",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0c6211fe862e102f95b48f54715f69d066a2c497",
"gitiles_ref:refs/heads/master"
]
}
[06:54:58.942] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611903298361378",
"id": "8856790602976082320",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0c6211fe862e102f95b48f54715f69d066a2c497\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611903298789851",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22394",
"builder:linux-chromeos-dbg",
"buildset:commit/git/0c6211fe862e102f95b48f54715f69d066a2c497",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0c6211fe862e102f95b48f54715f69d066a2c497",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022220664921744880",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856790602976082320/+/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": "1611903298790021",
"url": "https://ci.chromium.org/b/8856790602976082320",
"utcnow_ts": "1611903298935324"
}
}
[06:54:58.942] Task URL: https://ci.chromium.org/b/8856790602976082320
[06:54:58.942] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:2:0) after 1m0s
[06:55:33.341] Received PubSub notification, asking Buildbucket for the build status
[06:55:33.421] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:59.032] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:2:0)
[06:55:59.032] Timer tick, asking Buildbucket for the build status
[06:55:59.313] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:59.313] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:4:0) after 1m0s
[06:56:59.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:4:0)
[06:56:59.332] Timer tick, asking Buildbucket for the build status
[06:56:59.431] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:59.431] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:5:0) after 1m0s
[06:57:59.519] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:5:0)
[06:57:59.519] Timer tick, asking Buildbucket for the build status
[06:57:59.571] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:59.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:6:0) after 1m0s
[06:58:59.619] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:6:0)
[06:58:59.619] Timer tick, asking Buildbucket for the build status
[06:58:59.754] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:59.754] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:7:0) after 1m0s
[07:00:00.056] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:7:0)
[07:00:00.056] Timer tick, asking Buildbucket for the build status
[07:00:00.655] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:00.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:8:0) after 1m0s
[07:01:00.924] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:8:0)
[07:01:00.924] Timer tick, asking Buildbucket for the build status
[07:01:01.040] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:01.040] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:9:0) after 1m0s
[07:02:01.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:9:0)
[07:02:01.108] Timer tick, asking Buildbucket for the build status
[07:02:01.233] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:01.233] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:10:0) after 1m0s
[07:03:01.384] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:10:0)
[07:03:01.384] Timer tick, asking Buildbucket for the build status
[07:03:01.775] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:01.775] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:11:0) after 1m0s
[07:04:01.910] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:11:0)
[07:04:01.910] Timer tick, asking Buildbucket for the build status
[07:04:02.192] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:02.192] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:12:0) after 1m0s
[07:05:02.294] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:12:0)
[07:05:02.294] Timer tick, asking Buildbucket for the build status
[07:05:02.361] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:02.361] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:13:0) after 1m0s
[07:06:02.440] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:13:0)
[07:06:02.440] Timer tick, asking Buildbucket for the build status
[07:06:03.684] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:03.684] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:14:0) after 1m0s
[07:07:03.800] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:14:0)
[07:07:03.800] Timer tick, asking Buildbucket for the build status
[07:07:04.026] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:04.026] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:15:0) after 1m0s
[07:08:04.100] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:15:0)
[07:08:04.100] Timer tick, asking Buildbucket for the build status
[07:08:04.262] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:04.262] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:16:0) after 1m0s
[07:09:04.328] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:16:0)
[07:09:04.328] Timer tick, asking Buildbucket for the build status
[07:09:04.387] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:04.387] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:17:0) after 1m0s
[07:10:04.493] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:17:0)
[07:10:04.493] Timer tick, asking Buildbucket for the build status
[07:10:04.841] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:04.841] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:18:0) after 1m0s
[07:11:05.184] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:18:0)
[07:11:05.184] Timer tick, asking Buildbucket for the build status
[07:11:05.517] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:05.517] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:19:0) after 1m0s
[07:12:05.624] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:19:0)
[07:12:05.624] Timer tick, asking Buildbucket for the build status
[07:12:05.706] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:05.706] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:20:0) after 1m0s
[07:13:05.755] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:20:0)
[07:13:05.755] Timer tick, asking Buildbucket for the build status
[07:13:05.993] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:05.993] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:21:0) after 1m0s
[07:14:06.045] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:21:0)
[07:14:06.045] Timer tick, asking Buildbucket for the build status
[07:14:06.114] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:06.114] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:22:0) after 1m0s
[07:15:06.255] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:22:0)
[07:15:06.255] Timer tick, asking Buildbucket for the build status
[07:15:06.351] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:06.351] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:23:0) after 1m0s
[07:16:06.426] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:23:0)
[07:16:06.426] Timer tick, asking Buildbucket for the build status
[07:16:06.619] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:06.619] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:24:0) after 1m0s
[07:17:06.690] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:24:0)
[07:17:06.690] Timer tick, asking Buildbucket for the build status
[07:17:07.036] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:07.036] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:25:0) after 1m0s
[07:18:07.102] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:25:0)
[07:18:07.102] Timer tick, asking Buildbucket for the build status
[07:18:07.225] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:07.225] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:26:0) after 1m0s
[07:19:07.256] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:26:0)
[07:19:07.256] Timer tick, asking Buildbucket for the build status
[07:19:07.357] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:07.357] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:27:0) after 1m0s
[07:20:07.389] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:27:0)
[07:20:07.389] Timer tick, asking Buildbucket for the build status
[07:20:07.570] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:07.570] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:28:0) after 1m0s
[07:21:07.648] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:28:0)
[07:21:07.648] Timer tick, asking Buildbucket for the build status
[07:21:07.713] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:07.713] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:29:0) after 1m0s
[07:22:07.717] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:29:0)
[07:22:07.717] Timer tick, asking Buildbucket for the build status
[07:22:07.816] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:07.816] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:30:0) after 1m0s
[07:23:07.842] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:30:0)
[07:23:07.842] Timer tick, asking Buildbucket for the build status
[07:23:07.931] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:07.931] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:31:0) after 1m0s
[07:24:07.940] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:31:0)
[07:24:07.940] Timer tick, asking Buildbucket for the build status
[07:24:08.931] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:08.931] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:32:0) after 1m0s
[07:25:08.968] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:32:0)
[07:25:08.968] Timer tick, asking Buildbucket for the build status
[07:25:09.147] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:09.147] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:33:0) after 1m0s
[07:26:09.221] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:33:0)
[07:26:09.221] Timer tick, asking Buildbucket for the build status
[07:26:09.309] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:09.309] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:34:0) after 1m0s
[07:27:09.388] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:34:0)
[07:27:09.388] Timer tick, asking Buildbucket for the build status
[07:27:09.472] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:09.472] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:35:0) after 1m0s
[07:28:09.573] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:35:0)
[07:28:09.573] Timer tick, asking Buildbucket for the build status
[07:28:11.164] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:11.164] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:36:0) after 1m0s
[07:29:11.212] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:36:0)
[07:29:11.212] Timer tick, asking Buildbucket for the build status
[07:29:11.288] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:11.288] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:37:0) after 1m0s
[07:30:11.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:37:0)
[07:30:11.305] Timer tick, asking Buildbucket for the build status
[07:30:11.403] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:11.403] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:38:0) after 1m0s
[07:31:11.423] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:38:0)
[07:31:11.423] Timer tick, asking Buildbucket for the build status
[07:31:11.484] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:11.484] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:39:0) after 1m0s
[07:32:11.486] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:39:0)
[07:32:11.486] Timer tick, asking Buildbucket for the build status
[07:32:11.559] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:11.559] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:40:0) after 1m0s
[07:33:11.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:40:0)
[07:33:11.582] Timer tick, asking Buildbucket for the build status
[07:33:11.908] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:11.908] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:41:0) after 1m0s
[07:34:11.946] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:41:0)
[07:34:11.946] Timer tick, asking Buildbucket for the build status
[07:34:12.297] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:12.297] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:42:0) after 1m0s
[07:35:12.373] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:42:0)
[07:35:12.373] Timer tick, asking Buildbucket for the build status
[07:35:12.698] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:12.698] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:43:0) after 1m0s
[07:36:12.862] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:43:0)
[07:36:12.862] Timer tick, asking Buildbucket for the build status
[07:36:13.167] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:13.167] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:44:0) after 1m0s
[07:37:14.099] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:44:0)
[07:37:14.099] Timer tick, asking Buildbucket for the build status
[07:37:14.316] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:14.316] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:45:0) after 1m0s
[07:38:14.341] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:45:0)
[07:38:14.341] Timer tick, asking Buildbucket for the build status
[07:38:14.453] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:14.453] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:46:0) after 1m0s
[07:39:14.507] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:46:0)
[07:39:14.508] Timer tick, asking Buildbucket for the build status
[07:39:14.799] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:14.799] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:47:0) after 1m0s
[07:40:14.822] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:47:0)
[07:40:14.822] Timer tick, asking Buildbucket for the build status
[07:40:14.933] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:14.933] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:48:0) after 1m0s
[07:41:14.976] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:48:0)
[07:41:14.977] Timer tick, asking Buildbucket for the build status
[07:41:15.083] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:15.084] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:49:0) after 1m0s
[07:42:15.114] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:49:0)
[07:42:15.114] Timer tick, asking Buildbucket for the build status
[07:42:15.226] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:15.226] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:50:0) after 1m0s
[07:43:15.317] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:50:0)
[07:43:15.317] Timer tick, asking Buildbucket for the build status
[07:43:15.391] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:15.391] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:51:0) after 1m0s
[07:44:15.497] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:51:0)
[07:44:15.497] Timer tick, asking Buildbucket for the build status
[07:44:15.634] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:15.634] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:52:0) after 1m0s
[07:45:15.624] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:52:0)
[07:45:15.624] Timer tick, asking Buildbucket for the build status
[07:45:15.766] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:15.766] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:53:0) after 1m0s
[07:46:15.846] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:53:0)
[07:46:15.846] Timer tick, asking Buildbucket for the build status
[07:46:15.901] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:15.901] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:54:0) after 1m0s
[07:47:15.920] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:54:0)
[07:47:15.920] Timer tick, asking Buildbucket for the build status
[07:47:16.228] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:16.228] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:55:0) after 1m0s
[07:48:16.280] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:55:0)
[07:48:16.280] Timer tick, asking Buildbucket for the build status
[07:48:16.705] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:16.705] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:56:0) after 1m0s
[07:49:16.731] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:56:0)
[07:49:16.731] Timer tick, asking Buildbucket for the build status
[07:49:17.250] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:17.250] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:57:0) after 1m0s
[07:50:17.237] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:57:0)
[07:50:17.237] Timer tick, asking Buildbucket for the build status
[07:50:17.604] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:17.604] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:58:0) after 1m0s
[07:51:17.686] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:58:0)
[07:51:17.686] Timer tick, asking Buildbucket for the build status
[07:51:18.314] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:18.314] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:59:0) after 1m0s
[07:52:18.308] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:59:0)
[07:52:18.308] Timer tick, asking Buildbucket for the build status
[07:52:18.518] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:18.518] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:60:0) after 1m0s
[07:53:18.561] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:60:0)
[07:53:18.561] Timer tick, asking Buildbucket for the build status
[07:53:18.713] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:18.713] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:61:0) after 1m0s
[07:54:18.738] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:61:0)
[07:54:18.738] Timer tick, asking Buildbucket for the build status
[07:54:18.889] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:18.889] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:62:0) after 1m0s
[07:55:18.951] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:62:0)
[07:55:18.951] Timer tick, asking Buildbucket for the build status
[07:55:19.075] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:19.075] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:63:0) after 1m0s
[07:56:19.132] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:63:0)
[07:56:19.132] Timer tick, asking Buildbucket for the build status
[07:56:19.297] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:19.297] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:64:0) after 1m0s
[07:57:19.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:64:0)
[07:57:19.339] Timer tick, asking Buildbucket for the build status
[07:57:19.413] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:19.413] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:65:0) after 1m0s
[07:58:19.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:65:0)
[07:58:19.735] Timer tick, asking Buildbucket for the build status
[07:58:19.802] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:19.802] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:66:0) after 1m0s
[07:59:19.970] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:66:0)
[07:59:19.970] Timer tick, asking Buildbucket for the build status
[07:59:20.123] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:20.123] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:67:0) after 1m0s
[08:00:20.312] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:67:0)
[08:00:20.312] Timer tick, asking Buildbucket for the build status
[08:00:20.650] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:20.650] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:68:0) after 1m0s
[08:01:21.513] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:68:0)
[08:01:21.513] Timer tick, asking Buildbucket for the build status
[08:01:21.676] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:21.676] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:69:0) after 1m0s
[08:02:21.756] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:69:0)
[08:02:21.756] Timer tick, asking Buildbucket for the build status
[08:02:21.981] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:21.981] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:70:0) after 1m0s
[08:03:22.008] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:70:0)
[08:03:22.008] Timer tick, asking Buildbucket for the build status
[08:03:22.143] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:22.143] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:71:0) after 1m0s
[08:04:22.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:71:0)
[08:04:22.281] Timer tick, asking Buildbucket for the build status
[08:04:22.626] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:22.626] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:72:0) after 1m0s
[08:05:22.733] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:72:0)
[08:05:22.733] Timer tick, asking Buildbucket for the build status
[08:05:22.862] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:22.862] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:73:0) after 1m0s
[08:06:22.925] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:73:0)
[08:06:22.925] Timer tick, asking Buildbucket for the build status
[08:06:23.839] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:23.839] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:74:0) after 1m0s
[08:07:23.877] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:74:0)
[08:07:23.877] Timer tick, asking Buildbucket for the build status
[08:07:23.958] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:23.958] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:75:0) after 1m0s
[08:08:24.054] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:75:0)
[08:08:24.054] Timer tick, asking Buildbucket for the build status
[08:08:24.172] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:24.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:76:0) after 1m0s
[08:09:24.234] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:76:0)
[08:09:24.234] Timer tick, asking Buildbucket for the build status
[08:09:24.498] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:24.498] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:77:0) after 1m0s
[08:10:24.556] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:77:0)
[08:10:24.556] Timer tick, asking Buildbucket for the build status
[08:10:24.678] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:24.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:78:0) after 1m0s
[08:11:24.788] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:78:0)
[08:11:24.788] Timer tick, asking Buildbucket for the build status
[08:11:25.356] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:25.356] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:79:0) after 1m0s
[08:12:25.507] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:79:0)
[08:12:25.507] Timer tick, asking Buildbucket for the build status
[08:12:25.717] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:25.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:80:0) after 1m0s
[08:13:25.772] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:80:0)
[08:13:25.772] Timer tick, asking Buildbucket for the build status
[08:13:25.953] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:25.953] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:81:0) after 1m0s
[08:14:25.999] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:81:0)
[08:14:25.999] Timer tick, asking Buildbucket for the build status
[08:14:26.078] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:26.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:82:0) after 1m0s
[08:15:26.121] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:82:0)
[08:15:26.121] Timer tick, asking Buildbucket for the build status
[08:15:26.251] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:26.251] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:83:0) after 1m0s
[08:16:26.306] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:83:0)
[08:16:26.306] Timer tick, asking Buildbucket for the build status
[08:16:26.373] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:26.373] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:84:0) after 1m0s
[08:17:26.471] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:84:0)
[08:17:26.471] Timer tick, asking Buildbucket for the build status
[08:17:26.548] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:26.549] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:85:0) after 1m0s
[08:18:26.677] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:85:0)
[08:18:26.677] Timer tick, asking Buildbucket for the build status
[08:18:27.108] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:27.108] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:86:0) after 1m0s
[08:19:27.163] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:86:0)
[08:19:27.163] Timer tick, asking Buildbucket for the build status
[08:19:27.241] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:27.241] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:87:0) after 1m0s
[08:20:27.353] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:87:0)
[08:20:27.353] Timer tick, asking Buildbucket for the build status
[08:20:27.499] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:27.499] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:88:0) after 1m0s
[08:21:27.680] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:88:0)
[08:21:27.680] Timer tick, asking Buildbucket for the build status
[08:21:27.760] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:27.760] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:89:0) after 1m0s
[08:22:27.860] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:89:0)
[08:22:27.860] Timer tick, asking Buildbucket for the build status
[08:22:27.965] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:27.965] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:90:0) after 1m0s
[08:23:28.033] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:90:0)
[08:23:28.033] Timer tick, asking Buildbucket for the build status
[08:23:28.167] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:28.167] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:91:0) after 1m0s
[08:24:28.240] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:91:0)
[08:24:28.240] Timer tick, asking Buildbucket for the build status
[08:24:28.371] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:28.371] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:92:0) after 1m0s
[08:25:28.430] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:92:0)
[08:25:28.430] Timer tick, asking Buildbucket for the build status
[08:25:28.532] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:28.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:93:0) after 1m0s
[08:26:28.572] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:93:0)
[08:26:28.572] Timer tick, asking Buildbucket for the build status
[08:26:28.725] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:28.725] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:94:0) after 1m0s
[08:27:28.841] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:94:0)
[08:27:28.841] Timer tick, asking Buildbucket for the build status
[08:27:28.960] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:28.960] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:95:0) after 1m0s
[08:28:29.012] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:95:0)
[08:28:29.012] Timer tick, asking Buildbucket for the build status
[08:28:29.140] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:29.140] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:96:0) after 1m0s
[08:29:29.168] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:96:0)
[08:29:29.168] Timer tick, asking Buildbucket for the build status
[08:29:29.271] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:29.271] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:97:0) after 1m0s
[08:30:29.284] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:97:0)
[08:30:29.284] Timer tick, asking Buildbucket for the build status
[08:30:29.520] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:29.520] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:98:0) after 1m0s
[08:31:29.598] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:98:0)
[08:31:29.598] Timer tick, asking Buildbucket for the build status
[08:31:29.693] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:29.693] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:99:0) after 1m0s
[08:32:29.745] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:99:0)
[08:32:29.745] Timer tick, asking Buildbucket for the build status
[08:32:29.885] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:29.885] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:100:0) after 1m0s
[08:33:29.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:100:0)
[08:33:29.922] Timer tick, asking Buildbucket for the build status
[08:33:29.995] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:29.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:101:0) after 1m0s
[08:34:30.092] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:101:0)
[08:34:30.092] Timer tick, asking Buildbucket for the build status
[08:34:30.223] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:30.223] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:102:0) after 1m0s
[08:35:30.263] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:102:0)
[08:35:30.263] Timer tick, asking Buildbucket for the build status
[08:35:30.427] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:30.427] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:103:0) after 1m0s
[08:36:30.456] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:103:0)
[08:36:30.456] Timer tick, asking Buildbucket for the build status
[08:36:30.537] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:30.537] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:104:0) after 1m0s
[08:37:30.564] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:104:0)
[08:37:30.564] Timer tick, asking Buildbucket for the build status
[08:37:30.811] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:30.811] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:105:0) after 1m0s
[08:38:30.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:105:0)
[08:38:30.878] Timer tick, asking Buildbucket for the build status
[08:38:31.054] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:31.054] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:106:0) after 1m0s
[08:39:31.111] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:106:0)
[08:39:31.111] Timer tick, asking Buildbucket for the build status
[08:39:31.921] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:31.921] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:107:0) after 1m0s
[08:40:31.979] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:107:0)
[08:40:31.979] Timer tick, asking Buildbucket for the build status
[08:40:32.126] Build 8856790602976082320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:32.126] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022220664921744880:108:0) after 1m0s
[08:40:49.799] Received PubSub notification, asking Buildbucket for the build status
[08:40:49.883] Build 8856790602976082320: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[08:40:49.883] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611909647933211",
"created_by": "project:chromium",
"created_ts": "1611903298361378",
"failure_reason": "BUILD_FAILURE",
"id": "8856790602976082320",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0c6211fe862e102f95b48f54715f69d066a2c497\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- ChromeSitePerProcessTestWithVerifiedUserActivation.UserActivationBrowserVerificationSameOriginSite\\n\\n- LoggedInSpokenFeedbackTest.LearnModeHardwareKeys\"}, \"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\": \"0c6211fe862e102f95b48f54715f69d066a2c497\", \"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-east1-d-8-97-jg65\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22394, \"got_angle_revision\": \"e419222d82cff7286ccc368932bf0fbb5d31d9e9\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"0c6211fe862e102f95b48f54715f69d066a2c497\", \"got_revision_cp\": \"refs/heads/master@{#848429}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"89b433d7c3defd04809fba9deb6f8885160044a4\", \"got_v8_revision_cp\": \"refs/heads/9.0.100@{#1}\", \"got_webrtc_revision\": \"54b925cfc2cd7c2f8f83e8e484f281aa87b92dfc\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33090}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-dbg\", \"tryserver.chromium.chromiumos:linux-chromeos-compile-dbg\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0c6211fe862e102f95b48f54715f69d066a2c497\", \"swarm_hashes_refs/heads/master(at){#848429}_without_patch\": {\"absl_hardening_tests\": \"43d9b0306d8b3deca172e8e32efca6b44984d0bf\", \"accessibility_unittests\": \"1d715123fcd15c6457ac5ef3712aba5d1bf0538b\", \"angle_unittests\": \"39b27919d4dd4ef1b6ce5d018e6f310072a221b9\", \"app_list_unittests\": \"3b2b42b02656d38965d5fd40332a79576d3eeb0d\", \"app_shell_unittests\": \"fe4731078b63b175d09c26d28b089c5445a7759c\", \"ash_unittests\": \"74658d45b94677a5807cb842c28d6f8014201e8a\", \"aura_unittests\": \"3f6debcee7bc03aa79e0a404277836a5ad48237c\", \"base_unittests\": \"3827dc55baceb7fd55e67403568307ee1a7c6bf3\", \"base_util_unittests\": \"23b94b053b39cdf71a7c72d37bdbd77def3893b0\", \"blink_common_unittests\": \"542fd18da4c47e98a619995ccbdf99e8bdc8b1a1\", \"blink_fuzzer_unittests\": \"42e0d9d24fa6e3864c1498ff1b5c7faae7c683d7\", \"blink_heap_unittests\": \"87a97c1e4d3526d43f8c2cccae9c4a56916f7e04\", \"blink_platform_unittests\": \"d31e77e07df313874a06aed77a3619ce1d8093d4\", \"blink_unittests\": \"d1429fa31cd8555ae515c5d4f207a5d726f8949c\", \"boringssl_crypto_tests\": \"74b2ae11e00e842ada9ab15b147fd11a656cf3dc\", \"boringssl_ssl_tests\": \"6f548ef768d225a4f0009851254ec08c87c9ac1e\", \"browser_tests\": \"8759cde0c9de0512e6cbfda54c52069b92883f9f\", \"capture_unittests\": \"a5728cd5d6d374e3cc73560026b2ce54081f1df2\", \"cast_unittests\": \"edbb7ccb9449611b078958e3301e1cbf8e6276df\", \"cc_unittests\": \"fa985edeb77fb815c692cd9cbab3f9503c86d7e5\", \"chrome_app_unittests\": \"8a6e54fae6047e6bc3ec9da5f2014249d62ae74b\", \"chromedriver_unittests\": \"0de3554358096f56f44bc171514b39dc697a65e2\", \"chromeos_components_unittests\": \"3491b5a3aa86f6a20d67cfd529b3b08034899081\", \"chromeos_unittests\": \"e9bf3c0b86b7da89f15231b82d166f9fdfbbc281\", \"components_browsertests\": \"8cc6e70178b4e296848dbc97765758937fa52845\", \"components_unittests\": \"1ebdc5c11f4c4ee97c13e60542237094c35ebc90\", \"compositor_unittests\": \"07c2247fd2f1d16454803b307d7d7f817c0e3773\", \"content_browsertests\": \"701960e1fc85c502b4fbb0bec99b4f212f492a4e\", \"content_unittests\": \"207e25233f6f513ac888b87380996dbaab34b1f9\", \"crashpad_tests\": \"fa4a51927ea735a05c8ae2bf2c0765c0fffd65e9\", \"crypto_unittests\": \"63a25943da5f5dff839a673558733e56541baea8\", \"dbus_unittests\": \"9d2a7a8255fc93a02c54d6c11921f645ebb1e0d7\", \"device_unittests\": \"20ed1af75a65322b5d22623e5280ee7d1955bc94\", \"display_unittests\": \"021c90c29d1719a3d397dc8e5abfec3450a646b3\", \"events_unittests\": \"a79279581f25b4bc6a550a975745d0e74f27ea4e\", \"exo_unittests\": \"da2f447b34467554162af05d2d5088a34e7adba4\", \"extensions_browsertests\": \"c02153722f2ae3523bf36a49b079f302d3242269\", \"extensions_unittests\": \"678e6ddc0adc52de82d05e0b25dd8df2005c9b7a\", \"filesystem_service_unittests\": \"c17708993a09d3d9d784fc4ad9f8e810d5e2dd9f\", \"gcm_unit_tests\": \"a9e19bc0b526f311a0eb48e4d2b85d2e83981488\", \"gfx_unittests\": \"5eb6654995f8be667ccd6ba5b476135a629ddbc6\", \"gin_unittests\": \"23605653c77511a646c3b7226b18638c13b9dd77\", \"gl_unittests_ozone\": \"37e1b0c7ab75d6fd0caa74cefb3a4c80bcc935a4\", \"google_apis_unittests\": \"7704e8286f88f8a8957957bf2008a90fbe2c6135\", \"gpu_unittests\": \"f0039d6de3dd203716faf325a1376fc9b662b10c\", \"gwp_asan_unittests\": \"cfdd38f4007b0fed3962ec3af304e12597a56e8b\", \"interactive_ui_tests\": \"93154658a157bdf5c6357027b6fa2dbf16e92f1d\", \"ipc_tests\": \"58d70d448696f969d2cf17900c778d2c02f1d7b9\", \"jingle_unittests\": \"0c90b32ace8f53d9be26e68d653b71c94f8b23d0\", \"keyboard_unittests\": \"aedca788d5df1f10ccee3bd54a44110ca58f2632\", \"latency_unittests\": \"d3d62ac0e0778e7e55491cfdb9d156f021ac7cd2\", \"libjingle_xmpp_unittests\": \"df3b67c6cebf8f96a08f4650b94faaaed5a6c289\", \"liburlpattern_unittests\": \"24ac9ca048f43dbb36215bdc2cb92e4e9517aeb6\", \"media_blink_unittests\": \"39092401379408f4a563eb94a7f9dc555fec8181\", \"media_unittests\": \"8332df06d423e2d0e5fc244186d3f455a8cf1519\", \"message_center_unittests\": \"35390d88693dfe1e377972d78324db6c1cc2132e\", \"midi_unittests\": \"56e5adc49dfeaefa745f366d98bd4eb1179d4ad8\", \"mojo_core_unittests\": \"4d95d02d56b8339b60b500300b37aeadbb76bfef\", \"mojo_unittests\": \"b5330d5fe224879f03b295459048b4a174bf1d09\", \"nacl_helper_nonsfi_unittests\": \"d48acd7c1439eaee5af79d0592e9befce3d6fe8c\", \"nacl_loader_unittests\": \"37058039ac664dae632c39741517ad7e411ea841\", \"native_theme_unittests\": \"a60c49eb36cdbcca0b1b827241a470ca0d7eb895\", \"net_unittests\": \"23e17139a220c88d649c12d6813a16fad1ffb2f3\", \"ozone_gl_unittests\": \"d9ac7f4c991429be97b2da73cae93f8541610516\", \"ozone_unittests\": \"1ebbf465eae6dd3902585eb30ca5ca331f259f78\", \"ozone_x11_unittests\": \"3d8e409ec181a81778686ee6d1af7d5ac90f77ee\", \"pdf_unittests\": \"989397fb152860ea7862e6b2a4e91927e6cedcb4\", \"perfetto_unittests\": \"f3348af54029e7749e10454b0f1169a4463bede8\", \"ppapi_unittests\": \"70da23ad6292052c5277140d99cca8dd42d7c253\", \"printing_unittests\": \"11f5452c12583e791e03f437cf1fd7c2d420feb7\", \"remoting_unittests\": \"f02896709183e74c89cfa04ce8edd9df5cb1c10f\", \"sandbox_linux_unittests\": \"10ef665b2d817e32d9f6c85446bccd4e4ec26c66\", \"service_manager_unittests\": \"4d2ca5d5235edc6cca508a641f70c15b4febc8ba\", \"services_unittests\": \"f9261f80e3edf9d00761cdc3aa2aa1b909b2a3b5\", \"shell_dialogs_unittests\": \"04fa8b5546cfbf2ab45ac45b126d81c0663fcf45\", \"shell_encryption_unittests\": \"7866574b7fa2ade074781da5936da22ffd8aba93\", \"skia_unittests\": \"b18ddd55eede41505d8a1890d8bdf464552884b2\", \"snapshot_unittests\": \"4e28ef1e86bdf1dd804cbec993e2a22b2a8f27be\", \"sql_unittests\": \"ab6418ea924abf4f4a0c24273f574e7254768d6f\", \"storage_unittests\": \"f0ba231691454cc6d2d56613790b281a10dcb18d\", \"sync_integration_tests\": \"f07b32334c8e91ca5dd240d6f94140d7102ca55f\", \"traffic_annotation_auditor_unittests\": \"7fa3fde3916d10c12d8a43ee184f8e0e9765eac5\", \"ui_base_unittests\": \"fcc897d2b9d28766cd6edebaaaf058fdc6022b29\", \"ui_chromeos_unittests\": \"42ae5755bb2b7703f5744764a338566ed4fc3754\", \"ui_touch_selection_unittests\": \"f63261ef14ca892bceda98b83b437e9dee4663d6\", \"unit_tests\": \"4f75caa9ff7f95a7c144c5a67ed2e2ce024b1c52\", \"url_unittests\": \"13139bebee92549bf7d7f2a17cd0284072f12b7d\", \"usage_time_limit_unittests\": \"4b6c4b3f4fce25611a97e6ddb507227a3510360f\", \"views_unittests\": \"9e9831022dac113665dab57ae5e8468f1dacb89f\", \"viz_unittests\": \"c7d711efcc065f4d5ec7e094c9505f53d4d2cd14\", \"wm_unittests\": \"f639082919cdf3c1a88521279ab51ef24b804509\", \"wtf_unittests\": \"b65ed546520b891edf712f7ddc955d8505b48be3\", \"zlib_unittests\": \"3cd7efe124f7d0b540fd4250d35baaec4f7e4448\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_5031bc3d586fdedb2b02f62dae64b9ebfd5c405012b7a28b41092bb1d66cfccb_v2\", \"builder_a15c792d02d243f2865d956ab51326d361a8fcfed3c44b30d209df7676d4718c_v2\", \"builder_a1b9c6892397d61c3624774723419f5ae48fcf776e7d2304bd4682bf5cd4c049_v2\", \"builder_b21beaca446334e07b00c4982fb2d009cad33fb091bf002422bc83c400c91f3b_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-east1-d-8-97-jg65\"], \"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-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- ChromeSitePerProcessTestWithVerifiedUserActivation.UserActivationBrowserVerificationSameOriginSite\\n\\n- LoggedInSpokenFeedbackTest.LearnModeHardwareKeys\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611903332012696",
"status": "COMPLETED",
"status_changed_ts": "1611903332841321",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22394",
"builder:linux-chromeos-dbg",
"buildset:commit/git/0c6211fe862e102f95b48f54715f69d066a2c497",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0c6211fe862e102f95b48f54715f69d066a2c497",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022220664921744880",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856790602976082320/+/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:5165bdfd9ebbb810",
"user_agent:luci-scheduler"
],
"updated_ts": "1611909649495130",
"url": "https://ci.chromium.org/b/8856790602976082320",
"utcnow_ts": "1611909649874265"
}
}
[08:40:49.883] Invocation finished in 1h45m52.760890462s with status FAILED