[23:43:14.282] New invocation is queued and will start shortly
[23:43:15.472] Starting the invocation (attempt 1)
[23:43:15.521] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:43:15.521] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:43:15.521] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9071894963628231104",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"a12f9083713465e3cb338525678370c9949ecf92\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9071894963628231104",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/a12f9083713465e3cb338525678370c9949ecf92",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a12f9083713465e3cb338525678370c9949ecf92",
"gitiles_ref:refs/heads/master"
]
}
[23:43:18.403] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1564530196574410",
"id": "8906464900555183920",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a12f9083713465e3cb338525678370c9949ecf92\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1564530197938094",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/13654",
"builder:linux-chromeos-dbg",
"buildset:commit/git/a12f9083713465e3cb338525678370c9949ecf92",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a12f9083713465e3cb338525678370c9949ecf92",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9071894963628231104",
"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/8906464900555183920/+/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": "1564530197938248",
"url": "https://ci.chromium.org/b/8906464900555183920",
"utcnow_ts": "1564530198374156"
}
}
[23:43:18.403] Task URL: https://ci.chromium.org/b/8906464900555183920
[23:43:18.403] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:2:0) after 1m0s
[23:44:18.502] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:2:0)
[23:44:18.502] Timer tick, asking Buildbucket for the build status
[23:44:18.734] Build 8906464900555183920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:18.734] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:3:0) after 1m0s
[23:45:18.754] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:3:0)
[23:45:18.754] Timer tick, asking Buildbucket for the build status
[23:45:19.996] Build 8906464900555183920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:19.996] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:4:0) after 1m0s
[23:46:20.061] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:4:0)
[23:46:20.061] Timer tick, asking Buildbucket for the build status
[23:46:20.605] Build 8906464900555183920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:20.605] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:5:0) after 1m0s
[23:47:20.624] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:5:0)
[23:47:20.624] Timer tick, asking Buildbucket for the build status
[23:47:20.796] Build 8906464900555183920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:20.796] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:6:0) after 1m0s
[23:48:20.852] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:6:0)
[23:48:20.852] Timer tick, asking Buildbucket for the build status
[23:48:22.447] Build 8906464900555183920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:22.447] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:7:0) after 1m0s
[23:49:02.680] Received PubSub notification, asking Buildbucket for the build status
[23:49:03.926] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:22.467] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:7:0)
[23:49:22.467] Timer tick, asking Buildbucket for the build status
[23:49:23.028] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:23.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:9:0) after 1m0s
[23:50:23.670] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:9:0)
[23:50:23.670] Timer tick, asking Buildbucket for the build status
[23:50:24.004] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:24.004] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:10:0) after 1m0s
[23:51:24.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:10:0)
[23:51:24.057] Timer tick, asking Buildbucket for the build status
[23:51:24.633] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:24.633] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:11:0) after 1m0s
[23:52:24.612] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:11:0)
[23:52:24.612] Timer tick, asking Buildbucket for the build status
[23:52:24.895] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:24.895] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:12:0) after 1m0s
[23:53:24.908] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:12:0)
[23:53:24.908] Timer tick, asking Buildbucket for the build status
[23:53:26.625] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:26.625] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:13:0) after 1m0s
[23:54:26.717] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:13:0)
[23:54:26.717] Timer tick, asking Buildbucket for the build status
[23:54:26.923] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:26.923] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:14:0) after 1m0s
[23:55:26.955] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:14:0)
[23:55:26.955] Timer tick, asking Buildbucket for the build status
[23:55:27.570] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:27.570] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:15:0) after 1m0s
[23:56:27.794] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:15:0)
[23:56:27.794] Timer tick, asking Buildbucket for the build status
[23:56:27.940] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:27.940] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:16:0) after 1m0s
[23:57:28.217] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:16:0)
[23:57:28.217] Timer tick, asking Buildbucket for the build status
[23:57:28.500] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:28.500] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:17:0) after 1m0s
[23:58:28.571] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:17:0)
[23:58:28.571] Timer tick, asking Buildbucket for the build status
[23:58:29.457] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:29.457] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:18:0) after 1m0s
[23:59:29.493] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:18:0)
[23:59:29.493] Timer tick, asking Buildbucket for the build status
[23:59:30.500] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:30.500] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:19:0) after 1m0s
[00:00:30.598] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:19:0)
[00:00:30.598] Timer tick, asking Buildbucket for the build status
[00:00:30.849] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:30.849] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:20:0) after 1m0s
[00:01:30.859] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:20:0)
[00:01:30.859] Timer tick, asking Buildbucket for the build status
[00:01:31.033] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:31.033] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:21:0) after 1m0s
[00:02:31.106] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:21:0)
[00:02:31.106] Timer tick, asking Buildbucket for the build status
[00:02:31.587] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:31.587] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:22:0) after 1m0s
[00:03:31.694] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:22:0)
[00:03:31.694] Timer tick, asking Buildbucket for the build status
[00:03:32.403] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:32.403] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:23:0) after 1m0s
[00:04:32.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:23:0)
[00:04:32.447] Timer tick, asking Buildbucket for the build status
[00:04:33.841] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:33.841] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:24:0) after 1m0s
[00:05:33.867] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:24:0)
[00:05:33.867] Timer tick, asking Buildbucket for the build status
[00:05:34.118] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:34.118] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:25:0) after 1m0s
[00:06:34.126] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:25:0)
[00:06:34.126] Timer tick, asking Buildbucket for the build status
[00:06:34.892] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:34.892] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:26:0) after 1m0s
[00:07:34.996] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:26:0)
[00:07:34.996] Timer tick, asking Buildbucket for the build status
[00:07:35.426] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:35.426] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:27:0) after 1m0s
[00:08:35.470] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:27:0)
[00:08:35.470] Timer tick, asking Buildbucket for the build status
[00:08:36.557] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:36.557] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:28:0) after 1m0s
[00:09:36.579] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:28:0)
[00:09:36.579] Timer tick, asking Buildbucket for the build status
[00:09:37.021] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:37.021] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:29:0) after 1m0s
[00:10:37.143] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:29:0)
[00:10:37.143] Timer tick, asking Buildbucket for the build status
[00:10:39.596] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:39.596] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:30:0) after 1m0s
[00:11:39.659] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:30:0)
[00:11:39.659] Timer tick, asking Buildbucket for the build status
[00:11:40.786] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:40.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:31:0) after 1m0s
[00:12:40.887] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:31:0)
[00:12:40.888] Timer tick, asking Buildbucket for the build status
[00:12:41.231] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:41.231] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:32:0) after 1m0s
[00:13:41.254] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:32:0)
[00:13:41.254] Timer tick, asking Buildbucket for the build status
[00:13:46.335] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:46.335] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:33:0) after 1m0s
[00:14:46.413] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:33:0)
[00:14:46.413] Timer tick, asking Buildbucket for the build status
[00:14:46.612] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:46.612] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:34:0) after 1m0s
[00:15:46.673] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:34:0)
[00:15:46.673] Timer tick, asking Buildbucket for the build status
[00:15:47.614] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:47.614] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:35:0) after 1m0s
[00:16:48.068] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:35:0)
[00:16:48.068] Timer tick, asking Buildbucket for the build status
[00:16:48.788] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:48.788] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:36:0) after 1m0s
[00:17:48.860] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:36:0)
[00:17:48.860] Timer tick, asking Buildbucket for the build status
[00:17:49.385] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:49.385] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:37:0) after 1m0s
[00:18:49.412] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:37:0)
[00:18:49.412] Timer tick, asking Buildbucket for the build status
[00:18:50.964] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:50.964] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:38:0) after 1m0s
[00:19:51.010] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:38:0)
[00:19:51.010] Timer tick, asking Buildbucket for the build status
[00:19:51.800] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:51.800] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:39:0) after 1m0s
[00:20:51.863] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:39:0)
[00:20:51.863] Timer tick, asking Buildbucket for the build status
[00:20:52.259] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:52.259] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:40:0) after 1m0s
[00:21:52.315] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:40:0)
[00:21:52.315] Timer tick, asking Buildbucket for the build status
[00:21:53.786] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:53.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:41:0) after 1m0s
[00:22:53.857] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:41:0)
[00:22:53.857] Timer tick, asking Buildbucket for the build status
[00:22:55.536] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:55.536] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:42:0) after 1m0s
[00:23:55.563] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:42:0)
[00:23:55.563] Timer tick, asking Buildbucket for the build status
[00:23:55.946] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:55.946] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:43:0) after 1m0s
[00:24:56.005] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:43:0)
[00:24:56.005] Timer tick, asking Buildbucket for the build status
[00:24:57.723] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:57.723] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:44:0) after 1m0s
[00:25:57.795] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:44:0)
[00:25:57.795] Timer tick, asking Buildbucket for the build status
[00:25:58.279] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:58.279] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:45:0) after 1m0s
[00:26:58.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:45:0)
[00:26:58.281] Timer tick, asking Buildbucket for the build status
[00:26:58.459] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:58.459] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:46:0) after 1m0s
[00:27:58.486] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:46:0)
[00:27:58.486] Timer tick, asking Buildbucket for the build status
[00:27:58.780] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:58.780] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:47:0) after 1m0s
[00:28:58.864] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:47:0)
[00:28:58.864] Timer tick, asking Buildbucket for the build status
[00:29:00.084] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:00.084] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:48:0) after 1m0s
[00:30:00.158] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:48:0)
[00:30:00.158] Timer tick, asking Buildbucket for the build status
[00:30:01.225] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:01.225] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:49:0) after 1m0s
[00:31:01.285] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:49:0)
[00:31:01.285] Timer tick, asking Buildbucket for the build status
[00:31:01.548] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:01.548] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:50:0) after 1m0s
[00:32:01.576] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:50:0)
[00:32:01.576] Timer tick, asking Buildbucket for the build status
[00:32:02.396] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:02.396] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:51:0) after 1m0s
[00:33:02.476] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:51:0)
[00:33:02.476] Timer tick, asking Buildbucket for the build status
[00:33:02.699] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:02.699] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:52:0) after 1m0s
[00:34:02.787] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:52:0)
[00:34:02.787] Timer tick, asking Buildbucket for the build status
[00:34:03.601] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:03.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:53:0) after 1m0s
[00:35:03.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:53:0)
[00:35:03.641] Timer tick, asking Buildbucket for the build status
[00:35:04.481] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:04.481] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:54:0) after 1m0s
[00:36:04.576] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:54:0)
[00:36:04.576] Timer tick, asking Buildbucket for the build status
[00:36:05.271] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:05.271] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:55:0) after 1m0s
[00:37:05.291] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:55:0)
[00:37:05.291] Timer tick, asking Buildbucket for the build status
[00:37:05.949] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:05.949] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:56:0) after 1m0s
[00:38:06.008] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:56:0)
[00:38:06.008] Timer tick, asking Buildbucket for the build status
[00:38:06.223] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:06.223] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:57:0) after 1m0s
[00:39:06.284] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:57:0)
[00:39:06.284] Timer tick, asking Buildbucket for the build status
[00:39:06.487] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:06.487] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:58:0) after 1m0s
[00:40:06.558] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:58:0)
[00:40:06.558] Timer tick, asking Buildbucket for the build status
[00:40:07.001] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:07.001] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:59:0) after 1m0s
[00:41:07.054] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:59:0)
[00:41:07.054] Timer tick, asking Buildbucket for the build status
[00:41:07.535] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:07.535] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:60:0) after 1m0s
[00:42:07.565] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:60:0)
[00:42:07.565] Timer tick, asking Buildbucket for the build status
[00:42:08.646] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:08.646] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:61:0) after 1m0s
[00:43:08.698] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:61:0)
[00:43:08.698] Timer tick, asking Buildbucket for the build status
[00:43:10.198] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:10.198] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:62:0) after 1m0s
[00:44:10.318] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:62:0)
[00:44:10.318] Timer tick, asking Buildbucket for the build status
[00:44:10.683] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:10.683] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:63:0) after 1m0s
[00:45:10.791] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:63:0)
[00:45:10.791] Timer tick, asking Buildbucket for the build status
[00:45:11.610] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:11.610] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:64:0) after 1m0s
[00:46:11.649] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:64:0)
[00:46:11.649] Timer tick, asking Buildbucket for the build status
[00:46:12.657] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:12.657] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:65:0) after 1m0s
[00:47:12.805] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:65:0)
[00:47:12.805] Timer tick, asking Buildbucket for the build status
[00:47:12.971] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:12.971] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:66:0) after 1m0s
[00:48:13.099] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:66:0)
[00:48:13.099] Timer tick, asking Buildbucket for the build status
[00:48:13.605] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:13.605] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:67:0) after 1m0s
[00:49:13.679] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:67:0)
[00:49:13.679] Timer tick, asking Buildbucket for the build status
[00:49:15.264] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:15.264] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:68:0) after 1m0s
[00:50:15.317] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:68:0)
[00:50:15.317] Timer tick, asking Buildbucket for the build status
[00:50:15.881] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:15.881] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:69:0) after 1m0s
[00:51:15.979] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:69:0)
[00:51:15.979] Timer tick, asking Buildbucket for the build status
[00:51:17.257] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:17.257] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:70:0) after 1m0s
[00:52:17.243] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:70:0)
[00:52:17.243] Timer tick, asking Buildbucket for the build status
[00:52:18.007] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:18.007] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:71:0) after 1m0s
[00:53:18.164] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:71:0)
[00:53:18.164] Timer tick, asking Buildbucket for the build status
[00:53:18.918] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:18.918] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:72:0) after 1m0s
[00:54:19.028] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:72:0)
[00:54:19.028] Timer tick, asking Buildbucket for the build status
[00:54:21.351] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:21.351] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:73:0) after 1m0s
[00:55:21.586] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:73:0)
[00:55:21.586] Timer tick, asking Buildbucket for the build status
[00:55:22.275] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:22.275] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:74:0) after 1m0s
[00:56:22.474] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:74:0)
[00:56:22.474] Timer tick, asking Buildbucket for the build status
[00:56:22.841] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:22.842] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:75:0) after 1m0s
[00:57:22.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:75:0)
[00:57:22.879] Timer tick, asking Buildbucket for the build status
[00:57:23.202] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:23.202] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:76:0) after 1m0s
[00:58:23.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:76:0)
[00:58:23.281] Timer tick, asking Buildbucket for the build status
[00:58:24.911] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:24.911] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:77:0) after 1m0s
[00:59:25.204] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:77:0)
[00:59:25.204] Timer tick, asking Buildbucket for the build status
[00:59:26.032] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:26.032] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:78:0) after 1m0s
[01:00:26.035] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:78:0)
[01:00:26.035] Timer tick, asking Buildbucket for the build status
[01:00:26.441] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:26.441] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:79:0) after 1m0s
[01:01:26.599] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:79:0)
[01:01:26.599] Timer tick, asking Buildbucket for the build status
[01:01:26.832] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:26.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:80:0) after 1m0s
[01:02:26.976] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:80:0)
[01:02:26.976] Timer tick, asking Buildbucket for the build status
[01:02:27.444] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:27.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:81:0) after 1m0s
[01:03:27.449] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:81:0)
[01:03:27.449] Timer tick, asking Buildbucket for the build status
[01:03:28.303] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:28.303] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:82:0) after 1m0s
[01:04:28.436] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:82:0)
[01:04:28.436] Timer tick, asking Buildbucket for the build status
[01:04:28.672] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:28.672] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:83:0) after 1m0s
[01:05:28.861] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:83:0)
[01:05:28.861] Timer tick, asking Buildbucket for the build status
[01:05:29.362] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:29.362] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:84:0) after 1m0s
[01:06:29.443] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:84:0)
[01:06:29.443] Timer tick, asking Buildbucket for the build status
[01:06:30.834] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:30.834] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:85:0) after 1m0s
[01:07:30.972] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:85:0)
[01:07:30.972] Timer tick, asking Buildbucket for the build status
[01:07:31.210] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:31.210] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:86:0) after 1m0s
[01:08:31.243] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:86:0)
[01:08:31.243] Timer tick, asking Buildbucket for the build status
[01:08:32.574] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:32.574] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:87:0) after 1m0s
[01:09:32.629] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:87:0)
[01:09:32.629] Timer tick, asking Buildbucket for the build status
[01:09:32.951] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:32.952] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:88:0) after 1m0s
[01:10:32.963] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:88:0)
[01:10:32.963] Timer tick, asking Buildbucket for the build status
[01:10:33.184] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:33.184] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:89:0) after 1m0s
[01:11:33.259] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:89:0)
[01:11:33.259] Timer tick, asking Buildbucket for the build status
[01:11:33.452] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:33.452] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:90:0) after 1m0s
[01:12:33.719] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:90:0)
[01:12:33.719] Timer tick, asking Buildbucket for the build status
[01:12:34.327] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:34.327] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:91:0) after 1m0s
[01:13:34.433] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:91:0)
[01:13:34.433] Timer tick, asking Buildbucket for the build status
[01:13:35.308] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:35.308] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:92:0) after 1m0s
[01:14:35.573] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:92:0)
[01:14:35.573] Timer tick, asking Buildbucket for the build status
[01:14:35.888] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:35.888] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:93:0) after 1m0s
[01:15:36.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:93:0)
[01:15:36.003] Timer tick, asking Buildbucket for the build status
[01:15:37.429] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:37.429] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:94:0) after 1m0s
[01:16:37.604] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:94:0)
[01:16:37.604] Timer tick, asking Buildbucket for the build status
[01:16:38.622] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:38.623] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:95:0) after 1m0s
[01:17:38.749] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:95:0)
[01:17:38.749] Timer tick, asking Buildbucket for the build status
[01:17:38.920] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:38.920] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:96:0) after 1m0s
[01:18:39.080] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:96:0)
[01:18:39.080] Timer tick, asking Buildbucket for the build status
[01:18:39.661] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:39.661] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:97:0) after 1m0s
[01:19:39.841] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:97:0)
[01:19:39.841] Timer tick, asking Buildbucket for the build status
[01:19:40.336] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:40.336] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:98:0) after 1m0s
[01:20:40.511] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:98:0)
[01:20:40.511] Timer tick, asking Buildbucket for the build status
[01:20:40.927] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:40.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:99:0) after 1m0s
[01:21:41.064] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:99:0)
[01:21:41.064] Timer tick, asking Buildbucket for the build status
[01:21:41.600] Build 8906464900555183920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:41.600] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9071894963628231104:100:0) after 1m0s
[01:21:53.959] Received PubSub notification, asking Buildbucket for the build status
[01:21:54.384] Build 8906464900555183920: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[01:21:54.384] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1564536112429384",
"created_by": "project:chromium",
"created_ts": "1564530196574410",
"failure_reason": "BUILD_FAILURE",
"id": "8906464900555183920",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a12f9083713465e3cb338525678370c9949ecf92\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- DeviceScheduledUpdateCheckerTest.CheckMonthlyRolloverLogic\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-89-znre\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 13654, \"got_angle_revision\": \"1ebe6014389e022dfb1d89ae47cb8b4c7593a733\", \"got_dawn_revision\": \"35ad5221fb556c7aa80961d42537e4d9c825bf49\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"a12f9083713465e3cb338525678370c9949ecf92\", \"got_revision_cp\": \"refs/heads/master@{#682514}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"2ddb2cc31c4ab5b2d15ab4d99d2278f43dec0c45\", \"got_v8_revision_cp\": \"refs/heads/7.8.30@{#1}\", \"got_webrtc_revision\": \"4ed5b087f57fe8b116966ee2e60be2d3785975be\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28709}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.chromiumos\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a12f9083713465e3cb338525678370c9949ecf92\", \"swarm_hashes\": {\"accessibility_unittests\": \"77631947530034339bbcd37ebadb6ef0c5359f2a\", \"angle_unittests\": \"343644a4f1ee1af56c1bc90640bc08529d5fab34\", \"app_list_unittests\": \"ecd361d039851cbf38b436e2a09704afadffd3ff\", \"app_shell_unittests\": \"cee6cfbd4f90e551e5059ccc4c573e58e9988182\", \"ash_unittests\": \"be81590ec82298f50508f12caefceba370f1f3e1\", \"aura_unittests\": \"1f3ea7401602f7eb52b96d0d8c7e35f3e6000a87\", \"base_unittests\": \"9b3c6305c85f882e99af15ed4da9efa460922a16\", \"base_util_unittests\": \"a1389bf66eb868ae1d888658938934beee41e25c\", \"blink_common_unittests\": \"935b09b3f9e62088b345ddfca8c4739f557bc990\", \"blink_fuzzer_unittests\": \"d1e1f301f37ce46afb1d0f630c53c2177a3ffd89\", \"blink_heap_unittests\": \"3ee360318f851a5a92e1045b58564bd852585cd8\", \"blink_platform_unittests\": \"37dbd882f628b325d7eec5032fcbbcec0aeb87d5\", \"blink_unittests\": \"99760531cbdd9ce79566706e9448c856f93202a2\", \"boringssl_crypto_tests\": \"89fa228b0b04937de46679f10aa3044a82ca8e89\", \"boringssl_ssl_tests\": \"702ba0c838c59cfd55f1ff41c026424283be229d\", \"browser_tests\": \"698bb01e142e6d6be609fbd124f26e653985bec9\", \"cacheinvalidation_unittests\": \"6f4fa0a5860113f841cbb97e3974b3f25ed1f001\", \"capture_unittests\": \"98985a6fbb7c673b6f98789d008dec1f117c7cc7\", \"cast_unittests\": \"f9c839e82c2c40232bb3e4450132a91d731be729\", \"cc_unittests\": \"a150e28f78af711aa23b74695db4476a52a81411\", \"chrome_app_unittests\": \"bf029510cf556f34b92d9546d271b495bf2d3b77\", \"chromedriver_unittests\": \"88b1c7c2a2ee703f07ed0579be9cf4db287697be\", \"chromeos_components_unittests\": \"a8a7bed44bbebf70c37c73bfd08dfe5e4385bebd\", \"chromeos_unittests\": \"fe64202a4ed8627ddfe3e9f0c004b9f04d9dc441\", \"components_browsertests\": \"dfb2cc409ac9946be7786cbc8f068ffb5e0b64be\", \"components_unittests\": \"f56177cf80a2df288206917c6e555aeefbb6671a\", \"compositor_unittests\": \"d0fdf4366d7d40c9398abfc9d15f05bd6f3f1ff0\", \"content_browsertests\": \"b325e2be3aa7f6893a1e1ab4b300e6e18a154054\", \"content_unittests\": \"c4063273c9a0820478ad7df86320c8c011d2858e\", \"crypto_unittests\": \"7e3c75d17a959ffc9207e072313ccaaa78d23e48\", \"dbus_unittests\": \"d74c4436248eea4740c70796c4f477e1d14366b8\", \"device_unittests\": \"69c539d8d94410b94302cda06fe5a4dc09d475be\", \"display_unittests\": \"0513c88164dabaae0b39bb082d2c7aea680fbd6e\", \"events_unittests\": \"b5ac993ad82d58a3cab3cc897990b5ab25727f31\", \"exo_unittests\": \"19d2ff0eae601ca9d718dcd1c8e3dfcfcf0cdb44\", \"extensions_browsertests\": \"766ec67158ccbafa358db45a5ced6f04a2464c72\", \"extensions_unittests\": \"b3f77b668ab7d87095e8b65ad993ba9963b7d25a\", \"filesystem_service_unittests\": \"686e43f7a83a746d0e6eadee572441d93a27fa65\", \"gcm_unit_tests\": \"40c4ec0bfb79d04474286b9c3887e71a66ae248b\", \"gfx_unittests\": \"4986de22d37d4b3eb29640858f7681282920545e\", \"gin_unittests\": \"25a71fe46c27c074ce5b04a56eee847dbcb9e7fc\", \"gl_unittests_ozone\": \"bebfeed52e69d2ee2c88c4f7a7f9a35846b30bbd\", \"google_apis_unittests\": \"2334844b1edea22bccb00f4b0b9f71b1e6a30684\", \"gpu_unittests\": \"43b6c76cf69b56498e563b2d57bbdfac45a70df9\", \"interactive_ui_tests\": \"008333acfbd110835300e61529f83edb49fda971\", \"ipc_tests\": \"b0a2c83cc637501d183cb6112803f826e4479a20\", \"jingle_unittests\": \"78202731301679b3af154f8d6bdf35860f31049b\", \"keyboard_unittests\": \"707c750d68a00a22ab132cdd108519e35bce9b2d\", \"latency_unittests\": \"5e87ca5992c1bbf6c158d385ee9545a4a3108827\", \"leveldb_service_unittests\": \"146ed95ab7b387d6ecff94a84c766dbd404751b1\", \"libjingle_xmpp_unittests\": \"f91b58ede9e5e9497469d559a1fe9f3bb374b742\", \"media_blink_unittests\": \"f246916db94047fce86b899df2674e3c4dfbb9fe\", \"media_service_unittests\": \"010704aa48e90c34c3cfa135121c990494304d93\", \"media_unittests\": \"d3aa365d996577a219a069bf6e4a3624aaef81a1\", \"message_center_unittests\": \"401c9f68181f396466bfae69b19748efa74f1bf2\", \"midi_unittests\": \"782b1d81136d7b2f9ce5dc1b373bc068903b1486\", \"mojo_core_unittests\": \"22b05f6a7f9897fbb4f988f1509d92bab23e34c4\", \"mojo_unittests\": \"9e8ab884da4bbd11d55e31a76b56d086acf2c9e3\", \"nacl_helper_nonsfi_unittests\": \"79aa14ae544ca3030a5f7b96bec931cfb934f389\", \"nacl_loader_unittests\": \"2e7327e18170c495479dea3f66b050610a3b8592\", \"native_theme_unittests\": \"3cde2ace5b533406f87049de02d317570a4dc53c\", \"net_unittests\": \"31af50390be26d8fd42f519e902242f14ff16cb2\", \"ozone_gl_unittests\": \"10b00edd998e9eff34032d68d246b89092196280\", \"ozone_unittests\": \"0c3bbf67cdb7c962140deff8e57d60d3e2677b56\", \"ozone_x11_unittests\": \"89e3971c6667b7b5b195c8e686775b163230318d\", \"pdf_unittests\": \"bfa5ba5d7adb087a79623becfeeb14bab24931b1\", \"perfetto_unittests\": \"d78ce7e480da347d232597af38bcb39adc228311\", \"ppapi_unittests\": \"c4140f134048ef5237710b9519071340fac423b2\", \"printing_unittests\": \"b962bb82b8acd39f7680e7e005cd8c8ee8ff8596\", \"remoting_unittests\": \"4479018e796e56f4de7190600a169a15a6cc8e1e\", \"sandbox_linux_unittests\": \"4bee626069f653220fc9aba34df5c2ef04e53f55\", \"service_manager_unittests\": \"3e23a6790746d6ed65ef13b70c6aa22d9964ec2f\", \"services_unittests\": \"ad68766784ceb949a42c231ba9f47b57c87b4361\", \"shell_dialogs_unittests\": \"02e53cdc8b5ff20d6e41dc317c48bc32bdc8074d\", \"skia_unittests\": \"d51d7c058f98618d9c025215843042d85216a3a5\", \"snapshot_unittests\": \"381823cf584b1b45316430fbf2d6bffcbb8fb207\", \"sql_unittests\": \"c91070d6ba136c201349745aa3f57c4ed1c49511\", \"storage_unittests\": \"97480bf34a195183d33f582817a6f1d0d771b492\", \"sync_integration_tests\": \"5c581f62efa47d3d41d41839f978621dcd0a2d97\", \"traffic_annotation_auditor_unittests\": \"011ed25418fd893cfc96cec9167ebc8e149567e1\", \"ui_base_unittests\": \"d2a65d56abb92a2b4a8f7e232a4847b3582d909b\", \"ui_chromeos_unittests\": \"0247f49f8ab0ba24850f90659fe387d474152bdc\", \"ui_touch_selection_unittests\": \"61dec6978f012bf59953df566870b4def9745a87\", \"unit_tests\": \"4864b9ff3fa8f7e4b2fe87dce25871c029b7f8bf\", \"url_unittests\": \"653990af7332772f14c654033bce90f7e274cf8f\", \"usage_time_limit_unittests\": \"d67804b983aee5b9293e289de62e1365a21cf8fb\", \"views_unittests\": \"3a631a8e7586146c9dd70e4002b77c03b8381ad2\", \"viz_unittests\": \"37532d75aecdb679b9fb3cf7fd91e735b7a1fd41\", \"wm_unittests\": \"fe6ade0347d794fc8a2d4d09fcd2bc74f8ba98ef\", \"wtf_unittests\": \"77d7143d64beddc3d88afdc4bc288800952692b0\"}, \"swarm_hashes_refs/heads/master(at){#682514}_without_patch\": {\"accessibility_unittests\": \"77631947530034339bbcd37ebadb6ef0c5359f2a\", \"angle_unittests\": \"343644a4f1ee1af56c1bc90640bc08529d5fab34\", \"app_list_unittests\": \"ecd361d039851cbf38b436e2a09704afadffd3ff\", \"app_shell_unittests\": \"cee6cfbd4f90e551e5059ccc4c573e58e9988182\", \"ash_unittests\": \"be81590ec82298f50508f12caefceba370f1f3e1\", \"aura_unittests\": \"1f3ea7401602f7eb52b96d0d8c7e35f3e6000a87\", \"base_unittests\": \"9b3c6305c85f882e99af15ed4da9efa460922a16\", \"base_util_unittests\": \"a1389bf66eb868ae1d888658938934beee41e25c\", \"blink_common_unittests\": \"935b09b3f9e62088b345ddfca8c4739f557bc990\", \"blink_fuzzer_unittests\": \"d1e1f301f37ce46afb1d0f630c53c2177a3ffd89\", \"blink_heap_unittests\": \"3ee360318f851a5a92e1045b58564bd852585cd8\", \"blink_platform_unittests\": \"37dbd882f628b325d7eec5032fcbbcec0aeb87d5\", \"blink_unittests\": \"99760531cbdd9ce79566706e9448c856f93202a2\", \"boringssl_crypto_tests\": \"89fa228b0b04937de46679f10aa3044a82ca8e89\", \"boringssl_ssl_tests\": \"702ba0c838c59cfd55f1ff41c026424283be229d\", \"browser_tests\": \"698bb01e142e6d6be609fbd124f26e653985bec9\", \"cacheinvalidation_unittests\": \"6f4fa0a5860113f841cbb97e3974b3f25ed1f001\", \"capture_unittests\": \"98985a6fbb7c673b6f98789d008dec1f117c7cc7\", \"cast_unittests\": \"f9c839e82c2c40232bb3e4450132a91d731be729\", \"cc_unittests\": \"a150e28f78af711aa23b74695db4476a52a81411\", \"chrome_app_unittests\": \"bf029510cf556f34b92d9546d271b495bf2d3b77\", \"chromedriver_unittests\": \"88b1c7c2a2ee703f07ed0579be9cf4db287697be\", \"chromeos_components_unittests\": \"a8a7bed44bbebf70c37c73bfd08dfe5e4385bebd\", \"chromeos_unittests\": \"fe64202a4ed8627ddfe3e9f0c004b9f04d9dc441\", \"components_browsertests\": \"dfb2cc409ac9946be7786cbc8f068ffb5e0b64be\", \"components_unittests\": \"f56177cf80a2df288206917c6e555aeefbb6671a\", \"compositor_unittests\": \"d0fdf4366d7d40c9398abfc9d15f05bd6f3f1ff0\", \"content_browsertests\": \"b325e2be3aa7f6893a1e1ab4b300e6e18a154054\", \"content_unittests\": \"c4063273c9a0820478ad7df86320c8c011d2858e\", \"crypto_unittests\": \"7e3c75d17a959ffc9207e072313ccaaa78d23e48\", \"dbus_unittests\": \"d74c4436248eea4740c70796c4f477e1d14366b8\", \"device_unittests\": \"69c539d8d94410b94302cda06fe5a4dc09d475be\", \"display_unittests\": \"0513c88164dabaae0b39bb082d2c7aea680fbd6e\", \"events_unittests\": \"b5ac993ad82d58a3cab3cc897990b5ab25727f31\", \"exo_unittests\": \"19d2ff0eae601ca9d718dcd1c8e3dfcfcf0cdb44\", \"extensions_browsertests\": \"766ec67158ccbafa358db45a5ced6f04a2464c72\", \"extensions_unittests\": \"b3f77b668ab7d87095e8b65ad993ba9963b7d25a\", \"filesystem_service_unittests\": \"686e43f7a83a746d0e6eadee572441d93a27fa65\", \"gcm_unit_tests\": \"40c4ec0bfb79d04474286b9c3887e71a66ae248b\", \"gfx_unittests\": \"4986de22d37d4b3eb29640858f7681282920545e\", \"gin_unittests\": \"25a71fe46c27c074ce5b04a56eee847dbcb9e7fc\", \"gl_unittests_ozone\": \"bebfeed52e69d2ee2c88c4f7a7f9a35846b30bbd\", \"google_apis_unittests\": \"2334844b1edea22bccb00f4b0b9f71b1e6a30684\", \"gpu_unittests\": \"43b6c76cf69b56498e563b2d57bbdfac45a70df9\", \"interactive_ui_tests\": \"008333acfbd110835300e61529f83edb49fda971\", \"ipc_tests\": \"b0a2c83cc637501d183cb6112803f826e4479a20\", \"jingle_unittests\": \"78202731301679b3af154f8d6bdf35860f31049b\", \"keyboard_unittests\": \"707c750d68a00a22ab132cdd108519e35bce9b2d\", \"latency_unittests\": \"5e87ca5992c1bbf6c158d385ee9545a4a3108827\", \"leveldb_service_unittests\": \"146ed95ab7b387d6ecff94a84c766dbd404751b1\", \"libjingle_xmpp_unittests\": \"f91b58ede9e5e9497469d559a1fe9f3bb374b742\", \"media_blink_unittests\": \"f246916db94047fce86b899df2674e3c4dfbb9fe\", \"media_service_unittests\": \"010704aa48e90c34c3cfa135121c990494304d93\", \"media_unittests\": \"d3aa365d996577a219a069bf6e4a3624aaef81a1\", \"message_center_unittests\": \"401c9f68181f396466bfae69b19748efa74f1bf2\", \"midi_unittests\": \"782b1d81136d7b2f9ce5dc1b373bc068903b1486\", \"mojo_core_unittests\": \"22b05f6a7f9897fbb4f988f1509d92bab23e34c4\", \"mojo_unittests\": \"9e8ab884da4bbd11d55e31a76b56d086acf2c9e3\", \"nacl_helper_nonsfi_unittests\": \"79aa14ae544ca3030a5f7b96bec931cfb934f389\", \"nacl_loader_unittests\": \"2e7327e18170c495479dea3f66b050610a3b8592\", \"native_theme_unittests\": \"3cde2ace5b533406f87049de02d317570a4dc53c\", \"net_unittests\": \"31af50390be26d8fd42f519e902242f14ff16cb2\", \"ozone_gl_unittests\": \"10b00edd998e9eff34032d68d246b89092196280\", \"ozone_unittests\": \"0c3bbf67cdb7c962140deff8e57d60d3e2677b56\", \"ozone_x11_unittests\": \"89e3971c6667b7b5b195c8e686775b163230318d\", \"pdf_unittests\": \"bfa5ba5d7adb087a79623becfeeb14bab24931b1\", \"perfetto_unittests\": \"d78ce7e480da347d232597af38bcb39adc228311\", \"ppapi_unittests\": \"c4140f134048ef5237710b9519071340fac423b2\", \"printing_unittests\": \"b962bb82b8acd39f7680e7e005cd8c8ee8ff8596\", \"remoting_unittests\": \"4479018e796e56f4de7190600a169a15a6cc8e1e\", \"sandbox_linux_unittests\": \"4bee626069f653220fc9aba34df5c2ef04e53f55\", \"service_manager_unittests\": \"3e23a6790746d6ed65ef13b70c6aa22d9964ec2f\", \"services_unittests\": \"ad68766784ceb949a42c231ba9f47b57c87b4361\", \"shell_dialogs_unittests\": \"02e53cdc8b5ff20d6e41dc317c48bc32bdc8074d\", \"skia_unittests\": \"d51d7c058f98618d9c025215843042d85216a3a5\", \"snapshot_unittests\": \"381823cf584b1b45316430fbf2d6bffcbb8fb207\", \"sql_unittests\": \"c91070d6ba136c201349745aa3f57c4ed1c49511\", \"storage_unittests\": \"97480bf34a195183d33f582817a6f1d0d771b492\", \"sync_integration_tests\": \"5c581f62efa47d3d41d41839f978621dcd0a2d97\", \"traffic_annotation_auditor_unittests\": \"011ed25418fd893cfc96cec9167ebc8e149567e1\", \"ui_base_unittests\": \"d2a65d56abb92a2b4a8f7e232a4847b3582d909b\", \"ui_chromeos_unittests\": \"0247f49f8ab0ba24850f90659fe387d474152bdc\", \"ui_touch_selection_unittests\": \"61dec6978f012bf59953df566870b4def9745a87\", \"unit_tests\": \"4864b9ff3fa8f7e4b2fe87dce25871c029b7f8bf\", \"url_unittests\": \"653990af7332772f14c654033bce90f7e274cf8f\", \"usage_time_limit_unittests\": \"d67804b983aee5b9293e289de62e1365a21cf8fb\", \"views_unittests\": \"3a631a8e7586146c9dd70e4002b77c03b8381ad2\", \"viz_unittests\": \"37532d75aecdb679b9fb3cf7fd91e735b7a1fd41\", \"wm_unittests\": \"fe6ade0347d794fc8a2d4d09fcd2bc74f8ba98ef\", \"wtf_unittests\": \"77d7143d64beddc3d88afdc4bc288800952692b0\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_73a5b2ce307b316a1240917eaad41a92e4adc44903c6789e05a5f10300306cda_v2\", \"builder_933ba9ae858283254a9f288fa6037f5e3a70c42f58738d30148c4853e5f0b9c1_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-89-znre\"], \"image\": [\"chrome-xenial-19052201-9cb74617499\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4382-5929880\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- DeviceScheduledUpdateCheckerTest.CheckMonthlyRolloverLogic\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1564530541959500",
"status": "COMPLETED",
"status_changed_ts": "1564530542359105",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/13654",
"builder:linux-chromeos-dbg",
"buildset:commit/git/a12f9083713465e3cb338525678370c9949ecf92",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a12f9083713465e3cb338525678370c9949ecf92",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9071894963628231104",
"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/8906464900555183920/+/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:465e15cf4d781d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1564536113531747",
"url": "https://ci.chromium.org/b/8906464900555183920",
"utcnow_ts": "1564536114349401"
}
}
[01:21:54.385] Invocation finished in 1h38m40.124173199s with status FAILED