[03:35:44.804] New invocation is queued and will start shortly
[03:35:45.930] Starting the invocation (attempt 1)
[03:35:45.966] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:35:45.966] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:35:45.966] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018971707060925664",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"03180c4aa8c39c4fbe19103676f50c0ed7ebbb09\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9018971707060925664",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/03180c4aa8c39c4fbe19103676f50c0ed7ebbb09",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/03180c4aa8c39c4fbe19103676f50c0ed7ebbb09",
"gitiles_ref:refs/heads/master"
]
}
[03:35:46.633] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615001746099196",
"id": "8853541645040755872",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"03180c4aa8c39c4fbe19103676f50c0ed7ebbb09\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615001746507138",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45950",
"builder:linux-chromeos-rel",
"buildset:commit/git/03180c4aa8c39c4fbe19103676f50c0ed7ebbb09",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/03180c4aa8c39c4fbe19103676f50c0ed7ebbb09",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018971707060925664",
"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/8853541645040755872/+/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": "1615001746507242",
"url": "https://ci.chromium.org/b/8853541645040755872",
"utcnow_ts": "1615001746621323"
}
}
[03:35:46.633] Task URL: https://ci.chromium.org/b/8853541645040755872
[03:35:46.634] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:2:0) after 1m0s
[03:36:46.682] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:2:0)
[03:36:46.682] Timer tick, asking Buildbucket for the build status
[03:36:46.781] Build 8853541645040755872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:36:46.781] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:3:0) after 1m0s
[03:37:46.886] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:3:0)
[03:37:46.886] Timer tick, asking Buildbucket for the build status
[03:37:46.952] Build 8853541645040755872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:37:46.952] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:4:0) after 1m0s
[03:38:46.977] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:4:0)
[03:38:46.977] Timer tick, asking Buildbucket for the build status
[03:38:47.071] Build 8853541645040755872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:38:47.071] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:5:0) after 1m0s
[03:39:47.095] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:5:0)
[03:39:47.095] Timer tick, asking Buildbucket for the build status
[03:39:47.156] Build 8853541645040755872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:39:47.156] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:6:0) after 1m0s
[03:40:47.196] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:6:0)
[03:40:47.196] Timer tick, asking Buildbucket for the build status
[03:40:47.291] Build 8853541645040755872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:40:47.291] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:7:0) after 1m0s
[03:41:47.373] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:7:0)
[03:41:47.373] Timer tick, asking Buildbucket for the build status
[03:41:47.517] Build 8853541645040755872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:41:47.517] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:8:0) after 1m0s
[03:42:47.603] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:8:0)
[03:42:47.603] Timer tick, asking Buildbucket for the build status
[03:42:47.678] Build 8853541645040755872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:42:47.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:9:0) after 1m0s
[03:43:47.845] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:9:0)
[03:43:47.845] Timer tick, asking Buildbucket for the build status
[03:43:48.260] Build 8853541645040755872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:43:48.260] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:10:0) after 1m0s
[03:44:48.480] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:10:0)
[03:44:48.480] Timer tick, asking Buildbucket for the build status
[03:44:48.544] Build 8853541645040755872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:44:48.544] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:11:0) after 1m0s
[03:45:48.533] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:11:0)
[03:45:48.533] Timer tick, asking Buildbucket for the build status
[03:45:48.621] Build 8853541645040755872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:45:48.621] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:12:0) after 1m0s
[03:46:48.704] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:12:0)
[03:46:48.704] Timer tick, asking Buildbucket for the build status
[03:46:48.760] Build 8853541645040755872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:46:48.760] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:13:0) after 1m0s
[03:47:48.854] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:13:0)
[03:47:48.854] Timer tick, asking Buildbucket for the build status
[03:47:48.912] Build 8853541645040755872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:47:48.912] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:14:0) after 1m0s
[03:48:48.993] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:14:0)
[03:48:48.993] Timer tick, asking Buildbucket for the build status
[03:48:49.075] Build 8853541645040755872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:48:49.075] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:15:0) after 1m0s
[03:49:49.097] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:15:0)
[03:49:49.097] Timer tick, asking Buildbucket for the build status
[03:49:49.153] Build 8853541645040755872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:49:49.153] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:16:0) after 1m0s
[03:50:49.395] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:16:0)
[03:50:49.395] Timer tick, asking Buildbucket for the build status
[03:50:49.469] Build 8853541645040755872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:50:49.469] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:17:0) after 1m0s
[03:51:49.576] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:17:0)
[03:51:49.576] Timer tick, asking Buildbucket for the build status
[03:51:49.660] Build 8853541645040755872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:51:49.660] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:18:0) after 1m0s
[03:52:49.763] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:18:0)
[03:52:49.763] Timer tick, asking Buildbucket for the build status
[03:52:49.825] Build 8853541645040755872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:52:49.825] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:19:0) after 1m0s
[03:53:49.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:19:0)
[03:53:49.879] Timer tick, asking Buildbucket for the build status
[03:53:49.967] Build 8853541645040755872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:53:49.967] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:20:0) after 1m0s
[03:54:50.049] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:20:0)
[03:54:50.049] Timer tick, asking Buildbucket for the build status
[03:54:50.233] Build 8853541645040755872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:54:50.233] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:21:0) after 1m0s
[03:55:50.419] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:21:0)
[03:55:50.419] Timer tick, asking Buildbucket for the build status
[03:55:50.636] Build 8853541645040755872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:55:50.636] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:22:0) after 1m0s
[03:56:21.487] Received PubSub notification, asking Buildbucket for the build status
[03:56:21.567] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:50.684] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:22:0)
[03:56:50.684] Timer tick, asking Buildbucket for the build status
[03:56:50.845] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:50.845] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:24:0) after 1m0s
[03:57:50.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:24:0)
[03:57:50.889] Timer tick, asking Buildbucket for the build status
[03:57:50.955] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:50.955] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:25:0) after 1m0s
[03:58:50.982] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:25:0)
[03:58:50.982] Timer tick, asking Buildbucket for the build status
[03:58:51.063] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:51.063] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:26:0) after 1m0s
[03:59:51.056] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:26:0)
[03:59:51.056] Timer tick, asking Buildbucket for the build status
[03:59:51.114] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:51.114] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:27:0) after 1m0s
[04:00:51.143] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:27:0)
[04:00:51.143] Timer tick, asking Buildbucket for the build status
[04:00:51.259] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:51.259] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:28:0) after 1m0s
[04:01:51.299] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:28:0)
[04:01:51.299] Timer tick, asking Buildbucket for the build status
[04:01:51.371] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:51.371] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:29:0) after 1m0s
[04:02:51.427] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:29:0)
[04:02:51.427] Timer tick, asking Buildbucket for the build status
[04:02:51.649] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:51.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:30:0) after 1m0s
[04:03:51.695] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:30:0)
[04:03:51.695] Timer tick, asking Buildbucket for the build status
[04:03:51.841] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:51.841] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:31:0) after 1m0s
[04:04:51.943] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:31:0)
[04:04:51.944] Timer tick, asking Buildbucket for the build status
[04:04:52.037] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:52.037] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:32:0) after 1m0s
[04:05:52.112] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:32:0)
[04:05:52.112] Timer tick, asking Buildbucket for the build status
[04:05:52.302] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:52.302] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:33:0) after 1m0s
[04:06:52.324] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:33:0)
[04:06:52.324] Timer tick, asking Buildbucket for the build status
[04:06:52.556] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:52.556] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:34:0) after 1m0s
[04:07:52.555] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:34:0)
[04:07:52.555] Timer tick, asking Buildbucket for the build status
[04:07:56.966] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:56.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:35:0) after 1m0s
[04:08:57.019] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:35:0)
[04:08:57.019] Timer tick, asking Buildbucket for the build status
[04:08:57.082] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:57.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:36:0) after 1m0s
[04:09:57.119] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:36:0)
[04:09:57.119] Timer tick, asking Buildbucket for the build status
[04:09:57.240] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:57.240] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:37:0) after 1m0s
[04:10:57.252] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:37:0)
[04:10:57.252] Timer tick, asking Buildbucket for the build status
[04:10:57.348] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:57.348] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:38:0) after 1m0s
[04:11:57.403] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:38:0)
[04:11:57.403] Timer tick, asking Buildbucket for the build status
[04:11:57.470] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:57.470] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:39:0) after 1m0s
[04:12:57.525] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:39:0)
[04:12:57.525] Timer tick, asking Buildbucket for the build status
[04:12:57.706] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:57.706] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:40:0) after 1m0s
[04:13:57.744] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:40:0)
[04:13:57.744] Timer tick, asking Buildbucket for the build status
[04:13:57.939] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:57.939] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:41:0) after 1m0s
[04:14:57.983] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:41:0)
[04:14:57.983] Timer tick, asking Buildbucket for the build status
[04:14:58.128] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:58.128] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:42:0) after 1m0s
[04:15:58.174] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:42:0)
[04:15:58.174] Timer tick, asking Buildbucket for the build status
[04:15:58.448] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:58.448] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:43:0) after 1m0s
[04:16:58.449] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:43:0)
[04:16:58.449] Timer tick, asking Buildbucket for the build status
[04:16:58.812] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:58.812] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:44:0) after 1m0s
[04:17:58.864] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:44:0)
[04:17:58.864] Timer tick, asking Buildbucket for the build status
[04:17:59.719] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:59.719] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:45:0) after 1m0s
[04:18:59.774] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:45:0)
[04:18:59.774] Timer tick, asking Buildbucket for the build status
[04:18:59.866] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:59.866] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:46:0) after 1m0s
[04:19:59.961] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:46:0)
[04:19:59.961] Timer tick, asking Buildbucket for the build status
[04:20:00.226] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:00.226] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:47:0) after 1m0s
[04:21:00.358] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:47:0)
[04:21:00.358] Timer tick, asking Buildbucket for the build status
[04:21:00.430] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:00.430] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:48:0) after 1m0s
[04:22:00.924] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:48:0)
[04:22:00.924] Timer tick, asking Buildbucket for the build status
[04:22:01.101] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:01.101] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:49:0) after 1m0s
[04:23:01.537] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:49:0)
[04:23:01.537] Timer tick, asking Buildbucket for the build status
[04:23:01.598] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:01.598] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:50:0) after 1m0s
[04:24:01.676] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:50:0)
[04:24:01.676] Timer tick, asking Buildbucket for the build status
[04:24:01.788] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:01.788] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:51:0) after 1m0s
[04:25:01.944] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:51:0)
[04:25:01.944] Timer tick, asking Buildbucket for the build status
[04:25:02.019] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:02.019] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:52:0) after 1m0s
[04:26:02.076] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:52:0)
[04:26:02.076] Timer tick, asking Buildbucket for the build status
[04:26:02.165] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:02.166] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:53:0) after 1m0s
[04:27:02.234] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:53:0)
[04:27:02.234] Timer tick, asking Buildbucket for the build status
[04:27:02.512] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:02.512] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:54:0) after 1m0s
[04:28:02.552] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:54:0)
[04:28:02.552] Timer tick, asking Buildbucket for the build status
[04:28:02.983] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:02.983] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:55:0) after 1m0s
[04:29:03.066] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:55:0)
[04:29:03.066] Timer tick, asking Buildbucket for the build status
[04:29:03.151] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:03.151] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:56:0) after 1m0s
[04:30:03.274] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:56:0)
[04:30:03.274] Timer tick, asking Buildbucket for the build status
[04:30:03.441] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:03.441] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:57:0) after 1m0s
[04:31:03.569] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:57:0)
[04:31:03.569] Timer tick, asking Buildbucket for the build status
[04:31:03.645] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:03.645] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:58:0) after 1m0s
[04:32:03.700] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:58:0)
[04:32:03.700] Timer tick, asking Buildbucket for the build status
[04:32:03.915] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:03.915] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:59:0) after 1m0s
[04:33:04.077] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:59:0)
[04:33:04.077] Timer tick, asking Buildbucket for the build status
[04:33:04.151] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:04.151] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:60:0) after 1m0s
[04:34:04.259] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:60:0)
[04:34:04.259] Timer tick, asking Buildbucket for the build status
[04:34:04.322] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:04.322] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:61:0) after 1m0s
[04:35:04.529] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:61:0)
[04:35:04.529] Timer tick, asking Buildbucket for the build status
[04:35:04.638] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:04.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:62:0) after 1m0s
[04:36:04.743] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:62:0)
[04:36:04.743] Timer tick, asking Buildbucket for the build status
[04:36:04.897] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:04.897] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:63:0) after 1m0s
[04:37:04.931] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:63:0)
[04:37:04.931] Timer tick, asking Buildbucket for the build status
[04:37:04.996] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:04.996] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:64:0) after 1m0s
[04:38:05.031] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:64:0)
[04:38:05.031] Timer tick, asking Buildbucket for the build status
[04:38:05.107] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:05.107] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:65:0) after 1m0s
[04:39:05.178] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:65:0)
[04:39:05.178] Timer tick, asking Buildbucket for the build status
[04:39:05.243] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:05.243] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:66:0) after 1m0s
[04:40:05.265] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:66:0)
[04:40:05.265] Timer tick, asking Buildbucket for the build status
[04:40:05.357] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:05.357] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:67:0) after 1m0s
[04:41:05.431] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:67:0)
[04:41:05.431] Timer tick, asking Buildbucket for the build status
[04:41:05.507] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:05.507] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:68:0) after 1m0s
[04:42:05.530] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:68:0)
[04:42:05.530] Timer tick, asking Buildbucket for the build status
[04:42:05.603] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:05.603] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:69:0) after 1m0s
[04:43:05.665] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:69:0)
[04:43:05.665] Timer tick, asking Buildbucket for the build status
[04:43:05.744] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:05.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:70:0) after 1m0s
[04:44:05.760] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:70:0)
[04:44:05.760] Timer tick, asking Buildbucket for the build status
[04:44:05.940] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:05.940] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:71:0) after 1m0s
[04:45:05.960] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:71:0)
[04:45:05.960] Timer tick, asking Buildbucket for the build status
[04:45:06.029] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:06.029] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:72:0) after 1m0s
[04:46:06.049] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:72:0)
[04:46:06.049] Timer tick, asking Buildbucket for the build status
[04:46:06.241] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:06.241] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:73:0) after 1m0s
[04:47:06.259] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:73:0)
[04:47:06.259] Timer tick, asking Buildbucket for the build status
[04:47:06.326] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:06.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:74:0) after 1m0s
[04:48:06.402] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:74:0)
[04:48:06.402] Timer tick, asking Buildbucket for the build status
[04:48:06.500] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:06.500] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:75:0) after 1m0s
[04:49:06.560] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:75:0)
[04:49:06.560] Timer tick, asking Buildbucket for the build status
[04:49:06.766] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:06.766] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:76:0) after 1m0s
[04:50:06.798] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:76:0)
[04:50:06.798] Timer tick, asking Buildbucket for the build status
[04:50:06.864] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:06.864] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:77:0) after 1m0s
[04:51:06.885] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:77:0)
[04:51:06.885] Timer tick, asking Buildbucket for the build status
[04:51:06.966] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:06.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:78:0) after 1m0s
[04:52:07.133] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:78:0)
[04:52:07.133] Timer tick, asking Buildbucket for the build status
[04:52:07.965] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:07.965] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:79:0) after 1m0s
[04:53:08.017] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:79:0)
[04:53:08.017] Timer tick, asking Buildbucket for the build status
[04:53:08.232] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:08.232] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:80:0) after 1m0s
[04:54:08.309] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:80:0)
[04:54:08.309] Timer tick, asking Buildbucket for the build status
[04:54:08.551] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:08.551] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:81:0) after 1m0s
[04:55:08.626] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:81:0)
[04:55:08.626] Timer tick, asking Buildbucket for the build status
[04:55:09.135] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:09.135] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:82:0) after 1m0s
[04:56:09.192] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:82:0)
[04:56:09.192] Timer tick, asking Buildbucket for the build status
[04:56:09.283] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:09.283] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:83:0) after 1m0s
[04:57:09.316] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:83:0)
[04:57:09.316] Timer tick, asking Buildbucket for the build status
[04:57:09.466] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:09.467] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:84:0) after 1m0s
[04:58:09.501] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:84:0)
[04:58:09.501] Timer tick, asking Buildbucket for the build status
[04:58:09.619] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:09.619] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:85:0) after 1m0s
[04:59:09.640] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:85:0)
[04:59:09.640] Timer tick, asking Buildbucket for the build status
[04:59:09.713] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:09.713] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:86:0) after 1m0s
[05:00:09.723] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:86:0)
[05:00:09.723] Timer tick, asking Buildbucket for the build status
[05:00:10.156] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:10.156] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:87:0) after 1m0s
[05:01:10.176] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:87:0)
[05:01:10.176] Timer tick, asking Buildbucket for the build status
[05:01:10.264] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:10.264] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:88:0) after 1m0s
[05:02:10.284] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:88:0)
[05:02:10.284] Timer tick, asking Buildbucket for the build status
[05:02:10.507] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:10.507] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:89:0) after 1m0s
[05:03:10.561] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:89:0)
[05:03:10.561] Timer tick, asking Buildbucket for the build status
[05:03:10.640] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:10.640] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:90:0) after 1m0s
[05:04:10.651] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:90:0)
[05:04:10.651] Timer tick, asking Buildbucket for the build status
[05:04:10.760] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:10.760] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:91:0) after 1m0s
[05:05:10.811] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:91:0)
[05:05:10.811] Timer tick, asking Buildbucket for the build status
[05:05:11.135] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:11.135] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:92:0) after 1m0s
[05:06:11.187] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:92:0)
[05:06:11.187] Timer tick, asking Buildbucket for the build status
[05:06:11.308] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:11.308] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:93:0) after 1m0s
[05:07:11.330] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:93:0)
[05:07:11.330] Timer tick, asking Buildbucket for the build status
[05:07:11.514] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:11.514] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:94:0) after 1m0s
[05:08:11.807] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:94:0)
[05:08:11.807] Timer tick, asking Buildbucket for the build status
[05:08:11.908] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:11.908] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:95:0) after 1m0s
[05:09:12.184] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:95:0)
[05:09:12.184] Timer tick, asking Buildbucket for the build status
[05:09:12.349] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:12.349] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:96:0) after 1m0s
[05:10:12.333] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:96:0)
[05:10:12.333] Timer tick, asking Buildbucket for the build status
[05:10:12.517] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:12.517] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:97:0) after 1m0s
[05:11:12.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:97:0)
[05:11:12.726] Timer tick, asking Buildbucket for the build status
[05:11:12.848] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:12.848] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:98:0) after 1m0s
[05:12:12.866] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:98:0)
[05:12:12.866] Timer tick, asking Buildbucket for the build status
[05:12:13.046] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:13.046] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:99:0) after 1m0s
[05:13:13.065] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:99:0)
[05:13:13.065] Timer tick, asking Buildbucket for the build status
[05:13:13.347] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:13.347] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:100:0) after 1m0s
[05:14:13.364] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:100:0)
[05:14:13.364] Timer tick, asking Buildbucket for the build status
[05:14:13.732] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:13.732] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:101:0) after 1m0s
[05:15:13.750] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:101:0)
[05:15:13.750] Timer tick, asking Buildbucket for the build status
[05:15:14.205] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:14.205] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:102:0) after 1m0s
[05:16:14.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:102:0)
[05:16:14.223] Timer tick, asking Buildbucket for the build status
[05:16:14.524] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:14.524] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:103:0) after 1m0s
[05:17:14.593] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:103:0)
[05:17:14.593] Timer tick, asking Buildbucket for the build status
[05:17:14.751] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:14.751] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:104:0) after 1m0s
[05:18:14.796] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:104:0)
[05:18:14.796] Timer tick, asking Buildbucket for the build status
[05:18:14.889] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:14.889] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:105:0) after 1m0s
[05:19:14.995] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:105:0)
[05:19:14.995] Timer tick, asking Buildbucket for the build status
[05:19:15.416] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:15.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:106:0) after 1m0s
[05:20:20.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:106:0)
[05:20:20.085] Timer tick, asking Buildbucket for the build status
[05:20:20.276] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:20.276] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:107:0) after 1m0s
[05:21:21.196] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:107:0)
[05:21:21.196] Timer tick, asking Buildbucket for the build status
[05:21:21.392] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:21.392] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:108:0) after 1m0s
[05:22:21.569] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:108:0)
[05:22:21.570] Timer tick, asking Buildbucket for the build status
[05:22:21.641] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:21.641] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:109:0) after 1m0s
[05:23:21.662] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:109:0)
[05:23:21.662] Timer tick, asking Buildbucket for the build status
[05:23:21.751] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:21.751] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:110:0) after 1m0s
[05:24:21.771] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:110:0)
[05:24:21.771] Timer tick, asking Buildbucket for the build status
[05:24:21.842] Build 8853541645040755872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:21.842] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018971707060925664:111:0) after 1m0s
[05:25:07.129] Received PubSub notification, asking Buildbucket for the build status
[05:25:07.195] Build 8853541645040755872: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[05:25:07.195] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615008306548375",
"created_by": "project:chromium",
"created_ts": "1615001746099196",
"failure_reason": "BUILD_FAILURE",
"id": "8853541645040755872",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"03180c4aa8c39c4fbe19103676f50c0ed7ebbb09\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- ServiceWorkerMessagingTest.WorkerShutsDownWhileNativeMessagePortIsOpen\"}, \"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\": \"03180c4aa8c39c4fbe19103676f50c0ed7ebbb09\", \"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-32-ui0k\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45950, \"got_angle_revision\": \"8a48f4de1b6f20676b72e81e099993c3f570b345\", \"got_dawn_revision\": \"010fd1a8d7c7dd4e8fabe73546be78c45b6419c2\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"03180c4aa8c39c4fbe19103676f50c0ed7ebbb09\", \"got_revision_cp\": \"refs/heads/master@{#860498}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"de7d5775ab25234a2c14d061e0cda342248a0e6d\", \"got_v8_revision\": \"c1278b27dbd782403537d921f7c6c23fe56c5b10\", \"got_v8_revision_cp\": \"refs/heads/9.1.56@{#1}\", \"got_webrtc_revision\": \"1413e2db4c9b186176a54e70c52f5b673c2cb939\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33387}\", \"is_cached\": false, \"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\": \"03180c4aa8c39c4fbe19103676f50c0ed7ebbb09\", \"swarm_hashes_refs/heads/master(at){#860498}_without_patch\": {\"absl_hardening_tests\": \"42033aee87f14f7caae70e62ce07b154032900b0\", \"accessibility_unittests\": \"4ec91dd9de51a1dc53f06bb5df3277fdabcbb77c\", \"angle_unittests\": \"7a6502b9f809e13fc990180adaa958d5b251675c\", \"app_list_unittests\": \"44f69971dbce2facbd169cc08672aa1c7fd58d4a\", \"app_shell_unittests\": \"de85f6632fbd8e2c41bf274186502f20efda761b\", \"ash_unittests\": \"f169fecb1db91277dd3fe24ddf9f1b9129dff150\", \"aura_unittests\": \"9533d955690c66f0c7f5bb845bd97afecab6a293\", \"base_unittests\": \"1eb60451c9394ca44fde8a092bc7bc374080c7cf\", \"base_util_unittests\": \"f038265b759aa2a44fb55f4b054878943e935c27\", \"blink_common_unittests\": \"29a45ae01639ce2795780a1e09d6c2e74a8fbece\", \"blink_fuzzer_unittests\": \"00a202911666b6fd72a94a786aca9b74fde9a1ec\", \"blink_heap_unittests\": \"2f069618206aa10ae0e6b879b6f7eee0a4de316e\", \"blink_platform_unittests\": \"eb15bbd6ed61da90ac0dfb3bd95221a50eaeb007\", \"blink_unittests\": \"e4f80cff0fa82df7cbd9ec878bdb61f4f64b71a2\", \"boringssl_crypto_tests\": \"04d26bf044b9c9cba6645718c183748dc8917c6a\", \"boringssl_ssl_tests\": \"883a3697a0e11e7f91411a460bed70f7730065e2\", \"browser_tests\": \"a45fe1f44cb4727fe17c2056cc9e549bf140971f\", \"capture_unittests\": \"9bc73bf4e2182d153397fdfb04ecb161c1420c01\", \"cast_unittests\": \"af8b8fb12ab046e8cfd1e1099c346fa423a81f00\", \"cc_unittests\": \"94980606af268d41140a5dde0108aef789a40fd6\", \"chrome_app_unittests\": \"d04a808b24f133dec61b823b8e48f3d758771891\", \"chromedriver_unittests\": \"010a5afc990c1216f87975d37481f8e710261ace\", \"chromeos_components_unittests\": \"1c322fab65e70882919ae826cee796495b7f0e0f\", \"chromeos_unittests\": \"7f768513822c2fdbf5c01f51bccb83ccfc0b057f\", \"color_unittests\": \"ac3824aa95cc57ae914501408bc71e3b67398f2c\", \"components_browsertests\": \"b46e9e0aa221faba2fc5b6a17d52ce513ab8765a\", \"components_unittests\": \"d838e841990f0aa4f265c37125c956119cce4aa6\", \"compositor_unittests\": \"3455bfb9b772de2507b61199b503d37294ad9b19\", \"content_browsertests\": \"1a954aeffd7c393a2ec1014214e229eef7bbda38\", \"content_unittests\": \"c1666a62a55ea6b9ef7772eae7ad165d6dde7f45\", \"crashpad_tests\": \"af3095318e2ac3116ec37db1fc5210b253cbffa0\", \"crypto_unittests\": \"39f801dfad3f82028b384212eed182d094b979bb\", \"dbus_unittests\": \"557c61afa249290d1caa8d183c2190badb06714f\", \"device_unittests\": \"1f535abf6af6ef99d6c6847b8a3bb87e723792aa\", \"display_unittests\": \"2fa7d88fe5039e729bd7b9becfc3921e524fee6a\", \"events_unittests\": \"8dd37e05d7dbcc42b5af465f8bb1f4a90e117a82\", \"exo_unittests\": \"973ae81b8b97cd5331bc26e7a61e45f1f4be0ab0\", \"extensions_browsertests\": \"efd404d7c7a77914bd926be03ee60685507ba51c\", \"extensions_unittests\": \"077f46abd2031c1e166ef5f95f8f9910fa9e5575\", \"filesystem_service_unittests\": \"04aa712b4f93a427b7f3187aed405690545ca3d2\", \"gcm_unit_tests\": \"405942634217c4b89405fa05cb384f0f637e6a59\", \"gfx_unittests\": \"2a2e253a9f00758a3c8d53c17ec9a828ce75358e\", \"gin_unittests\": \"96e7dc8264b4c00effdc4c67a65436f774be2891\", \"gl_unittests_ozone\": \"c40ab569865d7267a486b57e9b4316eaf47bfcb5\", \"google_apis_unittests\": \"c177f27c2afd0b7da9e115a7bd4f289c3876c2b3\", \"gpu_unittests\": \"b94d0f59bd53902c3a3e32175ea8c93734182d8e\", \"gwp_asan_unittests\": \"5037a2818e47ce12a49ec00ee2837b77f449e5ac\", \"interactive_ui_tests\": \"0263cb6c2bc44d0d0083f6b04c88e000bde2f194\", \"ipc_tests\": \"526671e855b620836875a6d9fe77f6231ff77563\", \"jingle_unittests\": \"9e320b499c6579469262e8fae6f2dcbb0a296c4b\", \"keyboard_unittests\": \"74b58e187d149453807a14d60abab9b8bdd25c41\", \"latency_unittests\": \"6b873ed11ccc03b9be66ea57e1a37c58f1884309\", \"libjingle_xmpp_unittests\": \"058025b421a779e83a418359c5e4222ed321e233\", \"liburlpattern_unittests\": \"feb9f41ec9af2f92127c0d8cb7dea692021e1e48\", \"media_blink_unittests\": \"61a2380020684b4f8aeb0a764fff5ffed2a0b508\", \"media_unittests\": \"7d55c1f7ea7b84e487a28b29da2c52b428a19aef\", \"message_center_unittests\": \"4d3f6580dbb33b214fc3bec628ab01e6dab2e07e\", \"midi_unittests\": \"63d809856d9f4f0c9914050daa2cc001f6293a9f\", \"mojo_core_unittests\": \"61182564c90cb752840c991d0e53238224a1c3e8\", \"mojo_unittests\": \"17bb6d433f94817ef7ff859f264e241917641589\", \"nacl_helper_nonsfi_unittests\": \"be286d5c9bf95fd06396cd13dfb57a993fbacb37\", \"nacl_loader_unittests\": \"cbeb52250211059559fffbb36c3f15f7a97cb4da\", \"native_theme_unittests\": \"9167f1d2482e4e1ac888664a216d26e15051d145\", \"net_unittests\": \"a570fb975193800d1b24789da90df138af863e0f\", \"ozone_gl_unittests\": \"cbef277380aed7e882541e911eebf0c753b92448\", \"ozone_unittests\": \"9d6ac6dfcda524af4a4f432c1c26983f1da4ed56\", \"ozone_x11_unittests\": \"6ed76a2e084d1332bc8f40be8278a90b5d5aad10\", \"pdf_unittests\": \"107a03e52d30996d8f7e63b02c5cfb49ec985f69\", \"perfetto_unittests\": \"c5edde32669d488680cf9e7d8950781def80283c\", \"ppapi_unittests\": \"e2f5bbf70ee091e0a2ad3f949d6e95c953292fc3\", \"printing_unittests\": \"b5db7a0ad62c17213ea03e49c984f5a96afd30ab\", \"remoting_unittests\": \"7dc6cb506a89c81cd4a6c277e61ca2fecc8a7988\", \"sandbox_linux_unittests\": \"023c06d1e658192614e99797d9d20a0a9aeeeeff\", \"service_manager_unittests\": \"d35b3e0ef7f74b9eed5ba9fcecf5fd64f23f6248\", \"services_unittests\": \"955886f8dca4812dce8b5b6dbc67f452052d83c7\", \"shell_dialogs_unittests\": \"c308ce67a27d6322b3e33bd2a742fdb36b8d9e46\", \"shell_encryption_unittests\": \"8a970c6110ce7d4908b468486e311d726372cd66\", \"skia_unittests\": \"7fa9b3a1373b13308d7580f70b9680729dd332f1\", \"snapshot_unittests\": \"173625e2c6c2c47584fb505b856c3217318bb3eb\", \"sql_unittests\": \"147f27658d54072471e57c99ccb2163e5cda7cfd\", \"storage_unittests\": \"cd7473ae69c2ddfaf8f2a1ae09d94e17dc46872d\", \"sync_integration_tests\": \"8a130a3dd4ed1f7f538e79d0d4219241c216ba70\", \"traffic_annotation_auditor_unittests\": \"3f15d079a703d871898ff9e0d1a97a504664d73f\", \"ui_base_unittests\": \"d4acec7e7790416e118b57e918b00c706bec4862\", \"ui_chromeos_unittests\": \"67fcafe0267cf37cda3bc8f8d36990f925413558\", \"ui_touch_selection_unittests\": \"fdc3a99c28272f0299646a48123c134433e964dd\", \"unit_tests\": \"abd37f8f601b96a154f1f0520f34ecb0b997a77d\", \"url_unittests\": \"d40284dcc4f48ffc011e2c73a1048075c611c1b3\", \"usage_time_limit_unittests\": \"bbd91edb9627fdab16fed3397fb62d64ee0baa63\", \"views_unittests\": \"b505736dd928decd39ccea094e641fd32bd2060b\", \"viz_unittests\": \"9470717a19c989d3688c32d45a753ee0d8600460\", \"wayland_client_perftests\": \"c138a5161c100439f46b49a19b3343724be7bb0a\", \"wayland_client_tests\": \"52ab437f27541e437f338079308f811eb15e09b6\", \"wm_unittests\": \"471c9a25f3ffbf3fbb9e17e9f6c5ce111815c387\", \"wtf_unittests\": \"b2032a4a4e65977507c155c7dbc20b0491758225\", \"zlib_unittests\": \"41f1d8fdbf0390c74e6d2133d19a39292f2a1c8f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02b94346fc981ed3c3847ac0524bcc084571ef426302b2a255781857b227612a_v2\", \"builder_0ba11647c3777752a9345c9a31488a30e4a4f8f072cd97454b4c4835355bfe20_v2\", \"builder_5315cf154f9c1ae6b2621094599bc107171a849e1c0f232ec66a504ee800a364_v2\", \"builder_864635b37f8a928d1dc914b08e925b260febdb791940988e6cd5b520b91ad323_v2\", \"builder_c18d585c17603559d8d68e3de2c6e12032537b56b99cb619aa84174b37006d15_v2\", \"builder_d3ee47a27de6e242d9ac174bcd2903a12a57590a5abb70af1d9c4989bd7f5749_v2\", \"builder_d7cc7c59327c05146a034898ae78722818cd22c61c9435c4c4348e98bea5a132_v2\", \"builder_f5610824250c57fc28679fbc8606252c3dbaca731fb7f2a4bebf1e0fc7f48a3a_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-32-ui0k\"], \"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\": [\"5725-1a0d85e\"], \"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- ServiceWorkerMessagingTest.WorkerShutsDownWhileNativeMessagePortIsOpen\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615002980907924",
"status": "COMPLETED",
"status_changed_ts": "1615002981319700",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45950",
"builder:linux-chromeos-rel",
"buildset:commit/git/03180c4aa8c39c4fbe19103676f50c0ed7ebbb09",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/03180c4aa8c39c4fbe19103676f50c0ed7ebbb09",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018971707060925664",
"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/8853541645040755872/+/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:521e6c85177e7510",
"user_agent:luci-scheduler"
],
"updated_ts": "1615008306548774",
"url": "https://ci.chromium.org/b/8853541645040755872",
"utcnow_ts": "1615008307188125"
}
}
[05:25:07.195] Invocation finished in 1h49m22.406164033s with status FAILED