[18:16:44.804] New invocation is queued and will start shortly
[18:16:45.880] Starting the invocation (attempt 1)
[18:16:45.925] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:16:45.925] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:16:45.925] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9082696543683040976",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"b7bd4ca415d67340d082de31be87f162ed6070a6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9082696543683040976",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/b7bd4ca415d67340d082de31be87f162ed6070a6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b7bd4ca415d67340d082de31be87f162ed6070a6",
"gitiles_ref:refs/heads/master"
]
}
[18:16:46.741] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1554229006091702",
"id": "8917266481667526160",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"b7bd4ca415d67340d082de31be87f162ed6070a6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554229006587389",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11660",
"builder:linux-chromeos-dbg",
"buildset:commit/git/b7bd4ca415d67340d082de31be87f162ed6070a6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b7bd4ca415d67340d082de31be87f162ed6070a6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082696543683040976",
"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/8917266481667526160/+/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": "1554229006587480",
"url": "https://ci.chromium.org/b/8917266481667526160",
"utcnow_ts": "1554229006731253"
}
}
[18:16:46.741] Task URL: https://ci.chromium.org/b/8917266481667526160
[18:16:46.741] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:2:0) after 1m0s
[18:17:46.760] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:2:0)
[18:17:46.760] Timer tick, asking Buildbucket for the build status
[18:17:47.328] Build 8917266481667526160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:17:47.328] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:3:0) after 1m0s
[18:18:47.309] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:3:0)
[18:18:47.309] Timer tick, asking Buildbucket for the build status
[18:18:47.591] Build 8917266481667526160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:18:47.591] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:4:0) after 1m0s
[18:19:47.607] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:4:0)
[18:19:47.607] Timer tick, asking Buildbucket for the build status
[18:19:47.751] Build 8917266481667526160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:19:47.751] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:5:0) after 1m0s
[18:20:47.780] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:5:0)
[18:20:47.780] Timer tick, asking Buildbucket for the build status
[18:20:47.954] Build 8917266481667526160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:20:47.954] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:6:0) after 1m0s
[18:21:48.033] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:6:0)
[18:21:48.033] Timer tick, asking Buildbucket for the build status
[18:21:48.432] Build 8917266481667526160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:21:48.432] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:7:0) after 1m0s
[18:21:50.786] Received PubSub notification, asking Buildbucket for the build status
[18:21:50.966] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:48.476] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:7:0)
[18:22:48.476] Timer tick, asking Buildbucket for the build status
[18:22:48.599] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:48.599] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:9:0) after 1m0s
[18:23:48.615] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:9:0)
[18:23:48.615] Timer tick, asking Buildbucket for the build status
[18:23:48.804] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:48.804] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:10:0) after 1m0s
[18:24:48.870] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:10:0)
[18:24:48.870] Timer tick, asking Buildbucket for the build status
[18:24:49.290] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:49.290] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:11:0) after 1m0s
[18:25:49.315] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:11:0)
[18:25:49.315] Timer tick, asking Buildbucket for the build status
[18:25:50.089] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:50.089] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:12:0) after 1m0s
[18:26:50.173] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:12:0)
[18:26:50.173] Timer tick, asking Buildbucket for the build status
[18:26:50.578] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:50.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:13:0) after 1m0s
[18:27:50.630] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:13:0)
[18:27:50.630] Timer tick, asking Buildbucket for the build status
[18:27:50.792] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:50.792] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:14:0) after 1m0s
[18:28:50.814] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:14:0)
[18:28:50.814] Timer tick, asking Buildbucket for the build status
[18:28:51.005] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:51.005] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:15:0) after 1m0s
[18:29:51.026] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:15:0)
[18:29:51.026] Timer tick, asking Buildbucket for the build status
[18:29:51.137] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:51.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:16:0) after 1m0s
[18:30:51.158] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:16:0)
[18:30:51.158] Timer tick, asking Buildbucket for the build status
[18:30:51.731] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:51.731] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:17:0) after 1m0s
[18:31:51.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:17:0)
[18:31:51.712] Timer tick, asking Buildbucket for the build status
[18:31:52.316] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:52.316] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:18:0) after 1m0s
[18:32:52.341] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:18:0)
[18:32:52.341] Timer tick, asking Buildbucket for the build status
[18:32:52.742] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:52.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:19:0) after 1m0s
[18:33:52.749] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:19:0)
[18:33:52.749] Timer tick, asking Buildbucket for the build status
[18:33:53.074] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:53.074] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:20:0) after 1m0s
[18:34:53.093] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:20:0)
[18:34:53.093] Timer tick, asking Buildbucket for the build status
[18:34:53.175] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:53.175] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:21:0) after 1m0s
[18:35:53.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:21:0)
[18:35:53.199] Timer tick, asking Buildbucket for the build status
[18:35:53.333] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:53.333] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:22:0) after 1m0s
[18:36:53.335] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:22:0)
[18:36:53.335] Timer tick, asking Buildbucket for the build status
[18:36:53.586] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:53.586] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:23:0) after 1m0s
[18:37:53.594] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:23:0)
[18:37:53.594] Timer tick, asking Buildbucket for the build status
[18:37:54.374] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:54.374] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:24:0) after 1m0s
[18:38:54.380] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:24:0)
[18:38:54.380] Timer tick, asking Buildbucket for the build status
[18:38:55.280] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:55.280] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:25:0) after 1m0s
[18:39:55.302] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:25:0)
[18:39:55.302] Timer tick, asking Buildbucket for the build status
[18:39:55.423] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:55.423] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:26:0) after 1m0s
[18:40:55.441] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:26:0)
[18:40:55.441] Timer tick, asking Buildbucket for the build status
[18:40:55.568] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:55.568] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:27:0) after 1m0s
[18:41:55.588] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:27:0)
[18:41:55.588] Timer tick, asking Buildbucket for the build status
[18:41:55.682] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:55.682] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:28:0) after 1m0s
[18:42:55.702] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:28:0)
[18:42:55.702] Timer tick, asking Buildbucket for the build status
[18:42:55.864] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:55.864] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:29:0) after 1m0s
[18:43:55.884] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:29:0)
[18:43:55.884] Timer tick, asking Buildbucket for the build status
[18:43:56.313] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:56.313] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:30:0) after 1m0s
[18:44:56.286] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:30:0)
[18:44:56.286] Timer tick, asking Buildbucket for the build status
[18:44:56.743] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:56.743] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:31:0) after 1m0s
[18:45:56.767] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:31:0)
[18:45:56.767] Timer tick, asking Buildbucket for the build status
[18:45:57.206] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:57.206] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:32:0) after 1m0s
[18:46:57.241] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:32:0)
[18:46:57.241] Timer tick, asking Buildbucket for the build status
[18:46:57.354] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:57.354] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:33:0) after 1m0s
[18:47:57.345] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:33:0)
[18:47:57.345] Timer tick, asking Buildbucket for the build status
[18:47:57.575] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:57.575] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:34:0) after 1m0s
[18:48:57.559] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:34:0)
[18:48:57.559] Timer tick, asking Buildbucket for the build status
[18:48:57.892] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:57.892] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:35:0) after 1m0s
[18:49:57.872] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:35:0)
[18:49:57.872] Timer tick, asking Buildbucket for the build status
[18:49:58.112] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:58.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:36:0) after 1m0s
[18:50:58.119] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:36:0)
[18:50:58.119] Timer tick, asking Buildbucket for the build status
[18:50:58.604] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:58.604] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:37:0) after 1m0s
[18:51:58.581] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:37:0)
[18:51:58.581] Timer tick, asking Buildbucket for the build status
[18:51:58.994] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:58.994] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:38:0) after 1m0s
[18:52:58.968] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:38:0)
[18:52:58.968] Timer tick, asking Buildbucket for the build status
[18:52:59.317] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:59.318] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:39:0) after 1m0s
[18:53:59.320] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:39:0)
[18:53:59.320] Timer tick, asking Buildbucket for the build status
[18:54:00.397] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:00.397] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:40:0) after 1m0s
[18:55:00.420] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:40:0)
[18:55:00.420] Timer tick, asking Buildbucket for the build status
[18:55:01.241] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:01.241] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:41:0) after 1m0s
[18:56:01.262] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:41:0)
[18:56:01.262] Timer tick, asking Buildbucket for the build status
[18:56:01.970] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:01.970] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:42:0) after 1m0s
[18:57:02.011] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:42:0)
[18:57:02.011] Timer tick, asking Buildbucket for the build status
[18:57:02.872] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:02.872] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:43:0) after 1m0s
[18:58:02.948] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:43:0)
[18:58:02.948] Timer tick, asking Buildbucket for the build status
[18:58:03.115] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:03.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:44:0) after 1m0s
[18:59:03.141] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:44:0)
[18:59:03.141] Timer tick, asking Buildbucket for the build status
[18:59:04.309] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:04.309] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:45:0) after 1m0s
[19:00:04.296] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:45:0)
[19:00:04.296] Timer tick, asking Buildbucket for the build status
[19:00:04.516] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:04.516] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:46:0) after 1m0s
[19:01:04.529] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:46:0)
[19:01:04.529] Timer tick, asking Buildbucket for the build status
[19:01:04.896] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:04.896] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:47:0) after 1m0s
[19:02:04.880] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:47:0)
[19:02:04.880] Timer tick, asking Buildbucket for the build status
[19:02:05.324] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:05.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:48:0) after 1m0s
[19:03:05.325] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:48:0)
[19:03:05.325] Timer tick, asking Buildbucket for the build status
[19:03:06.061] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:06.061] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:49:0) after 1m0s
[19:04:06.087] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:49:0)
[19:04:06.087] Timer tick, asking Buildbucket for the build status
[19:04:06.340] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:06.340] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:50:0) after 1m0s
[19:05:06.338] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:50:0)
[19:05:06.339] Timer tick, asking Buildbucket for the build status
[19:05:06.645] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:06.645] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:51:0) after 1m0s
[19:06:06.766] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:51:0)
[19:06:06.766] Timer tick, asking Buildbucket for the build status
[19:06:09.019] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:09.019] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:52:0) after 1m0s
[19:07:09.061] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:52:0)
[19:07:09.061] Timer tick, asking Buildbucket for the build status
[19:07:09.323] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:09.323] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:53:0) after 1m0s
[19:08:09.344] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:53:0)
[19:08:09.344] Timer tick, asking Buildbucket for the build status
[19:08:09.729] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:09.730] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:54:0) after 1m0s
[19:09:09.751] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:54:0)
[19:09:09.751] Timer tick, asking Buildbucket for the build status
[19:09:09.847] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:09.847] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:55:0) after 1m0s
[19:10:09.872] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:55:0)
[19:10:09.872] Timer tick, asking Buildbucket for the build status
[19:10:10.099] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:10.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:56:0) after 1m0s
[19:11:10.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:56:0)
[19:11:10.122] Timer tick, asking Buildbucket for the build status
[19:11:10.412] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:10.412] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:57:0) after 1m0s
[19:12:10.397] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:57:0)
[19:12:10.397] Timer tick, asking Buildbucket for the build status
[19:12:10.797] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:10.797] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:58:0) after 1m0s
[19:13:10.801] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:58:0)
[19:13:10.801] Timer tick, asking Buildbucket for the build status
[19:13:11.579] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:11.579] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:59:0) after 1m0s
[19:14:11.603] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:59:0)
[19:14:11.603] Timer tick, asking Buildbucket for the build status
[19:14:11.736] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:11.736] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:60:0) after 1m0s
[19:15:11.758] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:60:0)
[19:15:11.758] Timer tick, asking Buildbucket for the build status
[19:15:11.855] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:11.855] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:61:0) after 1m0s
[19:16:11.855] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:61:0)
[19:16:11.855] Timer tick, asking Buildbucket for the build status
[19:16:11.964] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:11.964] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:62:0) after 1m0s
[19:17:11.985] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:62:0)
[19:17:11.985] Timer tick, asking Buildbucket for the build status
[19:17:12.250] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:12.250] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:63:0) after 1m0s
[19:18:12.249] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:63:0)
[19:18:12.249] Timer tick, asking Buildbucket for the build status
[19:18:12.903] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:12.903] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:64:0) after 1m0s
[19:19:12.880] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:64:0)
[19:19:12.880] Timer tick, asking Buildbucket for the build status
[19:19:13.723] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:13.723] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:65:0) after 1m0s
[19:20:13.743] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:65:0)
[19:20:13.743] Timer tick, asking Buildbucket for the build status
[19:20:15.236] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:15.236] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:66:0) after 1m0s
[19:21:15.255] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:66:0)
[19:21:15.255] Timer tick, asking Buildbucket for the build status
[19:21:15.514] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:15.514] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:67:0) after 1m0s
[19:22:15.577] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:67:0)
[19:22:15.577] Timer tick, asking Buildbucket for the build status
[19:22:15.838] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:15.838] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:68:0) after 1m0s
[19:23:15.864] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:68:0)
[19:23:15.864] Timer tick, asking Buildbucket for the build status
[19:23:16.247] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:16.247] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:69:0) after 1m0s
[19:24:16.247] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:69:0)
[19:24:16.247] Timer tick, asking Buildbucket for the build status
[19:24:16.485] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:16.485] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:70:0) after 1m0s
[19:25:16.519] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:70:0)
[19:25:16.519] Timer tick, asking Buildbucket for the build status
[19:25:17.041] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:17.041] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:71:0) after 1m0s
[19:26:17.077] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:71:0)
[19:26:17.077] Timer tick, asking Buildbucket for the build status
[19:26:17.234] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:17.234] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:72:0) after 1m0s
[19:27:17.258] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:72:0)
[19:27:17.258] Timer tick, asking Buildbucket for the build status
[19:27:18.286] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:18.286] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:73:0) after 1m0s
[19:28:18.306] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:73:0)
[19:28:18.306] Timer tick, asking Buildbucket for the build status
[19:28:18.419] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:18.419] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:74:0) after 1m0s
[19:29:18.416] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:74:0)
[19:29:18.416] Timer tick, asking Buildbucket for the build status
[19:29:18.887] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:18.887] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:75:0) after 1m0s
[19:30:18.911] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:75:0)
[19:30:18.911] Timer tick, asking Buildbucket for the build status
[19:30:19.021] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:19.021] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:76:0) after 1m0s
[19:31:19.019] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:76:0)
[19:31:19.019] Timer tick, asking Buildbucket for the build status
[19:31:19.159] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:19.159] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:77:0) after 1m0s
[19:32:19.141] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:77:0)
[19:32:19.141] Timer tick, asking Buildbucket for the build status
[19:32:19.707] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:19.707] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:78:0) after 1m0s
[19:33:19.775] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:78:0)
[19:33:19.775] Timer tick, asking Buildbucket for the build status
[19:33:19.929] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:19.929] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:79:0) after 1m0s
[19:34:19.917] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:79:0)
[19:34:19.917] Timer tick, asking Buildbucket for the build status
[19:34:20.458] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:20.458] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:80:0) after 1m0s
[19:35:20.499] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:80:0)
[19:35:20.499] Timer tick, asking Buildbucket for the build status
[19:35:20.671] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:20.671] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:81:0) after 1m0s
[19:36:20.696] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:81:0)
[19:36:20.696] Timer tick, asking Buildbucket for the build status
[19:36:20.903] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:20.903] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:82:0) after 1m0s
[19:37:20.900] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:82:0)
[19:37:20.900] Timer tick, asking Buildbucket for the build status
[19:37:21.303] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:21.303] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:83:0) after 1m0s
[19:38:21.325] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:83:0)
[19:38:21.325] Timer tick, asking Buildbucket for the build status
[19:38:21.803] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:21.803] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:84:0) after 1m0s
[19:39:21.810] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:84:0)
[19:39:21.810] Timer tick, asking Buildbucket for the build status
[19:39:22.126] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:22.126] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:85:0) after 1m0s
[19:40:22.141] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:85:0)
[19:40:22.141] Timer tick, asking Buildbucket for the build status
[19:40:22.546] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:22.546] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:86:0) after 1m0s
[19:41:22.560] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:86:0)
[19:41:22.560] Timer tick, asking Buildbucket for the build status
[19:41:22.915] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:22.915] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:87:0) after 1m0s
[19:42:22.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:87:0)
[19:42:22.922] Timer tick, asking Buildbucket for the build status
[19:42:23.095] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:23.095] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:88:0) after 1m0s
[19:43:23.078] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:88:0)
[19:43:23.078] Timer tick, asking Buildbucket for the build status
[19:43:24.083] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:24.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:89:0) after 1m0s
[19:44:24.107] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:89:0)
[19:44:24.107] Timer tick, asking Buildbucket for the build status
[19:44:24.700] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:24.700] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:90:0) after 1m0s
[19:45:24.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:90:0)
[19:45:24.726] Timer tick, asking Buildbucket for the build status
[19:45:24.848] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:24.848] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:91:0) after 1m0s
[19:46:24.827] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:91:0)
[19:46:24.827] Timer tick, asking Buildbucket for the build status
[19:46:24.939] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:24.939] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:92:0) after 1m0s
[19:47:24.919] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:92:0)
[19:47:24.919] Timer tick, asking Buildbucket for the build status
[19:47:25.699] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:25.699] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:93:0) after 1m0s
[19:48:25.727] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:93:0)
[19:48:25.727] Timer tick, asking Buildbucket for the build status
[19:48:25.892] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:25.892] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:94:0) after 1m0s
[19:49:25.967] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:94:0)
[19:49:25.967] Timer tick, asking Buildbucket for the build status
[19:49:26.882] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:26.882] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:95:0) after 1m0s
[19:50:26.872] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:95:0)
[19:50:26.872] Timer tick, asking Buildbucket for the build status
[19:50:27.503] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:27.503] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:96:0) after 1m0s
[19:51:27.525] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:96:0)
[19:51:27.525] Timer tick, asking Buildbucket for the build status
[19:51:27.895] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:27.895] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:97:0) after 1m0s
[19:52:27.917] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:97:0)
[19:52:27.917] Timer tick, asking Buildbucket for the build status
[19:52:28.069] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:28.069] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:98:0) after 1m0s
[19:53:28.094] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:98:0)
[19:53:28.094] Timer tick, asking Buildbucket for the build status
[19:53:28.267] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:28.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:99:0) after 1m0s
[19:54:28.263] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:99:0)
[19:54:28.263] Timer tick, asking Buildbucket for the build status
[19:54:28.718] Build 8917266481667526160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:28.718] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9082696543683040976:100:0) after 1m0s
[19:55:11.255] Received PubSub notification, asking Buildbucket for the build status
[19:55:11.373] Build 8917266481667526160: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[19:55:11.373] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1554234909879329",
"created_by": "project:chromium",
"created_ts": "1554229006091702",
"failure_reason": "BUILD_FAILURE",
"id": "8917266481667526160",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"b7bd4ca415d67340d082de31be87f162ed6070a6\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"3 tests failed: ['browser_tests', 'non_network_service_browser_tests', 'non_network_service_interactive_ui_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\": 11660, \"got_angle_revision\": \"7a6e421e16c6110e0892fe1d4bdf11fe9f43e0e5\", \"got_dawn_revision\": \"20b0c33913e45cd3d553b42a43b75b27af817833\", \"got_nacl_revision\": \"de8da4841bc3d1ed020d798d5a7fd6fb3d817fa1\", \"got_revision\": \"b7bd4ca415d67340d082de31be87f162ed6070a6\", \"got_revision_cp\": \"refs/heads/master@{#646877}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"84f2a33af26543d966c69574fd3c376a433537f9\", \"got_v8_revision_cp\": \"refs/heads/7.5.163@{#1}\", \"got_webrtc_revision\": \"cf2df2fb977f822be2ef406f9c52a09c5ebdb561\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27415}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.chromiumos\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"b7bd4ca415d67340d082de31be87f162ed6070a6\", \"swarm_hashes\": {\"accessibility_unittests\": \"9a134839d682e99ea82dc71fd32a79751486e680\", \"angle_unittests\": \"e7f785c896d690b00f1466b669be08445114452d\", \"app_list_unittests\": \"321f65a529861ebffecce2732c76218800addbd4\", \"app_shell_unittests\": \"096e0cd70ccf67a4298c0a1a7f09aadf48121c37\", \"ash_unittests\": \"49993e4f1374c45091e3a67e1d71a4c59c97f425\", \"aura_unittests\": \"e0e2807db276a975ad80b3d8d336556ddd27490c\", \"base_unittests\": \"1999e6cecbcf2cc81caac996c99268edadd24cce\", \"blink_common_unittests\": \"8828adf053fcb613df66df5f73160c92907e9e47\", \"blink_fuzzer_unittests\": \"7ef61f90dd90545756d42ba101d43d100f8216c9\", \"blink_heap_unittests\": \"1dc5ede9b93e3f7cc4a05b266f3333ea71367174\", \"blink_platform_unittests\": \"a4d4e682b44e091e53cd645dea3b95049a5220c3\", \"blink_unittests\": \"e96e16fddc1bfbbb267d7972fe45441934e899c4\", \"boringssl_crypto_tests\": \"2c052c346f3923dbeaab45cbe5d05840ee83a45a\", \"boringssl_ssl_tests\": \"cd1be47da075079e4a253a4a9490202996019d1d\", \"browser_tests\": \"599dce9ad15ec60c41e49c905d2177e6b5c2e704\", \"cacheinvalidation_unittests\": \"7bb8e65e65a16545241294be04464a0d4e1a4137\", \"capture_unittests\": \"7a3fe2808cf1372b80ee21fe30a555230f40a8ff\", \"cast_unittests\": \"9825535bf66381d76261aaa5bf140bc005ade198\", \"cc_unittests\": \"4e5dc3370ddc9021be1add19aa49f829b97ad594\", \"chrome_app_unittests\": \"90071afd517ff927686f0c0d05be47292db7e1aa\", \"chromedriver_unittests\": \"06ca309052e3d0583323538fee875cd7c7bcc3f2\", \"chromeos_components_unittests\": \"92d988bc929a07cc1fb6be9135f974c7d82041ed\", \"chromeos_unittests\": \"0a0b07b402fcdd31ea7c3aaa919a6466519b22ba\", \"components_browsertests\": \"72ad684b0c28698944807ed5486762df9a7469b6\", \"components_unittests\": \"ac8981372b84c2700cd513ae5066c29b688e3a46\", \"compositor_unittests\": \"42576ef4258a4887c135fa063e1048ad1ee71c1d\", \"content_browsertests\": \"0663767a96facddea68fbe911c8879a93c841bdd\", \"content_unittests\": \"baa30b616f3134ccb663109b9420b3c41db5460d\", \"crypto_unittests\": \"53b7c072a9515f99494fdac103404a54a7f7d19c\", \"dbus_unittests\": \"70545a33235f9b9a876e491bcdcdd464da3dc1a3\", \"device_unittests\": \"0399e30e1fc9cf208d436dc75e664ffc55fb46dc\", \"display_unittests\": \"2b34ac9b03bfc081b5ba8fa68e274ffb1452d8ed\", \"events_unittests\": \"341db3d7831ff027f87189bd92bbfe67f1f7788f\", \"exo_unittests\": \"efc05ab4c399f521dc3c539235f53bd81e0c8f2b\", \"extensions_browsertests\": \"4c253aa8930788e7b0890a450185552137f39d39\", \"extensions_unittests\": \"8513827f328adae46598a79f41aaede721a299f3\", \"filesystem_service_unittests\": \"88e910718f7bb0af9c6ca06974abd9be00b028cf\", \"gcm_unit_tests\": \"be905253b613d4727956e8eba14071966bf198f1\", \"gfx_unittests\": \"59fb64038c8ec6cc69703c6dd293eb2c70876bf2\", \"gin_unittests\": \"09720f88e61e34033a6c12d1834956124956fd94\", \"gl_unittests_ozone\": \"4e35e54046603290661c40acb1cce7378c48d016\", \"google_apis_unittests\": \"e4fc271aa340c992b0977c5a3dfa6a24a1607d68\", \"gpu_unittests\": \"1f5738d321d4e16c4941261df95f41984f8098b0\", \"interactive_ui_tests\": \"e03dbf5e0aa59a2d28e53b078b6088c9ad1cdba9\", \"ipc_tests\": \"91b5a9f53fd7f53d34a1eadf49430d9e40bed8bf\", \"jingle_unittests\": \"0fb0783529c27837371fec8801d44997a12870bb\", \"keyboard_unittests\": \"09c8bde382552517e38ba9ff5ebcdc28f2464129\", \"latency_unittests\": \"ef20c0c5c5606d7aa9fd19347d83a81728a64e70\", \"leveldb_service_unittests\": \"2c80ffe58d1996953bfd61f9f7c254c8405c70f8\", \"libjingle_xmpp_unittests\": \"3bf8e59ce05d8c866eaa9b89162779f308d9c48b\", \"media_blink_unittests\": \"53ca314ca278f31de47b2108981cd15a42751e0d\", \"media_service_unittests\": \"523cd27fe8ff2d032068aaf4824eab210e00303b\", \"media_unittests\": \"012681271b86db9f25f29b402eb1eaf91e0a61e2\", \"message_center_unittests\": \"fbb6ac7eec50a65a157c526d0d4e51a4ccb84b84\", \"midi_unittests\": \"d586f8db41c28781b522f67bb753dda864cb85da\", \"mojo_core_unittests\": \"9700579828078441125c4683b8a17c20abfc676b\", \"mojo_unittests\": \"1ecc83213cb2a59d5d5f5b6f331a06daf09d47f7\", \"nacl_helper_nonsfi_unittests\": \"edf2bd09c9292752a745c221248a2dfb3ce61c6c\", \"nacl_loader_unittests\": \"da3f072ea592d0c4c0922490da36a2fd1bce1906\", \"native_theme_unittests\": \"de7d858182eda04ff03b66fc55849bbcd72172d2\", \"net_unittests\": \"a0c533b8c05d8d0afc84f7b55ceb8842b21c10dd\", \"ozone_gl_unittests\": \"e36413e06c21ac6e77685b08c9da4ff0893a3aac\", \"ozone_unittests\": \"2f6951c4f6a355e0e879fa1b171f63446122e51c\", \"ozone_x11_unittests\": \"81cc5638e0eade2f468290d1663d30e56a0ab371\", \"pdf_unittests\": \"5212940eacdfe792c5f9001ffb71a4a4f2914da2\", \"perfetto_unittests\": \"34eb24148685099a8bdae9505742b98b88062fa6\", \"ppapi_unittests\": \"c9c59469df5690d43a12455b0d3284ed1986b7cb\", \"printing_unittests\": \"5e813cd3b288ff0de2b06a494e8a3b157416008c\", \"remoting_unittests\": \"1b368d3bb2936fe679cc7fa47b361e86cc0a7184\", \"sandbox_linux_unittests\": \"099efaedb5734cdfd7582ce529ee7d42cebe4f46\", \"service_manager_unittests\": \"0f2248de3c935fed24e887462e8c42ced19cac6b\", \"services_unittests\": \"49274c5f2a188b24dee0e7200c90b3742ba9290b\", \"shell_dialogs_unittests\": \"1e0661088d0863e27c864da1127d9624035b9ab8\", \"skia_unittests\": \"0acad9dce381710e36f9a9414bc4c4ed93629bb8\", \"snapshot_unittests\": \"33500e996afc65f6fe5abf12aaee631bf06a9ddb\", \"sql_unittests\": \"b7a2a63fe85fd452cfbf7dde9801104b8d9249ca\", \"storage_unittests\": \"5d15d702d3ec996b5c6d9d1f9673a3d35bca678b\", \"sync_integration_tests\": \"357ec57bd8d498a55d7968317052d7f38671c28c\", \"traffic_annotation_auditor_unittests\": \"678c95d61d4b7cfea83875993d5a0773a150334d\", \"ui_base_unittests\": \"1473492d9841db2442312ec98eabc8618c403551\", \"ui_chromeos_unittests\": \"dc8b348120f3e8cc9dc9564cae6dc3015d141696\", \"ui_touch_selection_unittests\": \"b8c069e63bab6e485aba74f12ac5ccf24fe4a3b1\", \"unit_tests\": \"b83a7aa1ac5248525ad77dc364481fa5a7e5fb8b\", \"url_unittests\": \"386de363f63ba1f9f0a72972da7b5819fb75ec00\", \"usage_time_limit_unittests\": \"932743f79066f755cbb6ee294681971ae06ceb69\", \"views_unittests\": \"abacbfdc5b3e4021adbe9abe11734f526aaabc7a\", \"viz_unittests\": \"077c69cc85359b0c8cb642388dc12a25c98c3dd2\", \"wm_unittests\": \"53cef155565ae1377e0b7daf5fe5821c3d0d8dfc\", \"wtf_unittests\": \"ae7ca3c3723f72dbbc18cd33d9f8713dc614411d\"}, \"swarm_hashes_refs/heads/master(at){#646877}_without_patch\": {\"accessibility_unittests\": \"9a134839d682e99ea82dc71fd32a79751486e680\", \"angle_unittests\": \"e7f785c896d690b00f1466b669be08445114452d\", \"app_list_unittests\": \"321f65a529861ebffecce2732c76218800addbd4\", \"app_shell_unittests\": \"096e0cd70ccf67a4298c0a1a7f09aadf48121c37\", \"ash_unittests\": \"49993e4f1374c45091e3a67e1d71a4c59c97f425\", \"aura_unittests\": \"e0e2807db276a975ad80b3d8d336556ddd27490c\", \"base_unittests\": \"1999e6cecbcf2cc81caac996c99268edadd24cce\", \"blink_common_unittests\": \"8828adf053fcb613df66df5f73160c92907e9e47\", \"blink_fuzzer_unittests\": \"7ef61f90dd90545756d42ba101d43d100f8216c9\", \"blink_heap_unittests\": \"1dc5ede9b93e3f7cc4a05b266f3333ea71367174\", \"blink_platform_unittests\": \"a4d4e682b44e091e53cd645dea3b95049a5220c3\", \"blink_unittests\": \"e96e16fddc1bfbbb267d7972fe45441934e899c4\", \"boringssl_crypto_tests\": \"2c052c346f3923dbeaab45cbe5d05840ee83a45a\", \"boringssl_ssl_tests\": \"cd1be47da075079e4a253a4a9490202996019d1d\", \"browser_tests\": \"599dce9ad15ec60c41e49c905d2177e6b5c2e704\", \"cacheinvalidation_unittests\": \"7bb8e65e65a16545241294be04464a0d4e1a4137\", \"capture_unittests\": \"7a3fe2808cf1372b80ee21fe30a555230f40a8ff\", \"cast_unittests\": \"9825535bf66381d76261aaa5bf140bc005ade198\", \"cc_unittests\": \"4e5dc3370ddc9021be1add19aa49f829b97ad594\", \"chrome_app_unittests\": \"90071afd517ff927686f0c0d05be47292db7e1aa\", \"chromedriver_unittests\": \"06ca309052e3d0583323538fee875cd7c7bcc3f2\", \"chromeos_components_unittests\": \"92d988bc929a07cc1fb6be9135f974c7d82041ed\", \"chromeos_unittests\": \"0a0b07b402fcdd31ea7c3aaa919a6466519b22ba\", \"components_browsertests\": \"72ad684b0c28698944807ed5486762df9a7469b6\", \"components_unittests\": \"ac8981372b84c2700cd513ae5066c29b688e3a46\", \"compositor_unittests\": \"42576ef4258a4887c135fa063e1048ad1ee71c1d\", \"content_browsertests\": \"0663767a96facddea68fbe911c8879a93c841bdd\", \"content_unittests\": \"baa30b616f3134ccb663109b9420b3c41db5460d\", \"crypto_unittests\": \"53b7c072a9515f99494fdac103404a54a7f7d19c\", \"dbus_unittests\": \"70545a33235f9b9a876e491bcdcdd464da3dc1a3\", \"device_unittests\": \"0399e30e1fc9cf208d436dc75e664ffc55fb46dc\", \"display_unittests\": \"2b34ac9b03bfc081b5ba8fa68e274ffb1452d8ed\", \"events_unittests\": \"341db3d7831ff027f87189bd92bbfe67f1f7788f\", \"exo_unittests\": \"efc05ab4c399f521dc3c539235f53bd81e0c8f2b\", \"extensions_browsertests\": \"4c253aa8930788e7b0890a450185552137f39d39\", \"extensions_unittests\": \"8513827f328adae46598a79f41aaede721a299f3\", \"filesystem_service_unittests\": \"88e910718f7bb0af9c6ca06974abd9be00b028cf\", \"gcm_unit_tests\": \"be905253b613d4727956e8eba14071966bf198f1\", \"gfx_unittests\": \"59fb64038c8ec6cc69703c6dd293eb2c70876bf2\", \"gin_unittests\": \"09720f88e61e34033a6c12d1834956124956fd94\", \"gl_unittests_ozone\": \"4e35e54046603290661c40acb1cce7378c48d016\", \"google_apis_unittests\": \"e4fc271aa340c992b0977c5a3dfa6a24a1607d68\", \"gpu_unittests\": \"1f5738d321d4e16c4941261df95f41984f8098b0\", \"interactive_ui_tests\": \"e03dbf5e0aa59a2d28e53b078b6088c9ad1cdba9\", \"ipc_tests\": \"91b5a9f53fd7f53d34a1eadf49430d9e40bed8bf\", \"jingle_unittests\": \"0fb0783529c27837371fec8801d44997a12870bb\", \"keyboard_unittests\": \"09c8bde382552517e38ba9ff5ebcdc28f2464129\", \"latency_unittests\": \"ef20c0c5c5606d7aa9fd19347d83a81728a64e70\", \"leveldb_service_unittests\": \"2c80ffe58d1996953bfd61f9f7c254c8405c70f8\", \"libjingle_xmpp_unittests\": \"3bf8e59ce05d8c866eaa9b89162779f308d9c48b\", \"media_blink_unittests\": \"53ca314ca278f31de47b2108981cd15a42751e0d\", \"media_service_unittests\": \"523cd27fe8ff2d032068aaf4824eab210e00303b\", \"media_unittests\": \"012681271b86db9f25f29b402eb1eaf91e0a61e2\", \"message_center_unittests\": \"fbb6ac7eec50a65a157c526d0d4e51a4ccb84b84\", \"midi_unittests\": \"d586f8db41c28781b522f67bb753dda864cb85da\", \"mojo_core_unittests\": \"9700579828078441125c4683b8a17c20abfc676b\", \"mojo_unittests\": \"1ecc83213cb2a59d5d5f5b6f331a06daf09d47f7\", \"nacl_helper_nonsfi_unittests\": \"edf2bd09c9292752a745c221248a2dfb3ce61c6c\", \"nacl_loader_unittests\": \"da3f072ea592d0c4c0922490da36a2fd1bce1906\", \"native_theme_unittests\": \"de7d858182eda04ff03b66fc55849bbcd72172d2\", \"net_unittests\": \"a0c533b8c05d8d0afc84f7b55ceb8842b21c10dd\", \"ozone_gl_unittests\": \"e36413e06c21ac6e77685b08c9da4ff0893a3aac\", \"ozone_unittests\": \"2f6951c4f6a355e0e879fa1b171f63446122e51c\", \"ozone_x11_unittests\": \"81cc5638e0eade2f468290d1663d30e56a0ab371\", \"pdf_unittests\": \"5212940eacdfe792c5f9001ffb71a4a4f2914da2\", \"perfetto_unittests\": \"34eb24148685099a8bdae9505742b98b88062fa6\", \"ppapi_unittests\": \"c9c59469df5690d43a12455b0d3284ed1986b7cb\", \"printing_unittests\": \"5e813cd3b288ff0de2b06a494e8a3b157416008c\", \"remoting_unittests\": \"1b368d3bb2936fe679cc7fa47b361e86cc0a7184\", \"sandbox_linux_unittests\": \"099efaedb5734cdfd7582ce529ee7d42cebe4f46\", \"service_manager_unittests\": \"0f2248de3c935fed24e887462e8c42ced19cac6b\", \"services_unittests\": \"49274c5f2a188b24dee0e7200c90b3742ba9290b\", \"shell_dialogs_unittests\": \"1e0661088d0863e27c864da1127d9624035b9ab8\", \"skia_unittests\": \"0acad9dce381710e36f9a9414bc4c4ed93629bb8\", \"snapshot_unittests\": \"33500e996afc65f6fe5abf12aaee631bf06a9ddb\", \"sql_unittests\": \"b7a2a63fe85fd452cfbf7dde9801104b8d9249ca\", \"storage_unittests\": \"5d15d702d3ec996b5c6d9d1f9673a3d35bca678b\", \"sync_integration_tests\": \"357ec57bd8d498a55d7968317052d7f38671c28c\", \"traffic_annotation_auditor_unittests\": \"678c95d61d4b7cfea83875993d5a0773a150334d\", \"ui_base_unittests\": \"1473492d9841db2442312ec98eabc8618c403551\", \"ui_chromeos_unittests\": \"dc8b348120f3e8cc9dc9564cae6dc3015d141696\", \"ui_touch_selection_unittests\": \"b8c069e63bab6e485aba74f12ac5ccf24fe4a3b1\", \"unit_tests\": \"b83a7aa1ac5248525ad77dc364481fa5a7e5fb8b\", \"url_unittests\": \"386de363f63ba1f9f0a72972da7b5819fb75ec00\", \"usage_time_limit_unittests\": \"932743f79066f755cbb6ee294681971ae06ceb69\", \"views_unittests\": \"abacbfdc5b3e4021adbe9abe11734f526aaabc7a\", \"viz_unittests\": \"077c69cc85359b0c8cb642388dc12a25c98c3dd2\", \"wm_unittests\": \"53cef155565ae1377e0b7daf5fe5821c3d0d8dfc\", \"wtf_unittests\": \"ae7ca3c3723f72dbbc18cd33d9f8713dc614411d\"}}, \"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\": [\"4234-52014c4\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"3 tests failed: ['browser_tests', 'non_network_service_browser_tests', 'non_network_service_interactive_ui_tests']\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1554229310360830",
"status": "COMPLETED",
"status_changed_ts": "1554229310626839",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11660",
"builder:linux-chromeos-dbg",
"buildset:commit/git/b7bd4ca415d67340d082de31be87f162ed6070a6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b7bd4ca415d67340d082de31be87f162ed6070a6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082696543683040976",
"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/8917266481667526160/+/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:43f81619a6ef5310",
"user_agent:luci-scheduler"
],
"updated_ts": "1554234910312103",
"url": "https://ci.chromium.org/b/8917266481667526160",
"utcnow_ts": "1554234911358934"
}
}
[19:55:11.373] Invocation finished in 1h38m26.588045705s with status FAILED