[23:05:49.240] New invocation is queued and will start shortly
[23:05:50.468] Starting the invocation (attempt 1)
[23:05:50.520] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:05:50.520] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:05:50.520] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022884358899654336",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"84232eb6c4e3f2cf4cad5bd341d8b75f1d5ab0cd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9022884358899654336",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/84232eb6c4e3f2cf4cad5bd341d8b75f1d5ab0cd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/84232eb6c4e3f2cf4cad5bd341d8b75f1d5ab0cd",
"gitiles_ref:refs/heads/master"
]
}
[23:05:51.151] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611270350624326",
"id": "8857454296782800128",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"84232eb6c4e3f2cf4cad5bd341d8b75f1d5ab0cd\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611270351031698",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22293",
"builder:linux-chromeos-dbg",
"buildset:commit/git/84232eb6c4e3f2cf4cad5bd341d8b75f1d5ab0cd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/84232eb6c4e3f2cf4cad5bd341d8b75f1d5ab0cd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022884358899654336",
"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/8857454296782800128/+/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": "1611270351031828",
"url": "https://ci.chromium.org/b/8857454296782800128",
"utcnow_ts": "1611270351142606"
}
}
[23:05:51.151] Task URL: https://ci.chromium.org/b/8857454296782800128
[23:05:51.151] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:2:0) after 1m0s
[23:06:42.240] Received PubSub notification, asking Buildbucket for the build status
[23:06:42.414] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:51.217] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:2:0)
[23:06:51.217] Timer tick, asking Buildbucket for the build status
[23:06:51.342] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:51.342] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:4:0) after 1m0s
[23:07:51.363] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:4:0)
[23:07:51.363] Timer tick, asking Buildbucket for the build status
[23:07:51.560] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:51.560] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:5:0) after 1m0s
[23:08:51.626] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:5:0)
[23:08:51.626] Timer tick, asking Buildbucket for the build status
[23:08:51.715] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:51.715] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:6:0) after 1m0s
[23:09:51.734] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:6:0)
[23:09:51.734] Timer tick, asking Buildbucket for the build status
[23:09:52.052] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:52.052] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:7:0) after 1m0s
[23:10:52.095] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:7:0)
[23:10:52.095] Timer tick, asking Buildbucket for the build status
[23:10:52.277] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:52.277] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:8:0) after 1m0s
[23:11:52.296] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:8:0)
[23:11:52.296] Timer tick, asking Buildbucket for the build status
[23:11:52.440] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:52.440] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:9:0) after 1m0s
[23:12:52.496] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:9:0)
[23:12:52.496] Timer tick, asking Buildbucket for the build status
[23:12:52.680] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:52.680] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:10:0) after 1m0s
[23:13:52.805] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:10:0)
[23:13:52.805] Timer tick, asking Buildbucket for the build status
[23:13:53.111] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:53.111] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:11:0) after 1m0s
[23:14:53.123] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:11:0)
[23:14:53.123] Timer tick, asking Buildbucket for the build status
[23:14:53.225] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:53.225] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:12:0) after 1m0s
[23:15:53.285] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:12:0)
[23:15:53.285] Timer tick, asking Buildbucket for the build status
[23:15:53.485] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:53.485] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:13:0) after 1m0s
[23:16:53.491] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:13:0)
[23:16:53.491] Timer tick, asking Buildbucket for the build status
[23:16:53.822] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:53.822] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:14:0) after 1m0s
[23:17:53.895] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:14:0)
[23:17:53.895] Timer tick, asking Buildbucket for the build status
[23:17:53.980] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:53.980] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:15:0) after 1m0s
[23:18:54.036] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:15:0)
[23:18:54.036] Timer tick, asking Buildbucket for the build status
[23:18:54.175] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:54.175] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:16:0) after 1m0s
[23:19:54.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:16:0)
[23:19:54.311] Timer tick, asking Buildbucket for the build status
[23:19:54.403] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:54.403] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:17:0) after 1m0s
[23:20:54.695] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:17:0)
[23:20:54.695] Timer tick, asking Buildbucket for the build status
[23:20:55.725] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:55.725] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:18:0) after 1m0s
[23:21:55.966] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:18:0)
[23:21:55.966] Timer tick, asking Buildbucket for the build status
[23:21:56.160] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:56.160] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:19:0) after 1m0s
[23:22:56.478] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:19:0)
[23:22:56.478] Timer tick, asking Buildbucket for the build status
[23:22:56.652] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:56.652] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:20:0) after 1m0s
[23:23:56.876] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:20:0)
[23:23:56.876] Timer tick, asking Buildbucket for the build status
[23:23:56.968] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:56.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:21:0) after 1m0s
[23:24:57.150] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:21:0)
[23:24:57.150] Timer tick, asking Buildbucket for the build status
[23:24:57.774] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:57.774] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:22:0) after 1m0s
[23:25:57.963] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:22:0)
[23:25:57.963] Timer tick, asking Buildbucket for the build status
[23:25:58.254] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:58.254] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:23:0) after 1m0s
[23:26:58.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:23:0)
[23:26:58.371] Timer tick, asking Buildbucket for the build status
[23:26:58.766] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:58.766] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:24:0) after 1m0s
[23:27:58.765] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:24:0)
[23:27:58.765] Timer tick, asking Buildbucket for the build status
[23:27:59.130] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:59.130] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:25:0) after 1m0s
[23:28:59.109] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:25:0)
[23:28:59.109] Timer tick, asking Buildbucket for the build status
[23:28:59.462] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:59.462] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:26:0) after 1m0s
[23:29:59.471] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:26:0)
[23:29:59.471] Timer tick, asking Buildbucket for the build status
[23:29:59.541] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:59.541] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:27:0) after 1m0s
[23:30:59.633] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:27:0)
[23:30:59.633] Timer tick, asking Buildbucket for the build status
[23:30:59.927] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:59.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:28:0) after 1m0s
[23:31:59.964] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:28:0)
[23:31:59.964] Timer tick, asking Buildbucket for the build status
[23:32:00.644] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:00.644] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:29:0) after 1m0s
[23:33:00.685] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:29:0)
[23:33:00.685] Timer tick, asking Buildbucket for the build status
[23:33:00.802] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:00.802] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:30:0) after 1m0s
[23:34:01.493] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:30:0)
[23:34:01.493] Timer tick, asking Buildbucket for the build status
[23:34:01.672] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:01.672] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:31:0) after 1m0s
[23:35:01.751] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:31:0)
[23:35:01.751] Timer tick, asking Buildbucket for the build status
[23:35:01.861] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:01.861] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:32:0) after 1m0s
[23:36:01.950] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:32:0)
[23:36:01.951] Timer tick, asking Buildbucket for the build status
[23:36:02.134] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:02.134] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:33:0) after 1m0s
[23:37:02.159] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:33:0)
[23:37:02.159] Timer tick, asking Buildbucket for the build status
[23:37:02.408] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:02.408] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:34:0) after 1m0s
[23:38:02.439] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:34:0)
[23:38:02.439] Timer tick, asking Buildbucket for the build status
[23:38:02.551] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:02.551] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:35:0) after 1m0s
[23:39:02.636] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:35:0)
[23:39:02.636] Timer tick, asking Buildbucket for the build status
[23:39:02.962] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:02.962] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:36:0) after 1m0s
[23:40:03.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:36:0)
[23:40:03.003] Timer tick, asking Buildbucket for the build status
[23:40:03.211] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:03.211] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:37:0) after 1m0s
[23:41:03.237] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:37:0)
[23:41:03.237] Timer tick, asking Buildbucket for the build status
[23:41:03.781] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:03.781] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:38:0) after 1m0s
[23:42:03.869] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:38:0)
[23:42:03.869] Timer tick, asking Buildbucket for the build status
[23:42:04.086] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:04.086] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:39:0) after 1m0s
[23:43:04.131] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:39:0)
[23:43:04.131] Timer tick, asking Buildbucket for the build status
[23:43:04.692] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:04.692] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:40:0) after 1m0s
[23:44:04.723] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:40:0)
[23:44:04.723] Timer tick, asking Buildbucket for the build status
[23:44:04.781] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:04.781] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:41:0) after 1m0s
[23:45:04.766] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:41:0)
[23:45:04.766] Timer tick, asking Buildbucket for the build status
[23:45:05.157] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:05.157] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:42:0) after 1m0s
[23:46:05.187] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:42:0)
[23:46:05.187] Timer tick, asking Buildbucket for the build status
[23:46:05.505] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:05.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:43:0) after 1m0s
[23:47:05.549] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:43:0)
[23:47:05.549] Timer tick, asking Buildbucket for the build status
[23:47:05.969] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:05.969] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:44:0) after 1m0s
[23:48:06.030] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:44:0)
[23:48:06.030] Timer tick, asking Buildbucket for the build status
[23:48:06.211] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:06.211] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:45:0) after 1m0s
[23:49:06.300] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:45:0)
[23:49:06.300] Timer tick, asking Buildbucket for the build status
[23:49:06.601] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:06.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:46:0) after 1m0s
[23:50:06.642] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:46:0)
[23:50:06.642] Timer tick, asking Buildbucket for the build status
[23:50:06.724] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:06.724] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:47:0) after 1m0s
[23:51:06.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:47:0)
[23:51:06.746] Timer tick, asking Buildbucket for the build status
[23:51:07.301] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:07.301] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:48:0) after 1m0s
[23:52:07.354] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:48:0)
[23:52:07.354] Timer tick, asking Buildbucket for the build status
[23:52:07.902] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:07.902] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:49:0) after 1m0s
[23:53:07.949] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:49:0)
[23:53:07.949] Timer tick, asking Buildbucket for the build status
[23:53:08.172] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:08.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:50:0) after 1m0s
[23:54:08.226] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:50:0)
[23:54:08.226] Timer tick, asking Buildbucket for the build status
[23:54:08.353] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:08.353] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:51:0) after 1m0s
[23:55:08.405] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:51:0)
[23:55:08.405] Timer tick, asking Buildbucket for the build status
[23:55:08.584] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:08.584] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:52:0) after 1m0s
[23:56:08.616] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:52:0)
[23:56:08.616] Timer tick, asking Buildbucket for the build status
[23:56:10.133] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:10.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:53:0) after 1m0s
[23:57:10.149] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:53:0)
[23:57:10.149] Timer tick, asking Buildbucket for the build status
[23:57:10.349] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:10.349] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:54:0) after 1m0s
[23:58:10.396] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:54:0)
[23:58:10.396] Timer tick, asking Buildbucket for the build status
[23:58:10.613] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:10.613] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:55:0) after 1m0s
[23:59:10.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:55:0)
[23:59:10.641] Timer tick, asking Buildbucket for the build status
[23:59:11.954] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:11.954] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:56:0) after 1m0s
[00:00:11.990] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:56:0)
[00:00:11.990] Timer tick, asking Buildbucket for the build status
[00:00:12.353] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:12.354] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:57:0) after 1m0s
[00:01:12.457] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:57:0)
[00:01:12.457] Timer tick, asking Buildbucket for the build status
[00:01:12.743] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:12.743] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:58:0) after 1m0s
[00:02:12.826] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:58:0)
[00:02:12.826] Timer tick, asking Buildbucket for the build status
[00:02:12.930] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:12.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:59:0) after 1m0s
[00:03:12.957] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:59:0)
[00:03:12.957] Timer tick, asking Buildbucket for the build status
[00:03:13.192] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:13.192] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:60:0) after 1m0s
[00:04:13.232] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:60:0)
[00:04:13.232] Timer tick, asking Buildbucket for the build status
[00:04:13.346] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:13.346] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:61:0) after 1m0s
[00:05:13.373] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:61:0)
[00:05:13.373] Timer tick, asking Buildbucket for the build status
[00:05:13.576] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:13.576] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:62:0) after 1m0s
[00:06:13.583] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:62:0)
[00:06:13.583] Timer tick, asking Buildbucket for the build status
[00:06:13.835] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:13.835] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:63:0) after 1m0s
[00:07:13.904] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:63:0)
[00:07:13.904] Timer tick, asking Buildbucket for the build status
[00:07:14.387] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:14.387] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:64:0) after 1m0s
[00:08:14.458] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:64:0)
[00:08:14.458] Timer tick, asking Buildbucket for the build status
[00:08:15.036] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:15.036] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:65:0) after 1m0s
[00:09:15.043] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:65:0)
[00:09:15.043] Timer tick, asking Buildbucket for the build status
[00:09:15.184] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:15.184] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:66:0) after 1m0s
[00:10:15.257] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:66:0)
[00:10:15.257] Timer tick, asking Buildbucket for the build status
[00:10:15.468] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:15.468] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:67:0) after 1m0s
[00:11:15.738] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:67:0)
[00:11:15.738] Timer tick, asking Buildbucket for the build status
[00:11:16.232] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:16.232] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:68:0) after 1m0s
[00:12:16.449] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:68:0)
[00:12:16.449] Timer tick, asking Buildbucket for the build status
[00:12:16.558] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:16.558] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:69:0) after 1m0s
[00:13:17.408] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:69:0)
[00:13:17.408] Timer tick, asking Buildbucket for the build status
[00:13:17.561] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:17.561] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:70:0) after 1m0s
[00:14:17.742] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:70:0)
[00:14:17.742] Timer tick, asking Buildbucket for the build status
[00:14:18.067] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:18.067] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:71:0) after 1m0s
[00:15:18.137] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:71:0)
[00:15:18.137] Timer tick, asking Buildbucket for the build status
[00:15:18.799] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:18.799] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:72:0) after 1m0s
[00:16:18.873] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:72:0)
[00:16:18.873] Timer tick, asking Buildbucket for the build status
[00:16:19.095] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:19.095] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:73:0) after 1m0s
[00:17:19.267] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:73:0)
[00:17:19.267] Timer tick, asking Buildbucket for the build status
[00:17:20.555] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:20.555] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:74:0) after 1m0s
[00:18:20.682] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:74:0)
[00:18:20.682] Timer tick, asking Buildbucket for the build status
[00:18:20.802] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:20.802] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:75:0) after 1m0s
[00:19:20.954] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:75:0)
[00:19:20.954] Timer tick, asking Buildbucket for the build status
[00:19:21.163] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:21.163] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:76:0) after 1m0s
[00:20:21.263] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:76:0)
[00:20:21.263] Timer tick, asking Buildbucket for the build status
[00:20:21.499] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:21.499] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:77:0) after 1m0s
[00:21:21.547] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:77:0)
[00:21:21.548] Timer tick, asking Buildbucket for the build status
[00:21:21.730] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:21.730] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:78:0) after 1m0s
[00:22:21.863] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:78:0)
[00:22:21.863] Timer tick, asking Buildbucket for the build status
[00:22:21.984] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:21.984] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:79:0) after 1m0s
[00:23:22.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:79:0)
[00:23:22.003] Timer tick, asking Buildbucket for the build status
[00:23:22.138] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:22.138] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:80:0) after 1m0s
[00:24:22.363] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:80:0)
[00:24:22.363] Timer tick, asking Buildbucket for the build status
[00:24:22.457] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:22.457] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:81:0) after 1m0s
[00:25:22.511] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:81:0)
[00:25:22.511] Timer tick, asking Buildbucket for the build status
[00:25:24.827] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:24.827] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:82:0) after 1m0s
[00:26:25.135] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:82:0)
[00:26:25.135] Timer tick, asking Buildbucket for the build status
[00:26:25.325] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:25.325] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:83:0) after 1m0s
[00:27:25.408] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:83:0)
[00:27:25.408] Timer tick, asking Buildbucket for the build status
[00:27:25.947] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:25.947] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:84:0) after 1m0s
[00:28:25.946] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:84:0)
[00:28:25.946] Timer tick, asking Buildbucket for the build status
[00:28:26.721] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:26.721] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:85:0) after 1m0s
[00:29:26.888] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:85:0)
[00:29:26.888] Timer tick, asking Buildbucket for the build status
[00:29:26.975] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:26.975] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:86:0) after 1m0s
[00:30:27.033] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:86:0)
[00:30:27.033] Timer tick, asking Buildbucket for the build status
[00:30:28.528] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:28.528] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:87:0) after 1m0s
[00:31:28.597] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:87:0)
[00:31:28.597] Timer tick, asking Buildbucket for the build status
[00:31:29.102] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:29.102] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:88:0) after 1m0s
[00:32:29.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:88:0)
[00:32:29.154] Timer tick, asking Buildbucket for the build status
[00:32:29.244] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:29.244] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:89:0) after 1m0s
[00:33:29.283] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:89:0)
[00:33:29.283] Timer tick, asking Buildbucket for the build status
[00:33:29.699] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:29.699] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:90:0) after 1m0s
[00:34:29.780] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:90:0)
[00:34:29.780] Timer tick, asking Buildbucket for the build status
[00:34:29.869] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:29.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:91:0) after 1m0s
[00:35:29.890] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:91:0)
[00:35:29.890] Timer tick, asking Buildbucket for the build status
[00:35:31.127] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:31.127] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:92:0) after 1m0s
[00:36:31.250] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:92:0)
[00:36:31.250] Timer tick, asking Buildbucket for the build status
[00:36:31.475] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:31.475] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:93:0) after 1m0s
[00:37:31.655] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:93:0)
[00:37:31.655] Timer tick, asking Buildbucket for the build status
[00:37:31.780] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:31.780] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:94:0) after 1m0s
[00:38:31.821] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:94:0)
[00:38:31.821] Timer tick, asking Buildbucket for the build status
[00:38:31.899] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:31.900] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:95:0) after 1m0s
[00:39:31.929] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:95:0)
[00:39:31.929] Timer tick, asking Buildbucket for the build status
[00:39:31.999] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:31.999] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:96:0) after 1m0s
[00:40:32.099] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:96:0)
[00:40:32.099] Timer tick, asking Buildbucket for the build status
[00:40:32.465] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:32.465] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:97:0) after 1m0s
[00:41:32.521] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:97:0)
[00:41:32.521] Timer tick, asking Buildbucket for the build status
[00:41:32.851] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:32.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:98:0) after 1m0s
[00:42:32.929] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:98:0)
[00:42:32.929] Timer tick, asking Buildbucket for the build status
[00:42:34.407] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:34.407] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:99:0) after 1m0s
[00:43:34.502] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:99:0)
[00:43:34.502] Timer tick, asking Buildbucket for the build status
[00:43:34.715] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:34.715] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:100:0) after 1m0s
[00:44:34.837] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:100:0)
[00:44:34.837] Timer tick, asking Buildbucket for the build status
[00:44:34.959] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:34.959] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:101:0) after 1m0s
[00:45:34.994] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:101:0)
[00:45:34.994] Timer tick, asking Buildbucket for the build status
[00:45:35.119] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:35.119] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:102:0) after 1m0s
[00:46:35.209] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:102:0)
[00:46:35.209] Timer tick, asking Buildbucket for the build status
[00:46:38.133] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:38.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:103:0) after 1m0s
[00:47:38.166] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:103:0)
[00:47:38.166] Timer tick, asking Buildbucket for the build status
[00:47:38.480] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:38.480] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:104:0) after 1m0s
[00:48:38.517] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:104:0)
[00:48:38.517] Timer tick, asking Buildbucket for the build status
[00:48:39.805] Build 8857454296782800128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:39.805] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022884358899654336:105:0) after 1m0s
[00:49:04.479] Received PubSub notification, asking Buildbucket for the build status
[00:49:04.747] Build 8857454296782800128: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:49:04.747] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611276541897924",
"created_by": "project:chromium",
"created_ts": "1611270350624326",
"id": "8857454296782800128",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"84232eb6c4e3f2cf4cad5bd341d8b75f1d5ab0cd\"}}",
"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\": \"84232eb6c4e3f2cf4cad5bd341d8b75f1d5ab0cd\", \"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-103-gfdv\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22293, \"got_angle_revision\": \"203154b52a462070e774072abb3c71882bd9b61e\", \"got_dawn_revision\": \"001b499275e1354cc1198f5e7a50509aabe28be7\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"84232eb6c4e3f2cf4cad5bd341d8b75f1d5ab0cd\", \"got_revision_cp\": \"refs/heads/master@{#845828}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1d5174b9dc3be500d63daf227b163b5e86bbc7b5\", \"got_v8_revision\": \"bba017b40897bdf1fed939a76260569fe84bdc71\", \"got_v8_revision_cp\": \"refs/heads/9.0.42@{#1}\", \"got_webrtc_revision\": \"6031b74664d0be40d7f913600d17f6d8d1323729\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33052}\", \"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\": \"84232eb6c4e3f2cf4cad5bd341d8b75f1d5ab0cd\", \"swarm_hashes_refs/heads/master(at){#845828}_without_patch\": {\"absl_hardening_tests\": \"ac6d30635078ada2d338795676063e74f47f734b\", \"accessibility_unittests\": \"531fc85b2e67743fbb5c01bd268c9079a7dbcfad\", \"angle_unittests\": \"b57d9ffd829e1e2d87df37cd6ab81bdf9f3bac9b\", \"app_list_unittests\": \"e574dd1f9a12d054fec10bbc344b20d0195f43e1\", \"app_shell_unittests\": \"d2264b44dd85c1a449b02f8702aef12b84719856\", \"ash_unittests\": \"b44a4c03dc3801a5ca2e89657ed08aba10526e8e\", \"aura_unittests\": \"24c5cf8df5d95d4169d19c60627cb4c3009c5ba8\", \"base_unittests\": \"5b46d0d5370233b36f2f8e8550a9d2b29476953c\", \"base_util_unittests\": \"2cfa2104d2e72cf7320e5d7491ade1b6af1feee6\", \"blink_common_unittests\": \"5b9bced5a15938b0badbdf7a0d0e71e00be68573\", \"blink_fuzzer_unittests\": \"ac171a1ad058901c0b2fb99fb176ae332ea9f3fa\", \"blink_heap_unittests\": \"d4d88a91281b60cfe933d5606ada10e2a620ba8d\", \"blink_platform_unittests\": \"cef5a154e9dedfbdc11f6039f107662ceefea328\", \"blink_unittests\": \"1cab1e58f1c5fdf487b464bff915f76b5a61bca6\", \"boringssl_crypto_tests\": \"3abce227d441e342e5afdf20cd7744406865fa4b\", \"boringssl_ssl_tests\": \"573832023fa0d96d892e5a4d18a138edc36a61e3\", \"browser_tests\": \"4b0dbd43ab7f913d30febce5854c095a41720102\", \"capture_unittests\": \"05adb0f2fdf367e7082ad0614a3824c5a1149a6a\", \"cast_unittests\": \"c940cec127caf4244d6d9532ac2c57e33f7738e8\", \"cc_unittests\": \"414ce4bd229acaa55d7109bd268441164951ecce\", \"chrome_app_unittests\": \"861ba0b8b90263e6aae1f22ede4a342a7012f32d\", \"chromedriver_unittests\": \"ba48187ade6013c1a96f32282db0cf3857192fe0\", \"chromeos_components_unittests\": \"62101848310584b8b96b782f124f2ef2f3e273b1\", \"chromeos_unittests\": \"c20ce3c7f7e08cb24d4be01ade5b0fca510bdc21\", \"components_browsertests\": \"cf57c609389b6a2a1118ce5ca2145d9bd9e28a5e\", \"components_unittests\": \"bc9905f8fa55a10c82808bdf80af554495724110\", \"compositor_unittests\": \"9f897d5e2781589a9fc110c87c5ca30f0703b7c2\", \"content_browsertests\": \"e346495489420aa4de029637160dd269545966dd\", \"content_unittests\": \"c43a5a4ad2ceebddde408ce46a23c7243067c1f7\", \"crashpad_tests\": \"a5e9e5c29d670bbb7b32f1369273ad1e44404d38\", \"crypto_unittests\": \"0360d09e79ecd88a352053e6ebfc2e3ce6590fb1\", \"dbus_unittests\": \"8a45ed8acc29840d1545b270e6d6e3100e604f9e\", \"device_unittests\": \"8747e79b30f34c55b5d69f5a2a7fc5b0ada16ca0\", \"display_unittests\": \"bb8eb7108bd6e99fefc24a7832197ce044a34a12\", \"events_unittests\": \"26448f09e317a7eb5dd125383d288093c6e1207d\", \"exo_unittests\": \"1932785269d2a8618f9c6395d63d18c67faf1a7b\", \"extensions_browsertests\": \"ce6747dafa9b659743711c8eb643a3093aa18205\", \"extensions_unittests\": \"8050e919ae534ccd607985ea83c0fbc3a7059c4e\", \"filesystem_service_unittests\": \"155901251cbc0ec0aa67d6dd60611c83b2536515\", \"gcm_unit_tests\": \"d1ea17c2c9e40fa756fe65ab350b21ef60902626\", \"gfx_unittests\": \"07a0da47032aa1227f229544e31343fbacb30c9a\", \"gin_unittests\": \"cad10dd7e0c9fe0b707f14370dd57571a1ba9a56\", \"gl_unittests_ozone\": \"2a730aea303cfb9b311982b1a0e0d070bad45fa2\", \"google_apis_unittests\": \"870e743a91d633c9d79a555874ae595bdde3b757\", \"gpu_unittests\": \"faa8dccf69c2e67d4c0f0130954443f890d7b10e\", \"gwp_asan_unittests\": \"b4f87e6b51537178d4439892684d3e048aa32b06\", \"interactive_ui_tests\": \"f8e580f23df148ae4487c9691c742660932d612a\", \"ipc_tests\": \"902a30b9ab85ff17b9843dbc930cdde4ecbf086b\", \"jingle_unittests\": \"9d67da82ad3f7b18fb5bdd2d9ab724fbf91062de\", \"keyboard_unittests\": \"7ba3b096abade3c843fb378447588996d72d7a96\", \"latency_unittests\": \"eb967aaa8c2cc4d67ed3b3d4d08d7dc3a7240ed8\", \"libjingle_xmpp_unittests\": \"f2fc499a627de98d79e559e8b3244f7f71f4804f\", \"liburlpattern_unittests\": \"4d61fb81730bb59e2714ca585ea8f4f5aefd8544\", \"media_blink_unittests\": \"ca21e7b8720905ce4959ea2bb73513342ef24374\", \"media_unittests\": \"50207332574e0d8b7143bed236e40dc102cb877f\", \"message_center_unittests\": \"d96a214cb8a6040a9cd1de9f7bf8fca5fa61869b\", \"midi_unittests\": \"7f9a6dd2ee8009bb57d812655a8f8532cdd45a3d\", \"mojo_core_unittests\": \"6b99ce33b17d762e4add3e3d6840d57736457d33\", \"mojo_unittests\": \"9cb36f83c58296f251f9ff7fe74be7f551e2c8bb\", \"nacl_helper_nonsfi_unittests\": \"959044966230bfd824efefe0370ebc7d5dfdaf0c\", \"nacl_loader_unittests\": \"d61f490a5f8f49613b9fde3d0cb09ae815cccd6e\", \"native_theme_unittests\": \"5756f8b3d8f3196f5d9243abbc7c2add2832db28\", \"net_unittests\": \"c970d9c1c60d005047b6ca98de6d3e8d3e1aad20\", \"ozone_gl_unittests\": \"9a3fe31e2183cdb51d4ec3ea9dc4c118b0aeafe8\", \"ozone_unittests\": \"05b25ed77be40b0c1ab43180a646f6eb8e4918ce\", \"ozone_x11_unittests\": \"6d6499edb9075c7e907471d650d9dbe2467f8a65\", \"pdf_unittests\": \"9d0e637ceb88b2c94fcc683bbecf96a4e8a5948f\", \"perfetto_unittests\": \"625fc3c73cd20457ca912fc48dcffa11d59e55b5\", \"ppapi_unittests\": \"6c0d7a869eed0dca561865068feb92f6726477c1\", \"printing_unittests\": \"3fef7440d535f4226954bac1914367ace59ea87d\", \"remoting_unittests\": \"7d3a2d397c6573cef94e6dad86cf78b5291cbe60\", \"sandbox_linux_unittests\": \"dffd84acfd90946fcb8144098bb41b421b4b1ee6\", \"service_manager_unittests\": \"77f53d4a0cdfd42399d5cccf22d07648b038efc9\", \"services_unittests\": \"5a25cab6289c0d2ec67b0eaf7e529566617df17e\", \"shell_dialogs_unittests\": \"8d9ee33b2b602e9f0f4c28906e8c0b9565bd9581\", \"shell_encryption_unittests\": \"22992f1be161a7254aaaca94764cb5836e485099\", \"skia_unittests\": \"09b98a43fad4ef63c9f3be791e28f142da702560\", \"snapshot_unittests\": \"ac02c37edf5e66481a8d19c29c44c630f8a70160\", \"sql_unittests\": \"1444e2c8b627273b78e189018d4e8e0c292837e5\", \"storage_unittests\": \"12bd779fa2c43d276f2d0ae5f4ab7646ed2a6806\", \"sync_integration_tests\": \"ac64c1b2f5ab8fae778bcd3c2e1807fc10bcbe31\", \"traffic_annotation_auditor_unittests\": \"74f6c7488f13307b628e50d891cb5ada82262bdc\", \"ui_base_unittests\": \"415062bbc6efbec737b30925f9ced8883912cb18\", \"ui_chromeos_unittests\": \"6f147675e80d348431871c348d02fd152231c67a\", \"ui_touch_selection_unittests\": \"c3d22e54ea5df2264adf5a5544117111b2044f4e\", \"unit_tests\": \"325e3ec13e243940cdb94374c788c0eb4f752c26\", \"url_unittests\": \"dee29f18ead17fd6e3e3ce1d525e5a9cef6b1b08\", \"usage_time_limit_unittests\": \"f33485691b40fba5743e66bf657cd67f9b2535b8\", \"views_unittests\": \"2ee559619dd5cd49a54a75370d7932deb1997a5a\", \"viz_unittests\": \"9aed1e46cd5d82b1c9756b35018018b49a3d26d1\", \"wm_unittests\": \"9dae11bbd583cfcf2a52fe81c2c5e0ee6f9d7549\", \"wtf_unittests\": \"279f9ef95b449259007d4fadffe41aa2cacd00ae\", \"zlib_unittests\": \"0684e145921fcbc00f481903b31cad1c1f66b282\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_03bc629269a55e89b115ec4e4b472997df6f6f4caff479f65f24a3c64aef44c9_v2\", \"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_70f3607e3f7d84b5c3698c0eed74813ee5c700477d5aec13835ae2ecc5ee2d5b_v2\", \"builder_bee64b857be6df8b7c1ab34ae7b2c6eab296c35495667b8b8424b2083d103b1e_v2\", \"builder_cfdb55a1f65ed072d1fe07f7fc1107a97a5e848a8d1b6b0015401ab521f6b501_v2\", \"builder_ea4e7feff896b82ff6619a3b9c5371d5e74ebdd508befd295e2f5ab02f5eefaf_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-103-gfdv\"], \"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\": [\"5671-1d9dea8\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611270401312169",
"status": "COMPLETED",
"status_changed_ts": "1611276542588645",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22293",
"builder:linux-chromeos-dbg",
"buildset:commit/git/84232eb6c4e3f2cf4cad5bd341d8b75f1d5ab0cd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/84232eb6c4e3f2cf4cad5bd341d8b75f1d5ab0cd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022884358899654336",
"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/8857454296782800128/+/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:514003f1931d4310",
"user_agent:luci-scheduler"
],
"updated_ts": "1611276542628499",
"url": "https://ci.chromium.org/b/8857454296782800128",
"utcnow_ts": "1611276544732316"
}
}
[00:49:04.747] Invocation finished in 1h43m15.525785399s with status SUCCEEDED