[15:12:46.923] New invocation is queued and will start shortly
[15:12:48.046] Starting the invocation (attempt 1)
[15:12:48.096] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:12:48.096] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:12:48.096] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9077544090656171744",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"ecbaee3f5b16bcb0533c93adf2e88080112f6a18\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9077544090656171744",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/ecbaee3f5b16bcb0533c93adf2e88080112f6a18",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ecbaee3f5b16bcb0533c93adf2e88080112f6a18",
"gitiles_ref:refs/heads/master"
]
}
[15:12:50.451] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1559142768548285",
"id": "8912114028285704240",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"ecbaee3f5b16bcb0533c93adf2e88080112f6a18\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1559142770118417",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/12639",
"builder:linux-chromeos-dbg",
"buildset:commit/git/ecbaee3f5b16bcb0533c93adf2e88080112f6a18",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ecbaee3f5b16bcb0533c93adf2e88080112f6a18",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077544090656171744",
"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/8912114028285704240/+/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": "1559142770118549",
"url": "https://ci.chromium.org/b/8912114028285704240",
"utcnow_ts": "1559142770435356"
}
}
[15:12:50.451] Task URL: https://ci.chromium.org/b/8912114028285704240
[15:12:50.451] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:2:0) after 1m0s
[15:13:50.505] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:2:0)
[15:13:50.505] Timer tick, asking Buildbucket for the build status
[15:13:51.993] Build 8912114028285704240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:13:51.993] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:3:0) after 1m0s
[15:14:52.110] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:3:0)
[15:14:52.111] Timer tick, asking Buildbucket for the build status
[15:14:53.610] Build 8912114028285704240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:14:53.610] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:4:0) after 1m0s
[15:15:53.664] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:4:0)
[15:15:53.664] Timer tick, asking Buildbucket for the build status
[15:15:53.911] Build 8912114028285704240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:15:53.911] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:5:0) after 1m0s
[15:16:53.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:5:0)
[15:16:53.986] Timer tick, asking Buildbucket for the build status
[15:16:54.402] Build 8912114028285704240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:16:54.402] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:6:0) after 1m0s
[15:17:54.438] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:6:0)
[15:17:54.438] Timer tick, asking Buildbucket for the build status
[15:17:54.621] Build 8912114028285704240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:17:54.622] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:7:0) after 1m0s
[15:18:54.667] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:7:0)
[15:18:54.667] Timer tick, asking Buildbucket for the build status
[15:18:54.854] Build 8912114028285704240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:18:54.854] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:8:0) after 1m0s
[15:19:03.921] Received PubSub notification, asking Buildbucket for the build status
[15:19:06.151] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:54.926] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:8:0)
[15:19:54.926] Timer tick, asking Buildbucket for the build status
[15:19:55.313] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:55.313] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:10:0) after 1m0s
[15:20:55.369] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:10:0)
[15:20:55.369] Timer tick, asking Buildbucket for the build status
[15:20:56.251] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:56.251] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:11:0) after 1m0s
[15:21:56.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:11:0)
[15:21:56.332] Timer tick, asking Buildbucket for the build status
[15:21:56.475] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:56.475] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:12:0) after 1m0s
[15:22:56.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:12:0)
[15:22:56.641] Timer tick, asking Buildbucket for the build status
[15:22:57.171] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:57.171] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:13:0) after 1m0s
[15:23:57.203] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:13:0)
[15:23:57.203] Timer tick, asking Buildbucket for the build status
[15:23:58.764] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:58.764] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:14:0) after 1m0s
[15:24:58.785] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:14:0)
[15:24:58.785] Timer tick, asking Buildbucket for the build status
[15:25:00.497] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:00.497] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:15:0) after 1m0s
[15:26:00.652] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:15:0)
[15:26:00.652] Timer tick, asking Buildbucket for the build status
[15:26:03.225] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:03.225] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:16:0) after 1m0s
[15:27:03.247] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:16:0)
[15:27:03.247] Timer tick, asking Buildbucket for the build status
[15:27:05.882] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:05.882] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:17:0) after 1m0s
[15:28:05.913] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:17:0)
[15:28:05.913] Timer tick, asking Buildbucket for the build status
[15:28:08.336] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:08.336] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:18:0) after 1m0s
[15:29:08.518] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:18:0)
[15:29:08.518] Timer tick, asking Buildbucket for the build status
[15:29:09.375] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:09.375] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:19:0) after 1m0s
[15:30:09.431] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:19:0)
[15:30:09.431] Timer tick, asking Buildbucket for the build status
[15:30:10.641] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:10.641] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:20:0) after 1m0s
[15:31:10.699] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:20:0)
[15:31:10.699] Timer tick, asking Buildbucket for the build status
[15:31:11.556] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:11.556] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:21:0) after 1m0s
[15:32:11.544] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:21:0)
[15:32:11.544] Timer tick, asking Buildbucket for the build status
[15:32:12.554] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:12.554] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:22:0) after 1m0s
[15:33:12.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:22:0)
[15:33:12.582] Timer tick, asking Buildbucket for the build status
[15:33:14.330] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:14.330] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:23:0) after 1m0s
[15:34:14.462] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:23:0)
[15:34:14.462] Timer tick, asking Buildbucket for the build status
[15:34:16.356] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:16.356] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:24:0) after 1m0s
[15:35:16.940] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:24:0)
[15:35:16.940] Timer tick, asking Buildbucket for the build status
[15:35:17.704] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:17.705] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:25:0) after 1m0s
[15:36:17.967] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:25:0)
[15:36:17.967] Timer tick, asking Buildbucket for the build status
[15:36:18.488] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:18.488] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:26:0) after 1m0s
[15:37:18.780] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:26:0)
[15:37:18.781] Timer tick, asking Buildbucket for the build status
[15:37:20.365] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:20.365] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:27:0) after 1m0s
[15:38:20.423] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:27:0)
[15:38:20.423] Timer tick, asking Buildbucket for the build status
[15:38:20.709] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:20.709] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:28:0) after 1m0s
[15:39:21.116] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:28:0)
[15:39:21.116] Timer tick, asking Buildbucket for the build status
[15:39:23.146] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:23.146] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:29:0) after 1m0s
[15:40:23.458] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:29:0)
[15:40:23.458] Timer tick, asking Buildbucket for the build status
[15:40:23.628] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:23.628] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:30:0) after 1m0s
[15:41:23.752] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:30:0)
[15:41:23.752] Timer tick, asking Buildbucket for the build status
[15:41:25.651] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:25.651] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:31:0) after 1m0s
[15:42:25.695] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:31:0)
[15:42:25.695] Timer tick, asking Buildbucket for the build status
[15:42:25.781] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:25.781] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:32:0) after 1m0s
[15:43:25.960] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:32:0)
[15:43:25.960] Timer tick, asking Buildbucket for the build status
[15:43:26.309] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:26.309] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:33:0) after 1m0s
[15:44:26.352] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:33:0)
[15:44:26.352] Timer tick, asking Buildbucket for the build status
[15:44:28.297] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:28.297] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:34:0) after 1m0s
[15:45:28.416] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:34:0)
[15:45:28.416] Timer tick, asking Buildbucket for the build status
[15:45:28.571] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:28.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:35:0) after 1m0s
[15:46:28.732] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:35:0)
[15:46:28.732] Timer tick, asking Buildbucket for the build status
[15:46:30.444] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:30.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:36:0) after 1m0s
[15:47:30.536] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:36:0)
[15:47:30.536] Timer tick, asking Buildbucket for the build status
[15:47:32.620] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:32.620] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:37:0) after 1m0s
[15:48:32.705] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:37:0)
[15:48:32.705] Timer tick, asking Buildbucket for the build status
[15:48:33.550] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:33.550] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:38:0) after 1m0s
[15:49:33.606] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:38:0)
[15:49:33.606] Timer tick, asking Buildbucket for the build status
[15:49:33.720] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:33.720] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:39:0) after 1m0s
[15:50:33.919] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:39:0)
[15:50:33.919] Timer tick, asking Buildbucket for the build status
[15:50:34.170] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:34.170] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:40:0) after 1m0s
[15:51:34.627] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:40:0)
[15:51:34.627] Timer tick, asking Buildbucket for the build status
[15:51:35.048] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:35.048] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:41:0) after 1m0s
[15:52:35.349] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:41:0)
[15:52:35.349] Timer tick, asking Buildbucket for the build status
[15:52:35.433] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:35.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:42:0) after 1m0s
[15:53:35.833] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:42:0)
[15:53:35.833] Timer tick, asking Buildbucket for the build status
[15:53:36.390] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:36.390] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:43:0) after 1m0s
[15:54:36.432] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:43:0)
[15:54:36.432] Timer tick, asking Buildbucket for the build status
[15:54:36.824] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:36.824] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:44:0) after 1m0s
[15:55:36.893] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:44:0)
[15:55:36.893] Timer tick, asking Buildbucket for the build status
[15:55:38.595] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:38.595] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:45:0) after 1m0s
[15:56:38.718] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:45:0)
[15:56:38.718] Timer tick, asking Buildbucket for the build status
[15:56:39.585] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:39.585] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:46:0) after 1m0s
[15:57:39.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:46:0)
[15:57:39.628] Timer tick, asking Buildbucket for the build status
[15:57:39.961] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:39.961] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:47:0) after 1m0s
[15:58:39.987] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:47:0)
[15:58:39.987] Timer tick, asking Buildbucket for the build status
[15:58:41.125] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:41.125] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:48:0) after 1m0s
[15:59:41.166] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:48:0)
[15:59:41.166] Timer tick, asking Buildbucket for the build status
[15:59:42.442] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:42.442] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:49:0) after 1m0s
[16:00:42.546] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:49:0)
[16:00:42.546] Timer tick, asking Buildbucket for the build status
[16:00:43.258] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:43.258] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:50:0) after 1m0s
[16:01:43.282] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:50:0)
[16:01:43.282] Timer tick, asking Buildbucket for the build status
[16:01:43.881] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:43.881] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:51:0) after 1m0s
[16:02:43.931] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:51:0)
[16:02:43.931] Timer tick, asking Buildbucket for the build status
[16:02:44.109] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:44.109] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:52:0) after 1m0s
[16:03:44.162] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:52:0)
[16:03:44.162] Timer tick, asking Buildbucket for the build status
[16:03:44.660] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:44.660] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:53:0) after 1m0s
[16:04:44.722] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:53:0)
[16:04:44.722] Timer tick, asking Buildbucket for the build status
[16:04:45.088] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:45.088] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:54:0) after 1m0s
[16:05:45.201] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:54:0)
[16:05:45.201] Timer tick, asking Buildbucket for the build status
[16:05:47.621] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:47.621] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:55:0) after 1m0s
[16:06:47.671] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:55:0)
[16:06:47.671] Timer tick, asking Buildbucket for the build status
[16:06:48.322] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:48.323] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:56:0) after 1m0s
[16:07:48.347] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:56:0)
[16:07:48.347] Timer tick, asking Buildbucket for the build status
[16:07:49.092] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:49.092] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:57:0) after 1m0s
[16:08:49.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:57:0)
[16:08:49.154] Timer tick, asking Buildbucket for the build status
[16:08:49.421] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:49.421] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:58:0) after 1m0s
[16:09:49.449] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:58:0)
[16:09:49.449] Timer tick, asking Buildbucket for the build status
[16:09:52.784] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:52.784] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:59:0) after 1m0s
[16:10:52.829] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:59:0)
[16:10:52.829] Timer tick, asking Buildbucket for the build status
[16:10:53.732] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:53.732] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:60:0) after 1m0s
[16:11:53.797] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:60:0)
[16:11:53.797] Timer tick, asking Buildbucket for the build status
[16:11:55.839] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:55.839] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:61:0) after 1m0s
[16:12:55.860] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:61:0)
[16:12:55.860] Timer tick, asking Buildbucket for the build status
[16:12:56.561] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:56.561] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:62:0) after 1m0s
[16:13:56.662] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:62:0)
[16:13:56.663] Timer tick, asking Buildbucket for the build status
[16:13:57.542] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:57.542] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:63:0) after 1m0s
[16:14:57.588] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:63:0)
[16:14:57.588] Timer tick, asking Buildbucket for the build status
[16:14:57.738] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:57.738] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:64:0) after 1m0s
[16:15:57.804] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:64:0)
[16:15:57.804] Timer tick, asking Buildbucket for the build status
[16:15:57.957] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:57.957] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:65:0) after 1m0s
[16:16:58.019] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:65:0)
[16:16:58.019] Timer tick, asking Buildbucket for the build status
[16:16:58.363] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:58.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:66:0) after 1m0s
[16:17:58.385] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:66:0)
[16:17:58.385] Timer tick, asking Buildbucket for the build status
[16:18:01.885] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:01.885] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:67:0) after 1m0s
[16:19:01.945] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:67:0)
[16:19:01.945] Timer tick, asking Buildbucket for the build status
[16:19:04.641] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:04.641] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:68:0) after 1m0s
[16:20:04.690] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:68:0)
[16:20:04.690] Timer tick, asking Buildbucket for the build status
[16:20:06.631] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:06.631] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:69:0) after 1m0s
[16:21:06.708] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:69:0)
[16:21:06.708] Timer tick, asking Buildbucket for the build status
[16:21:07.223] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:07.223] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:70:0) after 1m0s
[16:22:07.301] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:70:0)
[16:22:07.301] Timer tick, asking Buildbucket for the build status
[16:22:09.297] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:09.299] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:71:0) after 1m0s
[16:23:09.331] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:71:0)
[16:23:09.331] Timer tick, asking Buildbucket for the build status
[16:23:11.245] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:11.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:72:0) after 1m0s
[16:24:11.323] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:72:0)
[16:24:11.323] Timer tick, asking Buildbucket for the build status
[16:24:12.223] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:12.223] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:73:0) after 1m0s
[16:25:12.285] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:73:0)
[16:25:12.285] Timer tick, asking Buildbucket for the build status
[16:25:12.592] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:12.592] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:74:0) after 1m0s
[16:26:12.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:74:0)
[16:26:12.871] Timer tick, asking Buildbucket for the build status
[16:26:15.610] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:15.610] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:75:0) after 1m0s
[16:27:15.730] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:75:0)
[16:27:15.730] Timer tick, asking Buildbucket for the build status
[16:27:16.126] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:16.126] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:76:0) after 1m0s
[16:28:16.229] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:76:0)
[16:28:16.229] Timer tick, asking Buildbucket for the build status
[16:28:16.884] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:16.884] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:77:0) after 1m0s
[16:29:17.004] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:77:0)
[16:29:17.004] Timer tick, asking Buildbucket for the build status
[16:29:17.788] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:17.788] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:78:0) after 1m0s
[16:30:17.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:78:0)
[16:30:17.896] Timer tick, asking Buildbucket for the build status
[16:30:19.166] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:19.166] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:79:0) after 1m0s
[16:31:19.225] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:79:0)
[16:31:19.225] Timer tick, asking Buildbucket for the build status
[16:31:19.383] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:19.383] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:80:0) after 1m0s
[16:32:19.714] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:80:0)
[16:32:19.714] Timer tick, asking Buildbucket for the build status
[16:32:20.388] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:20.388] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:81:0) after 1m0s
[16:33:20.584] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:81:0)
[16:33:20.584] Timer tick, asking Buildbucket for the build status
[16:33:20.840] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:20.840] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:82:0) after 1m0s
[16:34:20.939] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:82:0)
[16:34:20.939] Timer tick, asking Buildbucket for the build status
[16:34:23.185] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:23.185] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:83:0) after 1m0s
[16:35:23.402] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:83:0)
[16:35:23.402] Timer tick, asking Buildbucket for the build status
[16:35:23.539] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:23.539] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:84:0) after 1m0s
[16:36:23.559] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:84:0)
[16:36:23.559] Timer tick, asking Buildbucket for the build status
[16:36:24.569] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:24.569] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:85:0) after 1m0s
[16:37:24.713] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:85:0)
[16:37:24.713] Timer tick, asking Buildbucket for the build status
[16:37:25.011] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:25.011] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:86:0) after 1m0s
[16:38:25.038] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:86:0)
[16:38:25.038] Timer tick, asking Buildbucket for the build status
[16:38:25.148] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:25.148] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:87:0) after 1m0s
[16:39:25.162] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:87:0)
[16:39:25.162] Timer tick, asking Buildbucket for the build status
[16:39:26.083] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:26.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:88:0) after 1m0s
[16:40:26.118] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:88:0)
[16:40:26.118] Timer tick, asking Buildbucket for the build status
[16:40:29.296] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:29.296] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:89:0) after 1m0s
[16:41:29.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:89:0)
[16:41:29.339] Timer tick, asking Buildbucket for the build status
[16:41:29.622] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:29.622] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:90:0) after 1m0s
[16:42:29.731] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:90:0)
[16:42:29.731] Timer tick, asking Buildbucket for the build status
[16:42:30.002] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:30.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:91:0) after 1m0s
[16:43:30.101] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:91:0)
[16:43:30.101] Timer tick, asking Buildbucket for the build status
[16:43:30.675] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:30.675] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:92:0) after 1m0s
[16:44:30.697] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:92:0)
[16:44:30.697] Timer tick, asking Buildbucket for the build status
[16:44:32.927] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:32.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:93:0) after 1m0s
[16:45:32.950] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:93:0)
[16:45:32.950] Timer tick, asking Buildbucket for the build status
[16:45:33.511] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:33.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:94:0) after 1m0s
[16:46:33.576] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:94:0)
[16:46:33.576] Timer tick, asking Buildbucket for the build status
[16:46:34.006] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:34.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:95:0) after 1m0s
[16:47:34.080] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:95:0)
[16:47:34.080] Timer tick, asking Buildbucket for the build status
[16:47:34.933] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:34.933] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:96:0) after 1m0s
[16:48:34.938] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:96:0)
[16:48:34.938] Timer tick, asking Buildbucket for the build status
[16:48:35.285] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:35.285] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:97:0) after 1m0s
[16:49:35.343] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:97:0)
[16:49:35.343] Timer tick, asking Buildbucket for the build status
[16:49:38.384] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:38.384] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:98:0) after 1m0s
[16:50:38.438] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:98:0)
[16:50:38.438] Timer tick, asking Buildbucket for the build status
[16:50:39.693] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:39.693] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:99:0) after 1m0s
[16:51:39.729] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:99:0)
[16:51:39.729] Timer tick, asking Buildbucket for the build status
[16:51:41.640] Build 8912114028285704240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:41.640] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9077544090656171744:100:0) after 1m0s
[16:52:40.120] Received PubSub notification, asking Buildbucket for the build status
[16:52:40.570] Build 8912114028285704240: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[16:52:40.570] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1559148755875186",
"created_by": "project:chromium",
"created_ts": "1559142768548285",
"failure_reason": "BUILD_FAILURE",
"id": "8912114028285704240",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"ecbaee3f5b16bcb0533c93adf2e88080112f6a18\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"3 tests failed: ['browser_tests', 'non_network_service_browser_tests', 'viz_browser_tests']\"}, \"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\": 12639, \"got_angle_revision\": \"262e2824847798c7549da0f79278fe7d6c9faea7\", \"got_dawn_revision\": \"26d3cf08c209c662a6e2298c301272e2eb8246e4\", \"got_nacl_revision\": \"0ddc033406886a709b901e0c312872529f9705e8\", \"got_revision\": \"ecbaee3f5b16bcb0533c93adf2e88080112f6a18\", \"got_revision_cp\": \"refs/heads/master@{#664276}\", \"got_swarming_client_revision\": \"779c4f0f8488c64587b75dbb001d18c3c0c4cda9\", \"got_v8_revision\": \"2cb25737c9eb7893ac752674e75d400b4419f67c\", \"got_v8_revision_cp\": \"refs/heads/7.6.303@{#1}\", \"got_webrtc_revision\": \"2f5554dae54d25918c2092f266eb8edf70801037\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28102}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.chromiumos\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"ecbaee3f5b16bcb0533c93adf2e88080112f6a18\", \"swarm_hashes\": {\"accessibility_unittests\": \"fc4e1571c51135cb25543f32366710c297c347d9\", \"angle_unittests\": \"aedebc89af09c04ecdd9de80a388426e22e658da\", \"app_list_unittests\": \"ebf858d22b6e72a84150f4e0c477ed16970fa388\", \"app_shell_unittests\": \"862089e852fb04a30453e5cadc094e53a0c6232b\", \"ash_unittests\": \"99c497fcb8cfa38dd7e1bd34915faf446f86f29f\", \"aura_unittests\": \"40584a47eb68d64f5a73545e9f778ee0885febf7\", \"base_unittests\": \"368d54ae6b8740fb71a76e615db117ded0805087\", \"base_util_unittests\": \"4b804ef29d0747121e6e4040616bb3ee9aea7431\", \"blink_common_unittests\": \"6abce0df7865b144c758f625ad8101cfececee2f\", \"blink_fuzzer_unittests\": \"b2a277518953c4b0969a152acd8208829988ac16\", \"blink_heap_unittests\": \"96a4305fae3abd72d51e59b4c2b984ad50828272\", \"blink_platform_unittests\": \"d8d823d9aff88607160c5ab5b15c849687a934f8\", \"blink_unittests\": \"fe5ce729719139539d011bc4ec3319999ea1752c\", \"boringssl_crypto_tests\": \"a7b2b0fc4af66b3816bf331e26c3c01e428ef54c\", \"boringssl_ssl_tests\": \"7e41917d46d76e8efe71d104c4a00030078f2449\", \"browser_tests\": \"7050c8a31d82b2d010ea381cd3e0ca0c9df72bf8\", \"cacheinvalidation_unittests\": \"4bea1e9dfd62dacb1e51085d0f787fb7d8f73284\", \"capture_unittests\": \"2bf16d560219e0da373fed9c51a5500618af705a\", \"cast_unittests\": \"4362ff53fa8e4f114ae9bbaf387903a512bf5461\", \"cc_unittests\": \"fbef0a64fc747304bfd3c79c4bd9325fe956c08e\", \"chrome_app_unittests\": \"e350a2797bc947ac52d72c7461082a5779f8e44f\", \"chromedriver_unittests\": \"2eb485329ed20061819b0943866fcff129035296\", \"chromeos_components_unittests\": \"a9bdbdb594f6c3d061474e64c40c72e3fd5251a9\", \"chromeos_unittests\": \"ba76c9bb8c9900eae827b06c7ac80e264061a15a\", \"components_browsertests\": \"bd43e675809f0c409701813bfed3d735264d2ee3\", \"components_unittests\": \"a9fab11b1de46bd5689ac0ee1739f16c0e99cd52\", \"compositor_unittests\": \"15fa3e13a341f3835a16f2b0d6611eaa614ca5f8\", \"content_browsertests\": \"265f5891ef296677dc6fef2db15543ec3bd0c119\", \"content_unittests\": \"15beb177ef2e1667501b60f03d108bcdcc7e40d5\", \"crypto_unittests\": \"b7d48425a36bc864690991e019b5874de0064176\", \"dbus_unittests\": \"428db39abb55564f6424c1a0b414c7afe9942c10\", \"device_unittests\": \"6ee29ad6921c28dbb853cc342d7b862b36fe7b23\", \"display_unittests\": \"20e5b4e860dfd6606fe0fd46a10a39027b5137c7\", \"events_unittests\": \"c9e9650f3e40a39bd1d49f049da0fa69d2c25b12\", \"exo_unittests\": \"cd0e75f56b7ef74a234b6d875b212dacbf6d9179\", \"extensions_browsertests\": \"0805ca008a51f2e97a673938385fb1dbf3abb762\", \"extensions_unittests\": \"2652e7e4be276a6348abc1754fed8901de97e80b\", \"filesystem_service_unittests\": \"1058d13b9cad500a4a3a3d054c9e1141bceed7d5\", \"gcm_unit_tests\": \"e618c64a4685bbcd315031a9ad24cb82f570ae57\", \"gfx_unittests\": \"f0c77aca347b535a5c3ac6b6749ffa6f0e3649a9\", \"gin_unittests\": \"8d59500592ebd1b67de960de7f0e02098b94d56a\", \"gl_unittests_ozone\": \"a188a1e55e6c67676ba2730b8790bb5ec24fa77f\", \"google_apis_unittests\": \"fd3749a8b89ccef10fe5ea5578bcd418b124eddf\", \"gpu_unittests\": \"41ee7b46c92344e263c564016370dd9949e7d978\", \"interactive_ui_tests\": \"04df1bb9b793beee8eba45de19e5ec4c0ef58216\", \"ipc_tests\": \"205aa1bb1cc5ca45d75eccf55f64716c11391de5\", \"jingle_unittests\": \"8581d330ddfbfd58c457b42894187b76a603128a\", \"keyboard_unittests\": \"1f8720cd2f5c19798f1126c9969699ce7bbbbf14\", \"latency_unittests\": \"2a69cf3b34eb5fb51695542404f8379cefc54fc2\", \"leveldb_service_unittests\": \"54692e65626e857554117019a6f95ec9639b93ee\", \"libjingle_xmpp_unittests\": \"946eb83bc8be2c9bc5c5f0c141c292c38c6ba72a\", \"media_blink_unittests\": \"1e9195aeae9f3da1a3804da3335b6979d4a61334\", \"media_service_unittests\": \"91d7809682490fabe8bf6eff7e274682faa75358\", \"media_unittests\": \"c91a255bb84b370a52912119a20d6283b6b7428c\", \"message_center_unittests\": \"72fedb048117373f577566c0f1a608d23b70abd5\", \"midi_unittests\": \"9037937fe4d523de5fc7f300c2b9be240e0efb2e\", \"mojo_core_unittests\": \"658ba2d05acf6f103ebbb7c9319c02d046268bfc\", \"mojo_unittests\": \"0ba47ebf832a32d026bb16215f67651860cd28c3\", \"nacl_helper_nonsfi_unittests\": \"bdebde0398535f49273e1d5001d9b3e0251bd67c\", \"nacl_loader_unittests\": \"d579a5891bf26811af1bba2f556d7c977bd59e9e\", \"native_theme_unittests\": \"b4a1bd443090184d1af77814bfa91b29482e5d4b\", \"net_unittests\": \"a5370391c3c66d75a5507dbeed2145dcf98e8b99\", \"ozone_gl_unittests\": \"b62abc27a15e0a763fa5eb58a65be4547365e6d7\", \"ozone_unittests\": \"3838f2c08738f6d1e7b5bcf138e846184055968c\", \"ozone_x11_unittests\": \"7677dccfbc2fd3c6a32a41b89fb0b8b72c920b0c\", \"pdf_unittests\": \"8fdcf88a5bb863fb6cbffde3905f3b8034eaea2b\", \"perfetto_unittests\": \"4ed21a9a4b37ac0ef6983fe981c1e0a29790d947\", \"ppapi_unittests\": \"d4272023559e238aecdd34782e87554d64054d51\", \"printing_unittests\": \"3f2af4a4d2b3240a46f6b7146b6c9473cfeec6fd\", \"remoting_unittests\": \"6d81c9952e5eff169c9ada7f843207c528d9f73d\", \"sandbox_linux_unittests\": \"8bba0a9f8e348867fa13a8639c396ac892913cbf\", \"service_manager_unittests\": \"9cba7914217e5845e125b15b79efeaa4a9937940\", \"services_unittests\": \"c3d9546fae4e3bb054b404e55a63ff81e99a930b\", \"shell_dialogs_unittests\": \"8532ad0c070e27eb3114f1986acdcbb27427bdc5\", \"skia_unittests\": \"ada5cb3a9f5fdbbb22bb94bed5fe22da469cf185\", \"snapshot_unittests\": \"a2294f0ca11fa7d34c7a382d40b92eac0f73c623\", \"sql_unittests\": \"ead385e4a79a7c4e1399e26dbc8c5561755d70a7\", \"storage_unittests\": \"a142cb9e90b10fa23a1de89c8a8866a85263e817\", \"sync_integration_tests\": \"fdaab11757ae3270849f63ffcdf3ac4df8fdbf80\", \"traffic_annotation_auditor_unittests\": \"59ac501f78ea9eb020af3756def02b6b262a6d9e\", \"ui_base_unittests\": \"23dda338587cced118c04eeac9001992641b2331\", \"ui_chromeos_unittests\": \"6fa0e528aff19a7a12d6c0818e05b4fece8f8c0b\", \"ui_touch_selection_unittests\": \"23875c3f2253558e1df8a6803aeabaceb0697e40\", \"unit_tests\": \"ece0a091c2003cd7971b10d7f376f3a42a863a53\", \"url_unittests\": \"66f417f7b29df900f74b608393b78b14f08a50ca\", \"usage_time_limit_unittests\": \"a4d617cf11de442bd9ea985a2424521d786766fa\", \"views_unittests\": \"b400e0cf08e01ea820a8600b6a3559dc25139f16\", \"viz_unittests\": \"9fd1dbe11011098d5424e6e2cac73d75222a92c9\", \"wm_unittests\": \"68a9dc46d2b7b14a514b21b241cd9d6e9d626a01\", \"wtf_unittests\": \"3f31a8d696f2e7179a8a7317a0a7fd941ea1cf0c\"}, \"swarm_hashes_refs/heads/master(at){#664276}_without_patch\": {\"accessibility_unittests\": \"fc4e1571c51135cb25543f32366710c297c347d9\", \"angle_unittests\": \"aedebc89af09c04ecdd9de80a388426e22e658da\", \"app_list_unittests\": \"ebf858d22b6e72a84150f4e0c477ed16970fa388\", \"app_shell_unittests\": \"862089e852fb04a30453e5cadc094e53a0c6232b\", \"ash_unittests\": \"99c497fcb8cfa38dd7e1bd34915faf446f86f29f\", \"aura_unittests\": \"40584a47eb68d64f5a73545e9f778ee0885febf7\", \"base_unittests\": \"368d54ae6b8740fb71a76e615db117ded0805087\", \"base_util_unittests\": \"4b804ef29d0747121e6e4040616bb3ee9aea7431\", \"blink_common_unittests\": \"6abce0df7865b144c758f625ad8101cfececee2f\", \"blink_fuzzer_unittests\": \"b2a277518953c4b0969a152acd8208829988ac16\", \"blink_heap_unittests\": \"96a4305fae3abd72d51e59b4c2b984ad50828272\", \"blink_platform_unittests\": \"d8d823d9aff88607160c5ab5b15c849687a934f8\", \"blink_unittests\": \"fe5ce729719139539d011bc4ec3319999ea1752c\", \"boringssl_crypto_tests\": \"a7b2b0fc4af66b3816bf331e26c3c01e428ef54c\", \"boringssl_ssl_tests\": \"7e41917d46d76e8efe71d104c4a00030078f2449\", \"browser_tests\": \"7050c8a31d82b2d010ea381cd3e0ca0c9df72bf8\", \"cacheinvalidation_unittests\": \"4bea1e9dfd62dacb1e51085d0f787fb7d8f73284\", \"capture_unittests\": \"2bf16d560219e0da373fed9c51a5500618af705a\", \"cast_unittests\": \"4362ff53fa8e4f114ae9bbaf387903a512bf5461\", \"cc_unittests\": \"fbef0a64fc747304bfd3c79c4bd9325fe956c08e\", \"chrome_app_unittests\": \"e350a2797bc947ac52d72c7461082a5779f8e44f\", \"chromedriver_unittests\": \"2eb485329ed20061819b0943866fcff129035296\", \"chromeos_components_unittests\": \"a9bdbdb594f6c3d061474e64c40c72e3fd5251a9\", \"chromeos_unittests\": \"ba76c9bb8c9900eae827b06c7ac80e264061a15a\", \"components_browsertests\": \"bd43e675809f0c409701813bfed3d735264d2ee3\", \"components_unittests\": \"a9fab11b1de46bd5689ac0ee1739f16c0e99cd52\", \"compositor_unittests\": \"15fa3e13a341f3835a16f2b0d6611eaa614ca5f8\", \"content_browsertests\": \"265f5891ef296677dc6fef2db15543ec3bd0c119\", \"content_unittests\": \"15beb177ef2e1667501b60f03d108bcdcc7e40d5\", \"crypto_unittests\": \"b7d48425a36bc864690991e019b5874de0064176\", \"dbus_unittests\": \"428db39abb55564f6424c1a0b414c7afe9942c10\", \"device_unittests\": \"6ee29ad6921c28dbb853cc342d7b862b36fe7b23\", \"display_unittests\": \"20e5b4e860dfd6606fe0fd46a10a39027b5137c7\", \"events_unittests\": \"c9e9650f3e40a39bd1d49f049da0fa69d2c25b12\", \"exo_unittests\": \"cd0e75f56b7ef74a234b6d875b212dacbf6d9179\", \"extensions_browsertests\": \"0805ca008a51f2e97a673938385fb1dbf3abb762\", \"extensions_unittests\": \"2652e7e4be276a6348abc1754fed8901de97e80b\", \"filesystem_service_unittests\": \"1058d13b9cad500a4a3a3d054c9e1141bceed7d5\", \"gcm_unit_tests\": \"e618c64a4685bbcd315031a9ad24cb82f570ae57\", \"gfx_unittests\": \"f0c77aca347b535a5c3ac6b6749ffa6f0e3649a9\", \"gin_unittests\": \"8d59500592ebd1b67de960de7f0e02098b94d56a\", \"gl_unittests_ozone\": \"a188a1e55e6c67676ba2730b8790bb5ec24fa77f\", \"google_apis_unittests\": \"fd3749a8b89ccef10fe5ea5578bcd418b124eddf\", \"gpu_unittests\": \"41ee7b46c92344e263c564016370dd9949e7d978\", \"interactive_ui_tests\": \"04df1bb9b793beee8eba45de19e5ec4c0ef58216\", \"ipc_tests\": \"205aa1bb1cc5ca45d75eccf55f64716c11391de5\", \"jingle_unittests\": \"8581d330ddfbfd58c457b42894187b76a603128a\", \"keyboard_unittests\": \"1f8720cd2f5c19798f1126c9969699ce7bbbbf14\", \"latency_unittests\": \"2a69cf3b34eb5fb51695542404f8379cefc54fc2\", \"leveldb_service_unittests\": \"54692e65626e857554117019a6f95ec9639b93ee\", \"libjingle_xmpp_unittests\": \"946eb83bc8be2c9bc5c5f0c141c292c38c6ba72a\", \"media_blink_unittests\": \"1e9195aeae9f3da1a3804da3335b6979d4a61334\", \"media_service_unittests\": \"91d7809682490fabe8bf6eff7e274682faa75358\", \"media_unittests\": \"c91a255bb84b370a52912119a20d6283b6b7428c\", \"message_center_unittests\": \"72fedb048117373f577566c0f1a608d23b70abd5\", \"midi_unittests\": \"9037937fe4d523de5fc7f300c2b9be240e0efb2e\", \"mojo_core_unittests\": \"658ba2d05acf6f103ebbb7c9319c02d046268bfc\", \"mojo_unittests\": \"0ba47ebf832a32d026bb16215f67651860cd28c3\", \"nacl_helper_nonsfi_unittests\": \"bdebde0398535f49273e1d5001d9b3e0251bd67c\", \"nacl_loader_unittests\": \"d579a5891bf26811af1bba2f556d7c977bd59e9e\", \"native_theme_unittests\": \"b4a1bd443090184d1af77814bfa91b29482e5d4b\", \"net_unittests\": \"a5370391c3c66d75a5507dbeed2145dcf98e8b99\", \"ozone_gl_unittests\": \"b62abc27a15e0a763fa5eb58a65be4547365e6d7\", \"ozone_unittests\": \"3838f2c08738f6d1e7b5bcf138e846184055968c\", \"ozone_x11_unittests\": \"7677dccfbc2fd3c6a32a41b89fb0b8b72c920b0c\", \"pdf_unittests\": \"8fdcf88a5bb863fb6cbffde3905f3b8034eaea2b\", \"perfetto_unittests\": \"4ed21a9a4b37ac0ef6983fe981c1e0a29790d947\", \"ppapi_unittests\": \"d4272023559e238aecdd34782e87554d64054d51\", \"printing_unittests\": \"3f2af4a4d2b3240a46f6b7146b6c9473cfeec6fd\", \"remoting_unittests\": \"6d81c9952e5eff169c9ada7f843207c528d9f73d\", \"sandbox_linux_unittests\": \"8bba0a9f8e348867fa13a8639c396ac892913cbf\", \"service_manager_unittests\": \"9cba7914217e5845e125b15b79efeaa4a9937940\", \"services_unittests\": \"c3d9546fae4e3bb054b404e55a63ff81e99a930b\", \"shell_dialogs_unittests\": \"8532ad0c070e27eb3114f1986acdcbb27427bdc5\", \"skia_unittests\": \"ada5cb3a9f5fdbbb22bb94bed5fe22da469cf185\", \"snapshot_unittests\": \"a2294f0ca11fa7d34c7a382d40b92eac0f73c623\", \"sql_unittests\": \"ead385e4a79a7c4e1399e26dbc8c5561755d70a7\", \"storage_unittests\": \"a142cb9e90b10fa23a1de89c8a8866a85263e817\", \"sync_integration_tests\": \"fdaab11757ae3270849f63ffcdf3ac4df8fdbf80\", \"traffic_annotation_auditor_unittests\": \"59ac501f78ea9eb020af3756def02b6b262a6d9e\", \"ui_base_unittests\": \"23dda338587cced118c04eeac9001992641b2331\", \"ui_chromeos_unittests\": \"6fa0e528aff19a7a12d6c0818e05b4fece8f8c0b\", \"ui_touch_selection_unittests\": \"23875c3f2253558e1df8a6803aeabaceb0697e40\", \"unit_tests\": \"ece0a091c2003cd7971b10d7f376f3a42a863a53\", \"url_unittests\": \"66f417f7b29df900f74b608393b78b14f08a50ca\", \"usage_time_limit_unittests\": \"a4d617cf11de442bd9ea985a2424521d786766fa\", \"views_unittests\": \"b400e0cf08e01ea820a8600b6a3559dc25139f16\", \"viz_unittests\": \"9fd1dbe11011098d5424e6e2cac73d75222a92c9\", \"wm_unittests\": \"68a9dc46d2b7b14a514b21b241cd9d6e9d626a01\", \"wtf_unittests\": \"3f31a8d696f2e7179a8a7317a0a7fd941ea1cf0c\"}}, \"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\"], \"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\": [\"4303-bae210e\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"3 tests failed: ['browser_tests', 'non_network_service_browser_tests', 'viz_browser_tests']\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1559143142413101",
"status": "COMPLETED",
"status_changed_ts": "1559143143659506",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/12639",
"builder:linux-chromeos-dbg",
"buildset:commit/git/ecbaee3f5b16bcb0533c93adf2e88080112f6a18",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ecbaee3f5b16bcb0533c93adf2e88080112f6a18",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077544090656171744",
"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/8912114028285704240/+/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:451cf8300e90e410",
"user_agent:luci-scheduler"
],
"updated_ts": "1559148759340648",
"url": "https://ci.chromium.org/b/8912114028285704240",
"utcnow_ts": "1559148760415409"
}
}
[16:52:40.570] Invocation finished in 1h39m53.667016287s with status FAILED