[01:53:56.183] New invocation is queued and will start shortly
[01:53:57.356] Starting the invocation (attempt 1)
[01:53:57.401] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:53:57.401] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:53:57.401] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018434530616437488",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d65514146b797e45b37109934ba885900d184ac4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9018434530616437488",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/d65514146b797e45b37109934ba885900d184ac4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d65514146b797e45b37109934ba885900d184ac4",
"gitiles_ref:refs/heads/master"
]
}
[01:53:58.256] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615514037473866",
"id": "8853004468600849984",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d65514146b797e45b37109934ba885900d184ac4\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615514038044371",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22958",
"builder:linux-chromeos-dbg",
"buildset:commit/git/d65514146b797e45b37109934ba885900d184ac4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d65514146b797e45b37109934ba885900d184ac4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018434530616437488",
"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/8853004468600849984/+/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": "1615514038044492",
"url": "https://ci.chromium.org/b/8853004468600849984",
"utcnow_ts": "1615514038247239"
}
}
[01:53:58.256] Task URL: https://ci.chromium.org/b/8853004468600849984
[01:53:58.256] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:2:0) after 1m0s
[01:54:00.882] Received PubSub notification, asking Buildbucket for the build status
[01:54:00.998] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:58.293] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:2:0)
[01:54:58.293] Timer tick, asking Buildbucket for the build status
[01:54:58.363] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:58.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:4:0) after 1m0s
[01:55:58.451] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:4:0)
[01:55:58.451] Timer tick, asking Buildbucket for the build status
[01:55:58.532] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:58.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:5:0) after 1m0s
[01:56:58.618] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:5:0)
[01:56:58.618] Timer tick, asking Buildbucket for the build status
[01:56:58.835] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:58.835] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:6:0) after 1m0s
[01:57:58.924] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:6:0)
[01:57:58.924] Timer tick, asking Buildbucket for the build status
[01:57:59.151] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:59.151] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:7:0) after 1m0s
[01:58:59.427] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:7:0)
[01:58:59.427] Timer tick, asking Buildbucket for the build status
[01:58:59.506] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:59.507] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:8:0) after 1m0s
[01:59:59.603] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:8:0)
[01:59:59.603] Timer tick, asking Buildbucket for the build status
[01:59:59.678] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:59.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:9:0) after 1m0s
[02:00:59.704] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:9:0)
[02:00:59.704] Timer tick, asking Buildbucket for the build status
[02:00:59.771] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:59.771] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:10:0) after 1m0s
[02:01:59.831] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:10:0)
[02:01:59.831] Timer tick, asking Buildbucket for the build status
[02:01:59.895] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:59.895] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:11:0) after 1m0s
[02:02:59.885] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:11:0)
[02:02:59.885] Timer tick, asking Buildbucket for the build status
[02:02:59.961] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:59.961] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:12:0) after 1m0s
[02:04:00.064] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:12:0)
[02:04:00.064] Timer tick, asking Buildbucket for the build status
[02:04:00.164] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:00.164] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:13:0) after 1m0s
[02:05:00.235] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:13:0)
[02:05:00.235] Timer tick, asking Buildbucket for the build status
[02:05:00.426] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:00.426] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:14:0) after 1m0s
[02:06:00.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:14:0)
[02:06:00.628] Timer tick, asking Buildbucket for the build status
[02:06:00.725] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:00.725] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:15:0) after 1m0s
[02:07:00.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:15:0)
[02:07:00.889] Timer tick, asking Buildbucket for the build status
[02:07:01.022] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:01.022] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:16:0) after 1m0s
[02:08:01.028] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:16:0)
[02:08:01.028] Timer tick, asking Buildbucket for the build status
[02:08:01.345] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:01.345] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:17:0) after 1m0s
[02:09:01.438] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:17:0)
[02:09:01.438] Timer tick, asking Buildbucket for the build status
[02:09:01.525] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:01.525] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:18:0) after 1m0s
[02:10:01.674] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:18:0)
[02:10:01.674] Timer tick, asking Buildbucket for the build status
[02:10:01.769] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:01.769] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:19:0) after 1m0s
[02:11:01.775] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:19:0)
[02:11:01.775] Timer tick, asking Buildbucket for the build status
[02:11:01.836] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:01.836] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:20:0) after 1m0s
[02:12:01.904] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:20:0)
[02:12:01.904] Timer tick, asking Buildbucket for the build status
[02:12:02.115] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:02.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:21:0) after 1m0s
[02:13:02.211] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:21:0)
[02:13:02.211] Timer tick, asking Buildbucket for the build status
[02:13:02.346] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:02.346] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:22:0) after 1m0s
[02:14:02.385] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:22:0)
[02:14:02.385] Timer tick, asking Buildbucket for the build status
[02:14:02.655] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:02.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:23:0) after 1m0s
[02:15:02.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:23:0)
[02:15:02.726] Timer tick, asking Buildbucket for the build status
[02:15:02.797] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:02.798] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:24:0) after 1m0s
[02:16:02.838] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:24:0)
[02:16:02.838] Timer tick, asking Buildbucket for the build status
[02:16:02.969] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:02.969] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:25:0) after 1m0s
[02:17:03.056] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:25:0)
[02:17:03.056] Timer tick, asking Buildbucket for the build status
[02:17:03.115] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:03.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:26:0) after 1m0s
[02:18:03.144] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:26:0)
[02:18:03.144] Timer tick, asking Buildbucket for the build status
[02:18:03.231] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:03.231] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:27:0) after 1m0s
[02:19:03.265] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:27:0)
[02:19:03.265] Timer tick, asking Buildbucket for the build status
[02:19:03.561] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:03.561] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:28:0) after 1m0s
[02:20:03.614] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:28:0)
[02:20:03.614] Timer tick, asking Buildbucket for the build status
[02:20:03.714] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:03.714] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:29:0) after 1m0s
[02:21:03.699] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:29:0)
[02:21:03.699] Timer tick, asking Buildbucket for the build status
[02:21:03.895] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:03.895] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:30:0) after 1m0s
[02:22:03.967] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:30:0)
[02:22:03.967] Timer tick, asking Buildbucket for the build status
[02:22:04.039] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:04.039] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:31:0) after 1m0s
[02:23:04.072] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:31:0)
[02:23:04.072] Timer tick, asking Buildbucket for the build status
[02:23:04.299] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:04.299] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:32:0) after 1m0s
[02:24:04.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:32:0)
[02:24:04.305] Timer tick, asking Buildbucket for the build status
[02:24:04.448] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:04.448] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:33:0) after 1m0s
[02:25:04.481] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:33:0)
[02:25:04.481] Timer tick, asking Buildbucket for the build status
[02:25:04.555] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:04.555] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:34:0) after 1m0s
[02:26:04.589] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:34:0)
[02:26:04.589] Timer tick, asking Buildbucket for the build status
[02:26:04.665] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:04.665] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:35:0) after 1m0s
[02:27:04.768] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:35:0)
[02:27:04.768] Timer tick, asking Buildbucket for the build status
[02:27:04.845] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:04.845] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:36:0) after 1m0s
[02:28:04.861] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:36:0)
[02:28:04.861] Timer tick, asking Buildbucket for the build status
[02:28:05.068] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:05.068] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:37:0) after 1m0s
[02:29:05.095] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:37:0)
[02:29:05.095] Timer tick, asking Buildbucket for the build status
[02:29:05.200] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:05.200] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:38:0) after 1m0s
[02:30:05.297] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:38:0)
[02:30:05.297] Timer tick, asking Buildbucket for the build status
[02:30:05.375] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:05.375] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:39:0) after 1m0s
[02:31:05.456] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:39:0)
[02:31:05.456] Timer tick, asking Buildbucket for the build status
[02:31:05.526] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:05.526] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:40:0) after 1m0s
[02:32:05.508] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:40:0)
[02:32:05.508] Timer tick, asking Buildbucket for the build status
[02:32:05.578] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:05.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:41:0) after 1m0s
[02:33:05.689] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:41:0)
[02:33:05.689] Timer tick, asking Buildbucket for the build status
[02:33:05.760] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:05.760] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:42:0) after 1m0s
[02:34:05.846] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:42:0)
[02:34:05.846] Timer tick, asking Buildbucket for the build status
[02:34:05.924] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:05.924] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:43:0) after 1m0s
[02:35:05.970] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:43:0)
[02:35:05.970] Timer tick, asking Buildbucket for the build status
[02:35:06.052] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:06.052] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:44:0) after 1m0s
[02:36:06.075] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:44:0)
[02:36:06.075] Timer tick, asking Buildbucket for the build status
[02:36:06.166] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:06.166] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:45:0) after 1m0s
[02:37:06.222] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:45:0)
[02:37:06.222] Timer tick, asking Buildbucket for the build status
[02:37:06.324] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:06.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:46:0) after 1m0s
[02:38:06.313] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:46:0)
[02:38:06.313] Timer tick, asking Buildbucket for the build status
[02:38:06.387] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:06.387] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:47:0) after 1m0s
[02:39:06.598] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:47:0)
[02:39:06.598] Timer tick, asking Buildbucket for the build status
[02:39:06.689] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:06.689] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:48:0) after 1m0s
[02:40:06.684] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:48:0)
[02:40:06.684] Timer tick, asking Buildbucket for the build status
[02:40:06.911] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:06.911] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:49:0) after 1m0s
[02:41:06.933] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:49:0)
[02:41:06.933] Timer tick, asking Buildbucket for the build status
[02:41:07.085] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:07.085] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:50:0) after 1m0s
[02:42:07.110] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:50:0)
[02:42:07.110] Timer tick, asking Buildbucket for the build status
[02:42:07.189] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:07.189] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:51:0) after 1m0s
[02:43:07.307] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:51:0)
[02:43:07.307] Timer tick, asking Buildbucket for the build status
[02:43:07.530] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:07.530] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:52:0) after 1m0s
[02:44:07.549] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:52:0)
[02:44:07.549] Timer tick, asking Buildbucket for the build status
[02:44:07.781] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:07.781] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:53:0) after 1m0s
[02:45:07.831] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:53:0)
[02:45:07.831] Timer tick, asking Buildbucket for the build status
[02:45:08.027] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:08.027] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:54:0) after 1m0s
[02:46:08.072] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:54:0)
[02:46:08.072] Timer tick, asking Buildbucket for the build status
[02:46:08.154] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:08.154] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:55:0) after 1m0s
[02:47:08.366] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:55:0)
[02:47:08.366] Timer tick, asking Buildbucket for the build status
[02:47:08.821] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:08.821] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:56:0) after 1m0s
[02:48:08.852] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:56:0)
[02:48:08.852] Timer tick, asking Buildbucket for the build status
[02:48:09.228] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:09.229] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:57:0) after 1m0s
[02:49:09.306] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:57:0)
[02:49:09.306] Timer tick, asking Buildbucket for the build status
[02:49:09.499] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:09.499] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:58:0) after 1m0s
[02:50:09.574] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:58:0)
[02:50:09.574] Timer tick, asking Buildbucket for the build status
[02:50:09.656] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:09.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:59:0) after 1m0s
[02:51:09.724] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:59:0)
[02:51:09.724] Timer tick, asking Buildbucket for the build status
[02:51:10.398] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:10.398] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:60:0) after 1m0s
[02:52:10.538] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:60:0)
[02:52:10.538] Timer tick, asking Buildbucket for the build status
[02:52:10.653] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:10.653] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:61:0) after 1m0s
[02:53:10.709] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:61:0)
[02:53:10.709] Timer tick, asking Buildbucket for the build status
[02:53:10.993] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:10.993] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:62:0) after 1m0s
[02:54:11.066] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:62:0)
[02:54:11.066] Timer tick, asking Buildbucket for the build status
[02:54:11.415] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:11.415] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:63:0) after 1m0s
[02:55:11.496] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:63:0)
[02:55:11.497] Timer tick, asking Buildbucket for the build status
[02:55:11.606] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:11.607] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:64:0) after 1m0s
[02:56:11.609] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:64:0)
[02:56:11.609] Timer tick, asking Buildbucket for the build status
[02:56:11.908] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:11.908] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:65:0) after 1m0s
[02:57:11.962] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:65:0)
[02:57:11.962] Timer tick, asking Buildbucket for the build status
[02:57:12.027] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:12.027] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:66:0) after 1m0s
[02:58:12.053] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:66:0)
[02:58:12.053] Timer tick, asking Buildbucket for the build status
[02:58:12.246] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:12.246] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:67:0) after 1m0s
[02:59:12.271] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:67:0)
[02:59:12.271] Timer tick, asking Buildbucket for the build status
[02:59:12.429] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:12.429] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:68:0) after 1m0s
[03:00:12.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:68:0)
[03:00:12.447] Timer tick, asking Buildbucket for the build status
[03:00:12.532] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:12.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:69:0) after 1m0s
[03:01:12.572] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:69:0)
[03:01:12.572] Timer tick, asking Buildbucket for the build status
[03:01:12.686] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:12.686] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:70:0) after 1m0s
[03:02:12.809] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:70:0)
[03:02:12.809] Timer tick, asking Buildbucket for the build status
[03:02:13.011] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:13.011] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:71:0) after 1m0s
[03:03:13.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:71:0)
[03:03:13.085] Timer tick, asking Buildbucket for the build status
[03:03:13.181] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:13.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:72:0) after 1m0s
[03:04:13.227] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:72:0)
[03:04:13.227] Timer tick, asking Buildbucket for the build status
[03:04:13.353] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:13.353] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:73:0) after 1m0s
[03:05:13.417] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:73:0)
[03:05:13.417] Timer tick, asking Buildbucket for the build status
[03:05:13.489] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:13.489] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:74:0) after 1m0s
[03:06:13.569] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:74:0)
[03:06:13.569] Timer tick, asking Buildbucket for the build status
[03:06:13.945] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:13.945] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:75:0) after 1m0s
[03:07:13.983] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:75:0)
[03:07:13.983] Timer tick, asking Buildbucket for the build status
[03:07:14.065] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:14.065] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:76:0) after 1m0s
[03:08:14.139] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:76:0)
[03:08:14.139] Timer tick, asking Buildbucket for the build status
[03:08:14.566] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:14.566] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:77:0) after 1m0s
[03:09:14.693] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:77:0)
[03:09:14.693] Timer tick, asking Buildbucket for the build status
[03:09:15.063] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:15.063] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:78:0) after 1m0s
[03:10:15.099] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:78:0)
[03:10:15.099] Timer tick, asking Buildbucket for the build status
[03:10:15.539] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:15.539] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:79:0) after 1m0s
[03:11:15.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:79:0)
[03:11:15.746] Timer tick, asking Buildbucket for the build status
[03:11:16.126] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:16.126] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:80:0) after 1m0s
[03:12:16.147] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:80:0)
[03:12:16.147] Timer tick, asking Buildbucket for the build status
[03:12:16.222] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:16.222] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:81:0) after 1m0s
[03:13:16.247] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:81:0)
[03:13:16.247] Timer tick, asking Buildbucket for the build status
[03:13:16.445] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:16.445] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:82:0) after 1m0s
[03:14:16.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:82:0)
[03:14:16.466] Timer tick, asking Buildbucket for the build status
[03:14:16.603] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:16.603] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:83:0) after 1m0s
[03:15:16.682] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:83:0)
[03:15:16.682] Timer tick, asking Buildbucket for the build status
[03:15:16.989] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:16.989] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:84:0) after 1m0s
[03:16:17.101] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:84:0)
[03:16:17.101] Timer tick, asking Buildbucket for the build status
[03:16:17.314] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:17.314] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:85:0) after 1m0s
[03:17:17.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:85:0)
[03:17:17.339] Timer tick, asking Buildbucket for the build status
[03:17:17.753] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:17.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:86:0) after 1m0s
[03:18:17.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:86:0)
[03:18:17.896] Timer tick, asking Buildbucket for the build status
[03:18:18.041] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:18.041] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:87:0) after 1m0s
[03:19:18.114] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:87:0)
[03:19:18.114] Timer tick, asking Buildbucket for the build status
[03:19:18.198] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:18.198] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:88:0) after 1m0s
[03:20:18.297] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:88:0)
[03:20:18.297] Timer tick, asking Buildbucket for the build status
[03:20:18.381] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:18.381] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:89:0) after 1m0s
[03:21:18.472] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:89:0)
[03:21:18.472] Timer tick, asking Buildbucket for the build status
[03:21:18.563] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:18.563] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:90:0) after 1m0s
[03:22:18.579] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:90:0)
[03:22:18.579] Timer tick, asking Buildbucket for the build status
[03:22:19.042] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:19.042] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:91:0) after 1m0s
[03:23:19.120] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:91:0)
[03:23:19.120] Timer tick, asking Buildbucket for the build status
[03:23:19.192] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:19.192] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:92:0) after 1m0s
[03:24:19.243] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:92:0)
[03:24:19.243] Timer tick, asking Buildbucket for the build status
[03:24:19.364] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:19.364] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:93:0) after 1m0s
[03:25:19.418] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:93:0)
[03:25:19.418] Timer tick, asking Buildbucket for the build status
[03:25:19.550] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:19.550] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:94:0) after 1m0s
[03:26:19.612] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:94:0)
[03:26:19.612] Timer tick, asking Buildbucket for the build status
[03:26:19.690] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:19.690] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:95:0) after 1m0s
[03:27:19.764] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:95:0)
[03:27:19.764] Timer tick, asking Buildbucket for the build status
[03:27:19.834] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:19.834] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:96:0) after 1m0s
[03:28:19.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:96:0)
[03:28:19.871] Timer tick, asking Buildbucket for the build status
[03:28:19.950] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:19.950] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:97:0) after 1m0s
[03:29:20.000] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:97:0)
[03:29:20.000] Timer tick, asking Buildbucket for the build status
[03:29:20.266] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:20.266] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:98:0) after 1m0s
[03:30:20.286] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:98:0)
[03:30:20.286] Timer tick, asking Buildbucket for the build status
[03:30:20.519] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:20.519] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:99:0) after 1m0s
[03:31:20.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:99:0)
[03:31:20.583] Timer tick, asking Buildbucket for the build status
[03:31:20.666] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:20.666] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:100:0) after 1m0s
[03:32:20.719] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:100:0)
[03:32:20.719] Timer tick, asking Buildbucket for the build status
[03:32:20.837] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:20.837] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:101:0) after 1m0s
[03:33:20.902] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:101:0)
[03:33:20.902] Timer tick, asking Buildbucket for the build status
[03:33:21.084] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:21.084] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:102:0) after 1m0s
[03:34:21.150] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:102:0)
[03:34:21.150] Timer tick, asking Buildbucket for the build status
[03:34:21.273] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:21.273] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:103:0) after 1m0s
[03:35:21.328] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:103:0)
[03:35:21.328] Timer tick, asking Buildbucket for the build status
[03:35:21.627] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:21.627] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:104:0) after 1m0s
[03:36:21.753] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:104:0)
[03:36:21.753] Timer tick, asking Buildbucket for the build status
[03:36:21.947] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:21.947] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:105:0) after 1m0s
[03:37:21.976] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:105:0)
[03:37:21.976] Timer tick, asking Buildbucket for the build status
[03:37:22.055] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:22.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:106:0) after 1m0s
[03:38:22.038] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:106:0)
[03:38:22.038] Timer tick, asking Buildbucket for the build status
[03:38:22.117] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:22.117] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:107:0) after 1m0s
[03:39:22.153] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:107:0)
[03:39:22.153] Timer tick, asking Buildbucket for the build status
[03:39:22.279] Build 8853004468600849984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:22.279] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9018434530616437488:108:0) after 1m0s
[03:40:16.932] Received PubSub notification, asking Buildbucket for the build status
[03:40:17.024] Build 8853004468600849984: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:40:17.026] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615520416398748",
"created_by": "project:chromium",
"created_ts": "1615514037473866",
"id": "8853004468600849984",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d65514146b797e45b37109934ba885900d184ac4\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"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\": \"d65514146b797e45b37109934ba885900d184ac4\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-44-4rk7\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22958, \"got_angle_revision\": \"cb5441ed17ae58ccec9562a1ffe4eed2351c5c60\", \"got_dawn_revision\": \"0702b704697ca0c9d4b41faeb1d3f80e10f07278\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"d65514146b797e45b37109934ba885900d184ac4\", \"got_revision_cp\": \"refs/heads/master@{#862252}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3c4fd15fb075ab3a8eb5809f9a42b5faa9ac63ae\", \"got_v8_revision\": \"9d12b666c3e113f5cd61e2b2eb384aae242ac840\", \"got_v8_revision_cp\": \"refs/heads/9.1.105@{#1}\", \"got_webrtc_revision\": \"14a626af56675c9d95f9fb207d53366a53a95fbd\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33409}\", \"is_cached\": true, \"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\": \"d65514146b797e45b37109934ba885900d184ac4\", \"swarm_hashes_refs/heads/master(at){#862252}_without_patch\": {\"absl_hardening_tests\": \"e173691b9fc44c40a239a63f397aeb2bb7dc270c\", \"accessibility_unittests\": \"a66a194b28337c946560d4eb05b9cc742ba64d07\", \"angle_unittests\": \"afcf2f86c8ef42d2faa418dc76d18c45d0065ec8\", \"app_list_unittests\": \"845a65fefa5ca5107f1f83de6477f390f3f90705\", \"app_shell_unittests\": \"52dad26954e7e70cb86b7d1672341bbc271a6d6d\", \"ash_unittests\": \"8c54a15eef2cfa52f73dcf2b89907e1da874d7e5\", \"aura_unittests\": \"2834bc4f2bc50074f5b9362c394ac0457cd7e1c8\", \"base_unittests\": \"0787a3709a303dddba7d45c0f54f4b0740440187\", \"base_util_unittests\": \"6809b9e0079b00f693fb70d67e328b6945252cc7\", \"blink_common_unittests\": \"14cf54b5a62707d1fdad4a31a2036d2039f506d9\", \"blink_fuzzer_unittests\": \"348249a39f7ebaabd4ee15f224c7e7d9be0e03bb\", \"blink_heap_unittests\": \"bb4e1c0ec6f51b6249e6f3b4709a31cac45cb5ad\", \"blink_platform_unittests\": \"3809b51b76353b8e1bacb95a450b0744372ea9dd\", \"blink_unittests\": \"05d4ef284f57c92e1c8d88d46c1c783db0b780dc\", \"boringssl_crypto_tests\": \"22ada858a3b0c1be2e1160a3885227dccd6f9b50\", \"boringssl_ssl_tests\": \"854f02621210a1b211e61d2bdf0bc21c772cafda\", \"browser_tests\": \"3ad78e1bd31c6f90176d036a7c0273545f498837\", \"capture_unittests\": \"e3fc6a7319c19e69f62528ac512336e169f56c83\", \"cast_unittests\": \"cfcab67c32c8071963a8e841da38d2cb3598b83c\", \"cc_unittests\": \"464ae4ed6ecd1356575d1a408d771ae449e3a3eb\", \"chrome_app_unittests\": \"85c2ae8e94b94d58d3b0dbb553e1575624f8b64e\", \"chromedriver_unittests\": \"7a9543d9b61a71c71a792109756af7a9d21b0d05\", \"chromeos_components_unittests\": \"d31c8db9cee83fb222004feede078fbd68fa1717\", \"chromeos_unittests\": \"9a0e4e336b851d7c86384ca4b2f94015e91486bd\", \"color_unittests\": \"3f16dad49b39027fca0993461b2b05dbce8b8cbc\", \"components_browsertests\": \"94d3d43ec00d4f53c81139db42021217bf5deca7\", \"components_unittests\": \"a1ae0e3f0e74b765648499999be5d2a6dc6f9e22\", \"compositor_unittests\": \"42aaf0891c3de42a829e15b723c0a9d23565c965\", \"content_browsertests\": \"769dff87e6bee2b151eb9740e782d285abaee93a\", \"content_unittests\": \"479fe5e52f4180e24c389ff2fc942b33d783c9f5\", \"crashpad_tests\": \"4b6691b86617025d7d5c81e8d8e11b0bfb701f4c\", \"crypto_unittests\": \"c276a639a4ec05af7e19aed11a50b64bee432395\", \"dbus_unittests\": \"058e8885efa3ec364226ed4099bc33777baccc4f\", \"device_unittests\": \"3d42903176e63fbcf21c22210d0aae153198b831\", \"display_unittests\": \"e50db16da8a1269ea28153fcce1ab82ca2fb63e0\", \"events_unittests\": \"02dee6bae873dc59fc41b7959acf1e6bc92a2da7\", \"exo_unittests\": \"b336b9197961e6dae40aaa31c82209bd33aef180\", \"extensions_browsertests\": \"75f7b1c787bc9b899f37d17563d0d07ee9c31963\", \"extensions_unittests\": \"c00e7f75559de1fd935f30a28f2d9a2e3c310c06\", \"filesystem_service_unittests\": \"a8d4f018d4900b8ef6391b951ec3c2c376ea38ab\", \"gcm_unit_tests\": \"9eb6c321cdfd5e99e89a9a5fdaa94f1ca033d3d5\", \"gfx_unittests\": \"03e2fd5f742b47b43ac4ffa82f97fed6e416f67b\", \"gin_unittests\": \"bdd88ba92a0c3651edcf3ce50bed4a35b8713a72\", \"gl_unittests_ozone\": \"4ba585a1ba07f7883e297d8e35588dc1c3be90f9\", \"google_apis_unittests\": \"0ba7abbcff4f9bc65cdc64e4b523cc5b2ab07b4e\", \"gpu_unittests\": \"f1a6ee0784dd2adac6f3eca292c878257a03f5f5\", \"gwp_asan_unittests\": \"0e8095faecbe7f44f5035406b850c57e5ffc220c\", \"interactive_ui_tests\": \"69e6afaababb958dbb2e56cc315c4acc7a785a5b\", \"ipc_tests\": \"149743271bb543f53b30b1d6f4396782f85aef6b\", \"jingle_unittests\": \"2f6cd16039fa501cbbf3934c4de504c88ad92c7b\", \"keyboard_unittests\": \"ae421c31638498b4729625eee753bad8e59338af\", \"latency_unittests\": \"d28e8cb9b76dbb78574ede3ad3236b0891e40747\", \"libjingle_xmpp_unittests\": \"3eb5024a9cfb997fc80782539f0db0c46a090f57\", \"liburlpattern_unittests\": \"4c3ef8a4250cf7f84b0451487963a9249be0539f\", \"media_blink_unittests\": \"6cf84d4d9244dd898ed6d3d71f10a6b87e6540bd\", \"media_unittests\": \"f2d971b4f6dbfc4b2456c8e5f334d26f986b31db\", \"message_center_unittests\": \"c168b1d573605b05fb6dba81bb71a43e89dafdb9\", \"midi_unittests\": \"2059e0001f32e0732045939347e6dd06448ab04d\", \"mojo_core_unittests\": \"c4aa297a39e3b07926402f8a6138618237de2eb3\", \"mojo_unittests\": \"e0a91c3fdfb34596ee7accf84847d0863e9ceacd\", \"nacl_helper_nonsfi_unittests\": \"6a36f4e9e28a14305e4d2d8a33bca175bcd34fb7\", \"nacl_loader_unittests\": \"4ca864faf567c388032ae7a15c1965db57a2aeb7\", \"native_theme_unittests\": \"2c90f3a7af183ed8e2462a621348330ad05086c3\", \"net_unittests\": \"77ff5dc464b7441be1a42c3c88b9dd2dba73737b\", \"ozone_gl_unittests\": \"f26ad1aa71a32c60cfe85b617117282797e465ba\", \"ozone_unittests\": \"a681fdfb0c7919abda617990f13fb318776f5baa\", \"ozone_x11_unittests\": \"e414d6cf13e1f655ab80b38be3465e22a124188d\", \"pdf_unittests\": \"67fadc16aad216923661186f2275980e769c5fb5\", \"perfetto_unittests\": \"dc3fd748543250e12d8c6dd6ede92c4236c2fd25\", \"ppapi_unittests\": \"334ea99f9e50c87813cb8ca1c9d7e516f124676a\", \"printing_unittests\": \"48cd72e2e634cb757552e3dc8f0b2d58e5d564f3\", \"remoting_unittests\": \"26d8b391709bc16160a714fa310b96b047083226\", \"sandbox_linux_unittests\": \"fdde25d30ab0ae8ad6e09384845956ab90540c8f\", \"service_manager_unittests\": \"11b786f7c18e640bda6ab7895319e1279449c314\", \"services_unittests\": \"d1154b4d0c5871520df88a0a1dacd520382ed341\", \"shell_dialogs_unittests\": \"b2dd35a165302444af8153b5babf4a14b763be31\", \"shell_encryption_unittests\": \"e02a9c0df06d471fc52f70af9b78f1aacc6a5719\", \"skia_unittests\": \"a4695383978cb45a3588cc04638b2d3dc0c3b6c9\", \"snapshot_unittests\": \"d442fff70506acfb5a253992b7c95e658f103dfc\", \"sql_unittests\": \"b0df161a6595247caaf7deb17cf5435bfedbc970\", \"storage_unittests\": \"94355db98a2e4b85b1f001f773e4473b648a5d80\", \"sync_integration_tests\": \"ce8bd786febec09e8c341b8b6ad73373c473cfcf\", \"traffic_annotation_auditor_unittests\": \"fd2f469508fc5bbed18b4aa8cb165d1ed6629839\", \"ui_base_unittests\": \"44b8dba0dfe39e1845d7e86116a6e1d8b6c61871\", \"ui_chromeos_unittests\": \"5a15d8b80b5fceac5c54954116f1abc79e74740a\", \"ui_touch_selection_unittests\": \"fd2869b42493c469cd63a7b5ca28b743173e4ddc\", \"unit_tests\": \"956899688b0a26c365037d0ddd8962b735378023\", \"url_unittests\": \"863dfc25e57105b017224d7e908f39113cce4e16\", \"usage_time_limit_unittests\": \"339e7c333374157e43b2bc4665975cd26793833b\", \"views_unittests\": \"1fcf17b7c4f6757487b531a63905a5870a8bdd24\", \"viz_unittests\": \"e907a7f599c478184a682f24031aa8dc6f1bfbb0\", \"wayland_client_tests\": \"8b81745c71c0e2f8a128f414c6bc2fe9048333de\", \"wm_unittests\": \"b7035c953ceb6aede55b30dcdd52f1f307376b50\", \"wtf_unittests\": \"fde77a6b9b536830de4b9cea4baf211982a2e2e1\", \"zlib_unittests\": \"fa0c301f8b68a73fd52ed69359a4e96fa2a2dc14\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_8083860191ac1707bdac6310382d6ca226876fc8f88202f45d8d07453f74f5a4_v2\", \"builder_a8228d45a2e4046ab48e21b574ab916f6d3c7e4ad955b91040a9e9374d3dd18a_v2\", \"builder_b145c774831c5c42cee51952606378f966ad001ff04e0282f5dbcc16172748d9_v2\", \"builder_d4fdaf58b7947408531076283e0e8baf90c29d26a4447be7804b77c871ea449a_v2\", \"builder_fa0031875b7f2f11c46443055ab400bfaeb07a1d2a142b58c631314016c04e0a_v2\", \"builder_fbb3d50a702ddd064319c35d96f4b89f6e7a29ba4c11145a49be75e11fea82c5_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-44-4rk7\"], \"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\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615514040390506",
"status": "COMPLETED",
"status_changed_ts": "1615520416640388",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22958",
"builder:linux-chromeos-dbg",
"buildset:commit/git/d65514146b797e45b37109934ba885900d184ac4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d65514146b797e45b37109934ba885900d184ac4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018434530616437488",
"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/8853004468600849984/+/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:523cf5782cad5e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615520416645039",
"url": "https://ci.chromium.org/b/8853004468600849984",
"utcnow_ts": "1615520417005435"
}
}
[03:40:17.029] Invocation finished in 1h46m20.862062643s with status SUCCEEDED