[16:22:22.105] New invocation is queued and will start shortly
[16:22:23.191] Starting the invocation (attempt 1)
[16:22:23.268] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:22:23.268] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:22:23.268] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9081344785245390128",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"68c4de4bcc1dd52ead2a972e62fd1cea2a6bbb5d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9081344785245390128",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/68c4de4bcc1dd52ead2a972e62fd1cea2a6bbb5d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/68c4de4bcc1dd52ead2a972e62fd1cea2a6bbb5d",
"gitiles_ref:refs/heads/master"
]
}
[16:22:24.378] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1555518143446461",
"id": "8915914723175873360",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"68c4de4bcc1dd52ead2a972e62fd1cea2a6bbb5d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1555518144124512",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11919",
"builder:linux-chromeos-dbg",
"buildset:commit/git/68c4de4bcc1dd52ead2a972e62fd1cea2a6bbb5d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/68c4de4bcc1dd52ead2a972e62fd1cea2a6bbb5d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081344785245390128",
"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/8915914723175873360/+/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": "1555518144124630",
"url": "https://ci.chromium.org/b/8915914723175873360",
"utcnow_ts": "1555518144366764"
}
}
[16:22:24.378] Task URL: https://ci.chromium.org/b/8915914723175873360
[16:22:24.379] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:2:0) after 1m0s
[16:23:24.510] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:2:0)
[16:23:24.510] Timer tick, asking Buildbucket for the build status
[16:23:24.639] Build 8915914723175873360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:23:24.639] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:3:0) after 1m0s
[16:24:24.687] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:3:0)
[16:24:24.687] Timer tick, asking Buildbucket for the build status
[16:24:24.995] Build 8915914723175873360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:24:24.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:4:0) after 1m0s
[16:24:48.069] Received PubSub notification, asking Buildbucket for the build status
[16:24:48.170] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:25.029] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:4:0)
[16:25:25.029] Timer tick, asking Buildbucket for the build status
[16:25:25.237] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:25.237] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:6:0) after 1m0s
[16:26:25.263] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:6:0)
[16:26:25.263] Timer tick, asking Buildbucket for the build status
[16:26:25.393] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:25.393] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:7:0) after 1m0s
[16:27:25.410] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:7:0)
[16:27:25.410] Timer tick, asking Buildbucket for the build status
[16:27:25.574] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:25.574] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:8:0) after 1m0s
[16:28:25.615] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:8:0)
[16:28:25.615] Timer tick, asking Buildbucket for the build status
[16:28:25.715] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:25.715] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:9:0) after 1m0s
[16:29:25.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:9:0)
[16:29:25.735] Timer tick, asking Buildbucket for the build status
[16:29:26.014] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:26.069] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:10:0) after 1m0s
[16:30:26.103] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:10:0)
[16:30:26.103] Timer tick, asking Buildbucket for the build status
[16:30:26.450] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:26.450] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:11:0) after 1m0s
[16:31:26.446] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:11:0)
[16:31:26.446] Timer tick, asking Buildbucket for the build status
[16:31:26.680] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:26.680] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:12:0) after 1m0s
[16:32:26.682] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:12:0)
[16:32:26.683] Timer tick, asking Buildbucket for the build status
[16:32:27.288] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:27.288] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:13:0) after 1m0s
[16:33:27.329] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:13:0)
[16:33:27.329] Timer tick, asking Buildbucket for the build status
[16:33:27.532] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:27.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:14:0) after 1m0s
[16:34:27.615] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:14:0)
[16:34:27.615] Timer tick, asking Buildbucket for the build status
[16:34:27.977] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:27.977] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:15:0) after 1m0s
[16:35:28.019] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:15:0)
[16:35:28.019] Timer tick, asking Buildbucket for the build status
[16:35:28.407] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:28.407] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:16:0) after 1m0s
[16:36:28.406] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:16:0)
[16:36:28.406] Timer tick, asking Buildbucket for the build status
[16:36:28.816] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:28.816] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:17:0) after 1m0s
[16:37:28.838] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:17:0)
[16:37:28.838] Timer tick, asking Buildbucket for the build status
[16:37:28.968] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:28.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:18:0) after 1m0s
[16:38:28.975] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:18:0)
[16:38:28.975] Timer tick, asking Buildbucket for the build status
[16:38:29.098] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:29.098] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:19:0) after 1m0s
[16:39:29.200] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:19:0)
[16:39:29.200] Timer tick, asking Buildbucket for the build status
[16:39:29.285] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:29.285] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:20:0) after 1m0s
[16:40:29.282] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:20:0)
[16:40:29.282] Timer tick, asking Buildbucket for the build status
[16:40:29.898] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:29.898] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:21:0) after 1m0s
[16:41:29.880] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:21:0)
[16:41:29.880] Timer tick, asking Buildbucket for the build status
[16:41:30.274] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:30.274] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:22:0) after 1m0s
[16:42:30.257] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:22:0)
[16:42:30.257] Timer tick, asking Buildbucket for the build status
[16:42:30.781] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:30.781] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:23:0) after 1m0s
[16:43:30.799] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:23:0)
[16:43:30.799] Timer tick, asking Buildbucket for the build status
[16:43:30.885] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:30.885] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:24:0) after 1m0s
[16:44:30.914] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:24:0)
[16:44:30.914] Timer tick, asking Buildbucket for the build status
[16:44:31.204] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:31.204] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:25:0) after 1m0s
[16:45:31.188] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:25:0)
[16:45:31.188] Timer tick, asking Buildbucket for the build status
[16:45:31.292] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:31.292] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:26:0) after 1m0s
[16:46:31.266] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:26:0)
[16:46:31.266] Timer tick, asking Buildbucket for the build status
[16:46:31.421] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:31.421] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:27:0) after 1m0s
[16:47:31.443] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:27:0)
[16:47:31.443] Timer tick, asking Buildbucket for the build status
[16:47:31.539] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:31.539] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:28:0) after 1m0s
[16:48:31.540] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:28:0)
[16:48:31.540] Timer tick, asking Buildbucket for the build status
[16:48:31.974] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:31.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:29:0) after 1m0s
[16:49:31.994] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:29:0)
[16:49:31.994] Timer tick, asking Buildbucket for the build status
[16:49:32.383] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:32.383] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:30:0) after 1m0s
[16:50:32.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:30:0)
[16:50:32.371] Timer tick, asking Buildbucket for the build status
[16:50:32.462] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:32.462] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:31:0) after 1m0s
[16:51:32.491] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:31:0)
[16:51:32.491] Timer tick, asking Buildbucket for the build status
[16:51:32.701] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:32.701] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:32:0) after 1m0s
[16:52:32.719] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:32:0)
[16:52:32.719] Timer tick, asking Buildbucket for the build status
[16:52:32.884] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:32.884] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:33:0) after 1m0s
[16:53:32.907] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:33:0)
[16:53:32.907] Timer tick, asking Buildbucket for the build status
[16:53:33.071] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:33.071] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:34:0) after 1m0s
[16:54:33.095] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:34:0)
[16:54:33.095] Timer tick, asking Buildbucket for the build status
[16:54:33.235] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:33.235] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:35:0) after 1m0s
[16:55:33.264] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:35:0)
[16:55:33.264] Timer tick, asking Buildbucket for the build status
[16:55:33.434] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:33.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:36:0) after 1m0s
[16:56:33.457] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:36:0)
[16:56:33.457] Timer tick, asking Buildbucket for the build status
[16:56:33.604] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:33.604] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:37:0) after 1m0s
[16:57:33.671] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:37:0)
[16:57:33.671] Timer tick, asking Buildbucket for the build status
[16:57:34.001] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:34.001] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:38:0) after 1m0s
[16:58:34.021] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:38:0)
[16:58:34.021] Timer tick, asking Buildbucket for the build status
[16:58:34.474] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:34.474] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:39:0) after 1m0s
[16:59:34.497] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:39:0)
[16:59:34.497] Timer tick, asking Buildbucket for the build status
[16:59:34.605] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:34.605] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:40:0) after 1m0s
[17:00:34.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:40:0)
[17:00:34.628] Timer tick, asking Buildbucket for the build status
[17:00:34.889] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:34.889] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:41:0) after 1m0s
[17:01:34.911] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:41:0)
[17:01:34.911] Timer tick, asking Buildbucket for the build status
[17:01:35.534] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:35.534] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:42:0) after 1m0s
[17:02:35.612] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:42:0)
[17:02:35.612] Timer tick, asking Buildbucket for the build status
[17:02:35.729] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:35.729] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:43:0) after 1m0s
[17:03:35.750] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:43:0)
[17:03:35.750] Timer tick, asking Buildbucket for the build status
[17:03:36.159] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:36.159] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:44:0) after 1m0s
[17:04:36.138] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:44:0)
[17:04:36.138] Timer tick, asking Buildbucket for the build status
[17:04:36.294] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:36.294] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:45:0) after 1m0s
[17:05:36.330] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:45:0)
[17:05:36.330] Timer tick, asking Buildbucket for the build status
[17:05:37.103] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:37.103] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:46:0) after 1m0s
[17:06:37.145] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:46:0)
[17:06:37.145] Timer tick, asking Buildbucket for the build status
[17:06:37.400] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:37.400] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:47:0) after 1m0s
[17:07:37.450] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:47:0)
[17:07:37.450] Timer tick, asking Buildbucket for the build status
[17:07:39.011] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:39.011] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:48:0) after 1m0s
[17:08:38.988] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:48:0)
[17:08:38.988] Timer tick, asking Buildbucket for the build status
[17:08:40.015] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:40.015] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:49:0) after 1m0s
[17:09:40.106] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:49:0)
[17:09:40.106] Timer tick, asking Buildbucket for the build status
[17:09:40.395] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:40.395] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:50:0) after 1m0s
[17:10:40.372] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:50:0)
[17:10:40.372] Timer tick, asking Buildbucket for the build status
[17:10:40.697] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:40.697] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:51:0) after 1m0s
[17:11:40.719] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:51:0)
[17:11:40.719] Timer tick, asking Buildbucket for the build status
[17:11:41.108] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:41.108] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:52:0) after 1m0s
[17:12:41.127] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:52:0)
[17:12:41.127] Timer tick, asking Buildbucket for the build status
[17:12:41.424] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:41.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:53:0) after 1m0s
[17:13:41.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:53:0)
[17:13:41.424] Timer tick, asking Buildbucket for the build status
[17:13:41.725] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:41.725] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:54:0) after 1m0s
[17:14:41.744] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:54:0)
[17:14:41.744] Timer tick, asking Buildbucket for the build status
[17:14:42.203] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:42.203] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:55:0) after 1m0s
[17:15:42.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:55:0)
[17:15:42.233] Timer tick, asking Buildbucket for the build status
[17:15:42.986] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:42.986] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:56:0) after 1m0s
[17:16:42.955] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:56:0)
[17:16:42.955] Timer tick, asking Buildbucket for the build status
[17:16:43.516] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:43.516] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:57:0) after 1m0s
[17:17:43.531] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:57:0)
[17:17:43.531] Timer tick, asking Buildbucket for the build status
[17:17:43.857] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:43.857] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:58:0) after 1m0s
[17:18:43.883] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:58:0)
[17:18:43.883] Timer tick, asking Buildbucket for the build status
[17:18:44.023] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:44.023] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:59:0) after 1m0s
[17:19:44.016] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:59:0)
[17:19:44.016] Timer tick, asking Buildbucket for the build status
[17:19:44.421] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:44.421] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:60:0) after 1m0s
[17:20:44.442] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:60:0)
[17:20:44.442] Timer tick, asking Buildbucket for the build status
[17:20:44.665] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:44.665] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:61:0) after 1m0s
[17:21:44.752] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:61:0)
[17:21:44.752] Timer tick, asking Buildbucket for the build status
[17:21:45.215] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:45.215] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:62:0) after 1m0s
[17:22:45.216] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:62:0)
[17:22:45.216] Timer tick, asking Buildbucket for the build status
[17:22:45.341] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:45.341] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:63:0) after 1m0s
[17:23:45.352] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:63:0)
[17:23:45.352] Timer tick, asking Buildbucket for the build status
[17:23:45.780] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:45.780] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:64:0) after 1m0s
[17:24:45.788] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:64:0)
[17:24:45.788] Timer tick, asking Buildbucket for the build status
[17:24:45.991] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:45.991] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:65:0) after 1m0s
[17:25:46.011] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:65:0)
[17:25:46.011] Timer tick, asking Buildbucket for the build status
[17:25:46.130] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:46.130] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:66:0) after 1m0s
[17:26:46.124] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:66:0)
[17:26:46.124] Timer tick, asking Buildbucket for the build status
[17:26:47.124] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:47.124] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:67:0) after 1m0s
[17:27:47.132] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:67:0)
[17:27:47.132] Timer tick, asking Buildbucket for the build status
[17:27:47.402] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:47.402] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:68:0) after 1m0s
[17:28:47.408] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:68:0)
[17:28:47.408] Timer tick, asking Buildbucket for the build status
[17:28:47.596] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:47.596] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:69:0) after 1m0s
[17:29:47.618] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:69:0)
[17:29:47.618] Timer tick, asking Buildbucket for the build status
[17:29:49.566] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:49.566] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:70:0) after 1m0s
[17:30:49.560] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:70:0)
[17:30:49.560] Timer tick, asking Buildbucket for the build status
[17:30:49.811] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:49.811] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:71:0) after 1m0s
[17:31:49.826] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:71:0)
[17:31:49.826] Timer tick, asking Buildbucket for the build status
[17:31:49.993] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:49.993] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:72:0) after 1m0s
[17:32:49.998] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:72:0)
[17:32:49.998] Timer tick, asking Buildbucket for the build status
[17:32:51.233] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:51.234] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:73:0) after 1m0s
[17:33:51.244] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:73:0)
[17:33:51.244] Timer tick, asking Buildbucket for the build status
[17:33:51.438] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:51.438] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:74:0) after 1m0s
[17:34:51.418] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:74:0)
[17:34:51.418] Timer tick, asking Buildbucket for the build status
[17:34:51.742] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:51.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:75:0) after 1m0s
[17:35:51.767] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:75:0)
[17:35:51.767] Timer tick, asking Buildbucket for the build status
[17:35:52.008] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:52.008] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:76:0) after 1m0s
[17:36:52.039] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:76:0)
[17:36:52.039] Timer tick, asking Buildbucket for the build status
[17:36:52.421] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:52.421] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:77:0) after 1m0s
[17:37:52.460] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:77:0)
[17:37:52.460] Timer tick, asking Buildbucket for the build status
[17:37:53.051] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:53.051] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:78:0) after 1m0s
[17:38:53.105] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:78:0)
[17:38:53.105] Timer tick, asking Buildbucket for the build status
[17:38:53.447] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:53.447] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:79:0) after 1m0s
[17:39:53.469] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:79:0)
[17:39:53.469] Timer tick, asking Buildbucket for the build status
[17:39:53.572] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:53.572] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:80:0) after 1m0s
[17:40:53.596] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:80:0)
[17:40:53.596] Timer tick, asking Buildbucket for the build status
[17:40:53.880] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:53.880] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:81:0) after 1m0s
[17:41:53.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:81:0)
[17:41:53.871] Timer tick, asking Buildbucket for the build status
[17:41:54.312] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:54.312] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:82:0) after 1m0s
[17:42:54.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:82:0)
[17:42:54.339] Timer tick, asking Buildbucket for the build status
[17:42:54.485] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:54.485] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:83:0) after 1m0s
[17:43:54.502] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:83:0)
[17:43:54.502] Timer tick, asking Buildbucket for the build status
[17:43:55.984] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:55.984] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:84:0) after 1m0s
[17:44:56.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:84:0)
[17:44:56.001] Timer tick, asking Buildbucket for the build status
[17:44:56.114] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:56.114] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:85:0) after 1m0s
[17:45:56.165] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:85:0)
[17:45:56.165] Timer tick, asking Buildbucket for the build status
[17:45:56.309] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:56.309] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:86:0) after 1m0s
[17:46:56.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:86:0)
[17:46:56.332] Timer tick, asking Buildbucket for the build status
[17:46:56.459] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:56.459] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:87:0) after 1m0s
[17:47:56.451] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:87:0)
[17:47:56.451] Timer tick, asking Buildbucket for the build status
[17:47:57.012] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:57.012] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:88:0) after 1m0s
[17:48:57.029] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:88:0)
[17:48:57.029] Timer tick, asking Buildbucket for the build status
[17:48:57.172] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:57.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:89:0) after 1m0s
[17:49:57.197] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:89:0)
[17:49:57.197] Timer tick, asking Buildbucket for the build status
[17:49:57.786] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:57.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:90:0) after 1m0s
[17:50:57.813] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:90:0)
[17:50:57.813] Timer tick, asking Buildbucket for the build status
[17:50:58.385] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:58.385] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:91:0) after 1m0s
[17:51:58.415] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:91:0)
[17:51:58.415] Timer tick, asking Buildbucket for the build status
[17:51:58.513] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:58.513] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:92:0) after 1m0s
[17:52:58.538] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:92:0)
[17:52:58.538] Timer tick, asking Buildbucket for the build status
[17:52:58.974] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:58.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:93:0) after 1m0s
[17:53:59.004] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:93:0)
[17:53:59.004] Timer tick, asking Buildbucket for the build status
[17:53:59.162] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:59.162] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:94:0) after 1m0s
[17:54:59.185] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:94:0)
[17:54:59.185] Timer tick, asking Buildbucket for the build status
[17:54:59.288] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:59.288] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:95:0) after 1m0s
[17:55:59.300] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:95:0)
[17:55:59.300] Timer tick, asking Buildbucket for the build status
[17:55:59.559] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:59.559] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:96:0) after 1m0s
[17:56:59.574] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:96:0)
[17:56:59.574] Timer tick, asking Buildbucket for the build status
[17:56:59.714] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:59.714] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:97:0) after 1m0s
[17:57:59.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:97:0)
[17:57:59.726] Timer tick, asking Buildbucket for the build status
[17:57:59.834] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:59.834] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:98:0) after 1m0s
[17:58:59.856] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:98:0)
[17:58:59.856] Timer tick, asking Buildbucket for the build status
[17:59:00.228] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:00.228] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:99:0) after 1m0s
[18:00:00.248] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:99:0)
[18:00:00.248] Timer tick, asking Buildbucket for the build status
[18:00:00.394] Build 8915914723175873360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:00.394] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9081344785245390128:100:0) after 1m0s
[18:00:26.092] Received PubSub notification, asking Buildbucket for the build status
[18:00:26.310] Build 8915914723175873360: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[18:00:26.310] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1555524021192470",
"created_by": "project:chromium",
"created_ts": "1555518143446461",
"failure_reason": "BUILD_FAILURE",
"id": "8915914723175873360",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"68c4de4bcc1dd52ead2a972e62fd1cea2a6bbb5d\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 tests failed: ['viz_unittests']\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"swarm1567-c4\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 11919, \"got_angle_revision\": \"20a716319262ce5820fd09f8313108018aa3d948\", \"got_dawn_revision\": \"0c133bbe554e98c55b88f3dd3fab7b885aa5abcb\", \"got_nacl_revision\": \"6abc006f6760ec49350cd45e8bccbff4809725ac\", \"got_revision\": \"68c4de4bcc1dd52ead2a972e62fd1cea2a6bbb5d\", \"got_revision_cp\": \"refs/heads/master@{#651753}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"58da6c5b0c724f6c48227eb0c7c23486c6b074b2\", \"got_v8_revision_cp\": \"refs/heads/7.5.285@{#1}\", \"got_webrtc_revision\": \"6f5e84894e74e62b221a06cd4d2d7aeaa2750f41\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27655}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.chromiumos\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"68c4de4bcc1dd52ead2a972e62fd1cea2a6bbb5d\", \"swarm_hashes\": {\"accessibility_unittests\": \"acffbdf57567963f0757bf22f0e901be507d07b4\", \"angle_unittests\": \"95b2f072a5f25742bc5926fa9d53553df9bd2517\", \"app_list_unittests\": \"1756ececbf334dd9fbd73e244550918412ff2063\", \"app_shell_unittests\": \"921a6b1416e463ab5eb9c2d034e32ae3591a368b\", \"ash_unittests\": \"a795cb6e759797a6be3c1cdf01561bb682ec003d\", \"aura_unittests\": \"4df26908274786d609f61a72d319a83e75fb6d78\", \"base_unittests\": \"d8581a10b8527cd9a27ccf02f40d11a1a1070e4b\", \"blink_common_unittests\": \"fdb2be0aaa01408ce6032a0fd3e49bfba4f4f386\", \"blink_fuzzer_unittests\": \"990da69fe3430fde042c22ccb0ea376ec2de7801\", \"blink_heap_unittests\": \"cf37b47ddc5f80496eaf8d53f96c109d81820742\", \"blink_platform_unittests\": \"f2ce77506220dcecc822621741a0beba2850eecf\", \"blink_unittests\": \"7a53f726a0f09a7d1c96936824786c0c297f0e16\", \"boringssl_crypto_tests\": \"4b521e226949c1acec5e3dab4706ff6c3c250d18\", \"boringssl_ssl_tests\": \"f8f42a0437116fb9c8a02f4174aaf4882ff46221\", \"browser_tests\": \"dfdd721b5020c2d1fdf3e2c531955388bfafef3c\", \"cacheinvalidation_unittests\": \"7731923fef5daf46903362b30467368b366273b1\", \"capture_unittests\": \"2c6b4aed270e6c626850ecb0774d3ca50dd5e260\", \"cast_unittests\": \"56644e4ce67d142b3eef20dff9fdac3c70f48d52\", \"cc_unittests\": \"6de512b25da083b3840913007b958751ef1480e1\", \"chrome_app_unittests\": \"c8b67c2e2df0910bb577738eea6b5df10d10201f\", \"chromedriver_unittests\": \"6ce91bd72341e4d58d70c3b9721b3ce7ff042381\", \"chromeos_components_unittests\": \"9bb9658ca12ebe1adcee3d7ce6ad283fb35174b7\", \"chromeos_unittests\": \"15aa9c49c7b8e757f2e7d8c429c60ef9312255ff\", \"components_browsertests\": \"d38c26f854e7e4ffa29201c11ce442caa18f5bfd\", \"components_unittests\": \"2b37b80a205d8f46482d496fb89389ecbe39c354\", \"compositor_unittests\": \"5ef9d9c7db9dcaafbabef6abd0d8417c5c974d65\", \"content_browsertests\": \"43fefe863de79279db857c121913b48e4d572810\", \"content_unittests\": \"f71cdcc247297b8cc06ef3bb79de0ed0ae2deb1e\", \"crypto_unittests\": \"bff324f297128c7aa0f4ec21dd26717d49b74968\", \"dbus_unittests\": \"bef131ca63d4fa118af800d75343bd3b5ecf2860\", \"device_unittests\": \"935a782a09a35965d08a17460556151a668344f0\", \"display_unittests\": \"a9c81b765b167acd5bc24ececa16e7bdba393bf7\", \"events_unittests\": \"2919b83e9b133be43c2f495b7f50e9784b8af2f3\", \"exo_unittests\": \"9e8cb229629b11f060b327a4037d80f53ae5f21c\", \"extensions_browsertests\": \"ecce53dd42b9e870ea3e581179791971b1c83441\", \"extensions_unittests\": \"7d723178d759b19ae31bda13dee277f4797d276c\", \"filesystem_service_unittests\": \"b6e93fc054618ae5769e014e46c5a30ce16ad6e0\", \"gcm_unit_tests\": \"180697ed86282b18ab01d42db6f8254b686ea601\", \"gfx_unittests\": \"41296928fe8a3a6bcf86f5c66e763895d8ac82b3\", \"gin_unittests\": \"4d29db72e1c0276dad8a9d29baa4a5bed515b240\", \"gl_unittests_ozone\": \"75a34c7cf126d47b1dbca5d2f981243af1af4395\", \"google_apis_unittests\": \"3f4dc30c5d06cc60086884e324e0d73f7f3a5efa\", \"gpu_unittests\": \"1911745e5f22c6fc54406a313daea5dcc1fc448d\", \"interactive_ui_tests\": \"e95eca854998a766eeec2ef939b508de140b11c9\", \"ipc_tests\": \"7874b8e8759ef0a933a1f54d71dc7b411d2ea4db\", \"jingle_unittests\": \"f59bb91d37a4e2063ad39ab87dd7604418dfa384\", \"keyboard_unittests\": \"678c20da1ac41993b944c825bb00f35597f24fe4\", \"latency_unittests\": \"cf74dd851ccf56a55870160b49c677494225e6a4\", \"leveldb_service_unittests\": \"bc17b37bd12bc576c10775b8ea5c9b80cd448e35\", \"libjingle_xmpp_unittests\": \"6f722252fb208afbdc4e6231c26d3946dde24c3a\", \"media_blink_unittests\": \"7e158f6692a9f324d77b815a8f3a1e6a3553a87b\", \"media_service_unittests\": \"e56aa3162187539fc55e17edee0bdcc401a300b8\", \"media_unittests\": \"abc34f244ef08af3aa166c29affb25b459b5ceae\", \"message_center_unittests\": \"c33235c88b2ac3db0e69723b0e0146a5acc5186c\", \"midi_unittests\": \"dbc28a5e1b8bc2db3ee8cf869cafa7c8d8df1d81\", \"mojo_core_unittests\": \"279231a2da1b14b3be78b4af06474ebe3dd0ffbe\", \"mojo_unittests\": \"10e7f3477f79e4bc5d90a15b7e20ab2176906ac4\", \"nacl_helper_nonsfi_unittests\": \"18dfb56279ebfe356c840b1cd5c13c285d7ec2a9\", \"nacl_loader_unittests\": \"ab2efbaa06e789ce8ff9a18ca56c3074ce0224d8\", \"native_theme_unittests\": \"86d1ab17fb17741e0b0d95fab2a71aa701f31a3f\", \"net_unittests\": \"80691b785bcfd562f2f91e6f4fed40e4cbd5a495\", \"ozone_gl_unittests\": \"bc8d8fe998c67b8427948307d679ceec6178bcfd\", \"ozone_unittests\": \"198ab439d6c2d50c14b96f79c35428976a57e5de\", \"ozone_x11_unittests\": \"b199798e0d4f20d30d834695acab2db0bce99e7c\", \"pdf_unittests\": \"536382a54e68a7fc8c90c820d4277e3d5932b1f6\", \"perfetto_unittests\": \"803ef12bcc7e7340277535a7bb767a551a60ea34\", \"ppapi_unittests\": \"cd5e0ba3c4be871a5efcb6b37347fedaa7b3fbb8\", \"printing_unittests\": \"15efecfb92b451371e1662f4ac09f75995b34652\", \"remoting_unittests\": \"37ddc3bcc0cc0aab16fea6fb8d888f40549179cc\", \"sandbox_linux_unittests\": \"9f1aa6d7b75df682d4d20dd9d28473d28912d9ba\", \"service_manager_unittests\": \"7356821b804bc949a426ad647f4947584d750dd0\", \"services_unittests\": \"c0f8f35a24758fca528176c5c61d410b6d5ae973\", \"shell_dialogs_unittests\": \"60100fec0876fd63874995a6fb00ff7feebf82cc\", \"skia_unittests\": \"0cb525344d23272a3034f8fa1c9b707c0590d4d8\", \"snapshot_unittests\": \"6c03a8098121a41cfad5d31fc9eab62f0524a29e\", \"sql_unittests\": \"6f217eebc6613fea4d0dc9bf0efb522101e17f6a\", \"storage_unittests\": \"f18c3ef32a90a5a9fb3b870a8f806df876baeb12\", \"sync_integration_tests\": \"6097d4daa1c27c6a70857f0387abd444807e6aff\", \"traffic_annotation_auditor_unittests\": \"b7dae55a446ed9013f2c35a0e7ac33c7a946470d\", \"ui_base_unittests\": \"4f58c20323210483130772bd28504931c1d5d828\", \"ui_chromeos_unittests\": \"fb0217f4f1da7b28e7cc3875f1691ddf9de3cf54\", \"ui_touch_selection_unittests\": \"836bcf945b7661257edd9f5c5affc6618454eb2e\", \"unit_tests\": \"f7f48f789b9c691fcabe3dd914d07195ec21d0a7\", \"url_unittests\": \"b888db55e9a470de9df3f03d5d2acdc5171d6bb9\", \"usage_time_limit_unittests\": \"da5bf6e05954b964fcf4c3121710e17a424db7f6\", \"views_unittests\": \"504dd02e2174e4251ae2a59193bf4179b0c2ebe9\", \"viz_unittests\": \"d0d5058a523b968bcdf0a1066e1b395023c5e284\", \"wm_unittests\": \"652cfa96b645773a724bbd0531104c4d78e29eff\", \"wtf_unittests\": \"60280374d57fbfbce882f601ed7df2f2c6af630d\"}, \"swarm_hashes_refs/heads/master(at){#651753}_without_patch\": {\"accessibility_unittests\": \"acffbdf57567963f0757bf22f0e901be507d07b4\", \"angle_unittests\": \"95b2f072a5f25742bc5926fa9d53553df9bd2517\", \"app_list_unittests\": \"1756ececbf334dd9fbd73e244550918412ff2063\", \"app_shell_unittests\": \"921a6b1416e463ab5eb9c2d034e32ae3591a368b\", \"ash_unittests\": \"a795cb6e759797a6be3c1cdf01561bb682ec003d\", \"aura_unittests\": \"4df26908274786d609f61a72d319a83e75fb6d78\", \"base_unittests\": \"d8581a10b8527cd9a27ccf02f40d11a1a1070e4b\", \"blink_common_unittests\": \"fdb2be0aaa01408ce6032a0fd3e49bfba4f4f386\", \"blink_fuzzer_unittests\": \"990da69fe3430fde042c22ccb0ea376ec2de7801\", \"blink_heap_unittests\": \"cf37b47ddc5f80496eaf8d53f96c109d81820742\", \"blink_platform_unittests\": \"f2ce77506220dcecc822621741a0beba2850eecf\", \"blink_unittests\": \"7a53f726a0f09a7d1c96936824786c0c297f0e16\", \"boringssl_crypto_tests\": \"4b521e226949c1acec5e3dab4706ff6c3c250d18\", \"boringssl_ssl_tests\": \"f8f42a0437116fb9c8a02f4174aaf4882ff46221\", \"browser_tests\": \"dfdd721b5020c2d1fdf3e2c531955388bfafef3c\", \"cacheinvalidation_unittests\": \"7731923fef5daf46903362b30467368b366273b1\", \"capture_unittests\": \"2c6b4aed270e6c626850ecb0774d3ca50dd5e260\", \"cast_unittests\": \"56644e4ce67d142b3eef20dff9fdac3c70f48d52\", \"cc_unittests\": \"6de512b25da083b3840913007b958751ef1480e1\", \"chrome_app_unittests\": \"c8b67c2e2df0910bb577738eea6b5df10d10201f\", \"chromedriver_unittests\": \"6ce91bd72341e4d58d70c3b9721b3ce7ff042381\", \"chromeos_components_unittests\": \"9bb9658ca12ebe1adcee3d7ce6ad283fb35174b7\", \"chromeos_unittests\": \"15aa9c49c7b8e757f2e7d8c429c60ef9312255ff\", \"components_browsertests\": \"d38c26f854e7e4ffa29201c11ce442caa18f5bfd\", \"components_unittests\": \"2b37b80a205d8f46482d496fb89389ecbe39c354\", \"compositor_unittests\": \"5ef9d9c7db9dcaafbabef6abd0d8417c5c974d65\", \"content_browsertests\": \"43fefe863de79279db857c121913b48e4d572810\", \"content_unittests\": \"f71cdcc247297b8cc06ef3bb79de0ed0ae2deb1e\", \"crypto_unittests\": \"bff324f297128c7aa0f4ec21dd26717d49b74968\", \"dbus_unittests\": \"bef131ca63d4fa118af800d75343bd3b5ecf2860\", \"device_unittests\": \"935a782a09a35965d08a17460556151a668344f0\", \"display_unittests\": \"a9c81b765b167acd5bc24ececa16e7bdba393bf7\", \"events_unittests\": \"2919b83e9b133be43c2f495b7f50e9784b8af2f3\", \"exo_unittests\": \"9e8cb229629b11f060b327a4037d80f53ae5f21c\", \"extensions_browsertests\": \"ecce53dd42b9e870ea3e581179791971b1c83441\", \"extensions_unittests\": \"7d723178d759b19ae31bda13dee277f4797d276c\", \"filesystem_service_unittests\": \"b6e93fc054618ae5769e014e46c5a30ce16ad6e0\", \"gcm_unit_tests\": \"180697ed86282b18ab01d42db6f8254b686ea601\", \"gfx_unittests\": \"41296928fe8a3a6bcf86f5c66e763895d8ac82b3\", \"gin_unittests\": \"4d29db72e1c0276dad8a9d29baa4a5bed515b240\", \"gl_unittests_ozone\": \"75a34c7cf126d47b1dbca5d2f981243af1af4395\", \"google_apis_unittests\": \"3f4dc30c5d06cc60086884e324e0d73f7f3a5efa\", \"gpu_unittests\": \"1911745e5f22c6fc54406a313daea5dcc1fc448d\", \"interactive_ui_tests\": \"e95eca854998a766eeec2ef939b508de140b11c9\", \"ipc_tests\": \"7874b8e8759ef0a933a1f54d71dc7b411d2ea4db\", \"jingle_unittests\": \"f59bb91d37a4e2063ad39ab87dd7604418dfa384\", \"keyboard_unittests\": \"678c20da1ac41993b944c825bb00f35597f24fe4\", \"latency_unittests\": \"cf74dd851ccf56a55870160b49c677494225e6a4\", \"leveldb_service_unittests\": \"bc17b37bd12bc576c10775b8ea5c9b80cd448e35\", \"libjingle_xmpp_unittests\": \"6f722252fb208afbdc4e6231c26d3946dde24c3a\", \"media_blink_unittests\": \"7e158f6692a9f324d77b815a8f3a1e6a3553a87b\", \"media_service_unittests\": \"e56aa3162187539fc55e17edee0bdcc401a300b8\", \"media_unittests\": \"abc34f244ef08af3aa166c29affb25b459b5ceae\", \"message_center_unittests\": \"c33235c88b2ac3db0e69723b0e0146a5acc5186c\", \"midi_unittests\": \"dbc28a5e1b8bc2db3ee8cf869cafa7c8d8df1d81\", \"mojo_core_unittests\": \"279231a2da1b14b3be78b4af06474ebe3dd0ffbe\", \"mojo_unittests\": \"10e7f3477f79e4bc5d90a15b7e20ab2176906ac4\", \"nacl_helper_nonsfi_unittests\": \"18dfb56279ebfe356c840b1cd5c13c285d7ec2a9\", \"nacl_loader_unittests\": \"ab2efbaa06e789ce8ff9a18ca56c3074ce0224d8\", \"native_theme_unittests\": \"86d1ab17fb17741e0b0d95fab2a71aa701f31a3f\", \"net_unittests\": \"80691b785bcfd562f2f91e6f4fed40e4cbd5a495\", \"ozone_gl_unittests\": \"bc8d8fe998c67b8427948307d679ceec6178bcfd\", \"ozone_unittests\": \"198ab439d6c2d50c14b96f79c35428976a57e5de\", \"ozone_x11_unittests\": \"b199798e0d4f20d30d834695acab2db0bce99e7c\", \"pdf_unittests\": \"536382a54e68a7fc8c90c820d4277e3d5932b1f6\", \"perfetto_unittests\": \"803ef12bcc7e7340277535a7bb767a551a60ea34\", \"ppapi_unittests\": \"cd5e0ba3c4be871a5efcb6b37347fedaa7b3fbb8\", \"printing_unittests\": \"15efecfb92b451371e1662f4ac09f75995b34652\", \"remoting_unittests\": \"37ddc3bcc0cc0aab16fea6fb8d888f40549179cc\", \"sandbox_linux_unittests\": \"9f1aa6d7b75df682d4d20dd9d28473d28912d9ba\", \"service_manager_unittests\": \"7356821b804bc949a426ad647f4947584d750dd0\", \"services_unittests\": \"c0f8f35a24758fca528176c5c61d410b6d5ae973\", \"shell_dialogs_unittests\": \"60100fec0876fd63874995a6fb00ff7feebf82cc\", \"skia_unittests\": \"0cb525344d23272a3034f8fa1c9b707c0590d4d8\", \"snapshot_unittests\": \"6c03a8098121a41cfad5d31fc9eab62f0524a29e\", \"sql_unittests\": \"6f217eebc6613fea4d0dc9bf0efb522101e17f6a\", \"storage_unittests\": \"f18c3ef32a90a5a9fb3b870a8f806df876baeb12\", \"sync_integration_tests\": \"6097d4daa1c27c6a70857f0387abd444807e6aff\", \"traffic_annotation_auditor_unittests\": \"b7dae55a446ed9013f2c35a0e7ac33c7a946470d\", \"ui_base_unittests\": \"4f58c20323210483130772bd28504931c1d5d828\", \"ui_chromeos_unittests\": \"fb0217f4f1da7b28e7cc3875f1691ddf9de3cf54\", \"ui_touch_selection_unittests\": \"836bcf945b7661257edd9f5c5affc6618454eb2e\", \"unit_tests\": \"f7f48f789b9c691fcabe3dd914d07195ec21d0a7\", \"url_unittests\": \"b888db55e9a470de9df3f03d5d2acdc5171d6bb9\", \"usage_time_limit_unittests\": \"da5bf6e05954b964fcf4c3121710e17a424db7f6\", \"views_unittests\": \"504dd02e2174e4251ae2a59193bf4179b0c2ebe9\", \"viz_unittests\": \"d0d5058a523b968bcdf0a1066e1b395023c5e284\", \"wm_unittests\": \"652cfa96b645773a724bbd0531104c4d78e29eff\", \"wtf_unittests\": \"60280374d57fbfbce882f601ed7df2f2c6af630d\"}}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"linux-chromeos-dbg\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"swarm1567-c4\"], \"image\": [\"chrome-trusty-18042300-b7223b463e3\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4260-fb32526\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 tests failed: ['viz_unittests']\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1555518287161158",
"status": "COMPLETED",
"status_changed_ts": "1555518287636837",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11919",
"builder:linux-chromeos-dbg",
"buildset:commit/git/68c4de4bcc1dd52ead2a972e62fd1cea2a6bbb5d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/68c4de4bcc1dd52ead2a972e62fd1cea2a6bbb5d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081344785245390128",
"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/8915914723175873360/+/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:4444ecc7a40d5710",
"user_agent:luci-scheduler"
],
"updated_ts": "1555524025811817",
"url": "https://ci.chromium.org/b/8915914723175873360",
"utcnow_ts": "1555524026300887"
}
}
[18:00:26.310] Invocation finished in 1h38m4.221544656s with status FAILED