[15:57:34.204] New invocation is queued and will start shortly
[15:57:35.291] Starting the invocation (attempt 1)
[15:57:35.395] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:57:35.395] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:57:35.395] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9079987390927581104",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"9a18951eadae5991f8c09c8fb71d233888e18505\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9079987390927581104",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/9a18951eadae5991f8c09c8fb71d233888e18505",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9a18951eadae5991f8c09c8fb71d233888e18505",
"gitiles_ref:refs/heads/master"
]
}
[15:57:36.696] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1556812655689306",
"id": "8914557328706867728",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"9a18951eadae5991f8c09c8fb71d233888e18505\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1556812656380202",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/12175",
"builder:linux-chromeos-dbg",
"buildset:commit/git/9a18951eadae5991f8c09c8fb71d233888e18505",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9a18951eadae5991f8c09c8fb71d233888e18505",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079987390927581104",
"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/8914557328706867728/+/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": "1556812656380327",
"url": "https://ci.chromium.org/b/8914557328706867728",
"utcnow_ts": "1556812656688252"
}
}
[15:57:36.696] Task URL: https://ci.chromium.org/b/8914557328706867728
[15:57:36.696] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:2:0) after 1m0s
[15:57:39.202] Received PubSub notification, asking Buildbucket for the build status
[15:57:39.304] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:36.754] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:2:0)
[15:58:36.754] Timer tick, asking Buildbucket for the build status
[15:58:37.226] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:37.226] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:4:0) after 1m0s
[15:59:37.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:4:0)
[15:59:37.233] Timer tick, asking Buildbucket for the build status
[15:59:37.851] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:37.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:5:0) after 1m0s
[16:00:37.828] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:5:0)
[16:00:37.828] Timer tick, asking Buildbucket for the build status
[16:00:37.967] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:37.967] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:6:0) after 1m0s
[16:01:37.972] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:6:0)
[16:01:37.972] Timer tick, asking Buildbucket for the build status
[16:01:38.347] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:38.347] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:7:0) after 1m0s
[16:02:38.366] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:7:0)
[16:02:38.366] Timer tick, asking Buildbucket for the build status
[16:02:38.710] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:38.710] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:8:0) after 1m0s
[16:03:38.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:8:0)
[16:03:38.726] Timer tick, asking Buildbucket for the build status
[16:03:39.315] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:39.315] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:9:0) after 1m0s
[16:04:39.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:9:0)
[16:04:39.339] Timer tick, asking Buildbucket for the build status
[16:04:40.390] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:40.390] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:10:0) after 1m0s
[16:05:40.387] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:10:0)
[16:05:40.387] Timer tick, asking Buildbucket for the build status
[16:05:40.540] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:40.541] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:11:0) after 1m0s
[16:06:40.515] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:11:0)
[16:06:40.515] Timer tick, asking Buildbucket for the build status
[16:06:41.600] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:41.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:12:0) after 1m0s
[16:07:41.606] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:12:0)
[16:07:41.606] Timer tick, asking Buildbucket for the build status
[16:07:41.792] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:41.792] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:13:0) after 1m0s
[16:08:41.764] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:13:0)
[16:08:41.764] Timer tick, asking Buildbucket for the build status
[16:08:42.019] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:42.019] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:14:0) after 1m0s
[16:09:42.013] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:14:0)
[16:09:42.013] Timer tick, asking Buildbucket for the build status
[16:09:42.743] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:42.743] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:15:0) after 1m0s
[16:10:42.773] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:15:0)
[16:10:42.773] Timer tick, asking Buildbucket for the build status
[16:10:43.408] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:43.408] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:16:0) after 1m0s
[16:11:43.443] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:16:0)
[16:11:43.443] Timer tick, asking Buildbucket for the build status
[16:11:44.081] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:44.081] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:17:0) after 1m0s
[16:12:44.247] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:17:0)
[16:12:44.247] Timer tick, asking Buildbucket for the build status
[16:12:44.433] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:44.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:18:0) after 1m0s
[16:13:44.455] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:18:0)
[16:13:44.455] Timer tick, asking Buildbucket for the build status
[16:13:45.128] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:45.128] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:19:0) after 1m0s
[16:14:45.143] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:19:0)
[16:14:45.143] Timer tick, asking Buildbucket for the build status
[16:14:45.238] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:45.238] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:20:0) after 1m0s
[16:15:45.257] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:20:0)
[16:15:45.257] Timer tick, asking Buildbucket for the build status
[16:15:45.574] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:45.574] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:21:0) after 1m0s
[16:16:45.597] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:21:0)
[16:16:45.597] Timer tick, asking Buildbucket for the build status
[16:16:45.911] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:45.911] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:22:0) after 1m0s
[16:17:45.955] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:22:0)
[16:17:45.955] Timer tick, asking Buildbucket for the build status
[16:17:46.285] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:46.285] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:23:0) after 1m0s
[16:18:46.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:23:0)
[16:18:46.311] Timer tick, asking Buildbucket for the build status
[16:18:46.869] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:46.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:24:0) after 1m0s
[16:19:46.892] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:24:0)
[16:19:46.892] Timer tick, asking Buildbucket for the build status
[16:19:47.359] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:47.359] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:25:0) after 1m0s
[16:20:47.396] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:25:0)
[16:20:47.396] Timer tick, asking Buildbucket for the build status
[16:20:47.590] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:47.590] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:26:0) after 1m0s
[16:21:47.572] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:26:0)
[16:21:47.572] Timer tick, asking Buildbucket for the build status
[16:21:47.968] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:47.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:27:0) after 1m0s
[16:22:47.957] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:27:0)
[16:22:47.957] Timer tick, asking Buildbucket for the build status
[16:22:48.372] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:48.372] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:28:0) after 1m0s
[16:23:48.396] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:28:0)
[16:23:48.396] Timer tick, asking Buildbucket for the build status
[16:23:49.086] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:49.086] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:29:0) after 1m0s
[16:24:49.112] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:29:0)
[16:24:49.112] Timer tick, asking Buildbucket for the build status
[16:24:49.209] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:49.209] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:30:0) after 1m0s
[16:25:49.231] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:30:0)
[16:25:49.231] Timer tick, asking Buildbucket for the build status
[16:25:49.572] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:49.572] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:31:0) after 1m0s
[16:26:49.594] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:31:0)
[16:26:49.594] Timer tick, asking Buildbucket for the build status
[16:26:50.625] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:50.625] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:32:0) after 1m0s
[16:27:50.636] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:32:0)
[16:27:50.636] Timer tick, asking Buildbucket for the build status
[16:27:51.199] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:51.199] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:33:0) after 1m0s
[16:28:51.229] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:33:0)
[16:28:51.229] Timer tick, asking Buildbucket for the build status
[16:28:51.710] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:51.710] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:34:0) after 1m0s
[16:29:51.718] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:34:0)
[16:29:51.718] Timer tick, asking Buildbucket for the build status
[16:29:51.956] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:51.956] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:35:0) after 1m0s
[16:30:51.976] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:35:0)
[16:30:51.976] Timer tick, asking Buildbucket for the build status
[16:30:52.417] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:52.417] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:36:0) after 1m0s
[16:31:52.453] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:36:0)
[16:31:52.453] Timer tick, asking Buildbucket for the build status
[16:31:53.386] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:53.386] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:37:0) after 1m0s
[16:32:53.404] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:37:0)
[16:32:53.404] Timer tick, asking Buildbucket for the build status
[16:32:53.654] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:53.654] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:38:0) after 1m0s
[16:33:53.644] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:38:0)
[16:33:53.644] Timer tick, asking Buildbucket for the build status
[16:33:53.946] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:53.946] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:39:0) after 1m0s
[16:34:53.966] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:39:0)
[16:34:53.966] Timer tick, asking Buildbucket for the build status
[16:34:54.071] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:54.071] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:40:0) after 1m0s
[16:35:54.099] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:40:0)
[16:35:54.099] Timer tick, asking Buildbucket for the build status
[16:35:54.524] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:54.524] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:41:0) after 1m0s
[16:36:54.553] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:41:0)
[16:36:54.553] Timer tick, asking Buildbucket for the build status
[16:36:54.856] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:54.857] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:42:0) after 1m0s
[16:37:54.876] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:42:0)
[16:37:54.876] Timer tick, asking Buildbucket for the build status
[16:37:56.203] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:56.203] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:43:0) after 1m0s
[16:38:56.222] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:43:0)
[16:38:56.222] Timer tick, asking Buildbucket for the build status
[16:38:57.455] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:57.455] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:44:0) after 1m0s
[16:39:57.453] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:44:0)
[16:39:57.453] Timer tick, asking Buildbucket for the build status
[16:39:58.597] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:58.597] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:45:0) after 1m0s
[16:40:58.617] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:45:0)
[16:40:58.617] Timer tick, asking Buildbucket for the build status
[16:40:59.109] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:59.109] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:46:0) after 1m0s
[16:41:59.129] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:46:0)
[16:41:59.129] Timer tick, asking Buildbucket for the build status
[16:41:59.581] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:59.581] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:47:0) after 1m0s
[16:42:59.609] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:47:0)
[16:42:59.609] Timer tick, asking Buildbucket for the build status
[16:43:00.565] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:00.565] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:48:0) after 1m0s
[16:44:00.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:48:0)
[16:44:00.550] Timer tick, asking Buildbucket for the build status
[16:44:01.295] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:01.295] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:49:0) after 1m0s
[16:45:01.338] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:49:0)
[16:45:01.338] Timer tick, asking Buildbucket for the build status
[16:45:03.298] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:03.298] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:50:0) after 1m0s
[16:46:03.328] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:50:0)
[16:46:03.328] Timer tick, asking Buildbucket for the build status
[16:46:03.848] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:03.848] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:51:0) after 1m0s
[16:47:03.872] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:51:0)
[16:47:03.872] Timer tick, asking Buildbucket for the build status
[16:47:04.508] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:04.508] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:52:0) after 1m0s
[16:48:04.540] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:52:0)
[16:48:04.540] Timer tick, asking Buildbucket for the build status
[16:48:07.417] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:07.417] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:53:0) after 1m0s
[16:49:07.440] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:53:0)
[16:49:07.440] Timer tick, asking Buildbucket for the build status
[16:49:08.075] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:08.075] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:54:0) after 1m0s
[16:50:08.095] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:54:0)
[16:50:08.095] Timer tick, asking Buildbucket for the build status
[16:50:08.667] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:08.667] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:55:0) after 1m0s
[16:51:08.685] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:55:0)
[16:51:08.685] Timer tick, asking Buildbucket for the build status
[16:51:08.915] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:08.915] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:56:0) after 1m0s
[16:52:08.907] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:56:0)
[16:52:08.907] Timer tick, asking Buildbucket for the build status
[16:52:09.870] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:09.870] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:57:0) after 1m0s
[16:53:09.860] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:57:0)
[16:53:09.860] Timer tick, asking Buildbucket for the build status
[16:53:10.192] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:10.192] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:58:0) after 1m0s
[16:54:10.286] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:58:0)
[16:54:10.286] Timer tick, asking Buildbucket for the build status
[16:54:10.451] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:10.451] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:59:0) after 1m0s
[16:55:10.479] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:59:0)
[16:55:10.479] Timer tick, asking Buildbucket for the build status
[16:55:11.110] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:11.110] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:60:0) after 1m0s
[16:56:11.134] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:60:0)
[16:56:11.134] Timer tick, asking Buildbucket for the build status
[16:56:11.513] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:11.513] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:61:0) after 1m0s
[16:57:11.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:61:0)
[16:57:11.570] Timer tick, asking Buildbucket for the build status
[16:57:13.510] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:13.510] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:62:0) after 1m0s
[16:58:13.521] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:62:0)
[16:58:13.521] Timer tick, asking Buildbucket for the build status
[16:58:13.649] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:13.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:63:0) after 1m0s
[16:59:13.681] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:63:0)
[16:59:13.681] Timer tick, asking Buildbucket for the build status
[16:59:15.051] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:15.051] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:64:0) after 1m0s
[17:00:15.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:64:0)
[17:00:15.059] Timer tick, asking Buildbucket for the build status
[17:00:15.210] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:15.210] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:65:0) after 1m0s
[17:01:15.273] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:65:0)
[17:01:15.273] Timer tick, asking Buildbucket for the build status
[17:01:16.006] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:16.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:66:0) after 1m0s
[17:02:16.011] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:66:0)
[17:02:16.011] Timer tick, asking Buildbucket for the build status
[17:02:16.631] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:16.631] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:67:0) after 1m0s
[17:03:16.611] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:67:0)
[17:03:16.611] Timer tick, asking Buildbucket for the build status
[17:03:17.648] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:17.648] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:68:0) after 1m0s
[17:04:17.676] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:68:0)
[17:04:17.676] Timer tick, asking Buildbucket for the build status
[17:04:17.814] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:17.814] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:69:0) after 1m0s
[17:05:17.813] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:69:0)
[17:05:17.813] Timer tick, asking Buildbucket for the build status
[17:05:18.205] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:18.205] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:70:0) after 1m0s
[17:06:18.254] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:70:0)
[17:06:18.254] Timer tick, asking Buildbucket for the build status
[17:06:18.493] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:18.493] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:71:0) after 1m0s
[17:07:18.520] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:71:0)
[17:07:18.520] Timer tick, asking Buildbucket for the build status
[17:07:18.701] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:18.701] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:72:0) after 1m0s
[17:08:18.724] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:72:0)
[17:08:18.724] Timer tick, asking Buildbucket for the build status
[17:08:19.100] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:19.100] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:73:0) after 1m0s
[17:09:19.132] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:73:0)
[17:09:19.132] Timer tick, asking Buildbucket for the build status
[17:09:19.543] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:19.543] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:74:0) after 1m0s
[17:10:19.535] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:74:0)
[17:10:19.535] Timer tick, asking Buildbucket for the build status
[17:10:19.696] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:19.696] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:75:0) after 1m0s
[17:11:19.736] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:75:0)
[17:11:19.736] Timer tick, asking Buildbucket for the build status
[17:11:19.935] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:19.935] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:76:0) after 1m0s
[17:12:19.956] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:76:0)
[17:12:19.956] Timer tick, asking Buildbucket for the build status
[17:12:20.994] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:20.994] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:77:0) after 1m0s
[17:13:20.975] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:77:0)
[17:13:20.975] Timer tick, asking Buildbucket for the build status
[17:13:22.304] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:22.304] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:78:0) after 1m0s
[17:14:22.277] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:78:0)
[17:14:22.277] Timer tick, asking Buildbucket for the build status
[17:14:22.554] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:22.554] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:79:0) after 1m0s
[17:15:22.535] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:79:0)
[17:15:22.535] Timer tick, asking Buildbucket for the build status
[17:15:23.453] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:23.453] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:80:0) after 1m0s
[17:16:23.474] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:80:0)
[17:16:23.474] Timer tick, asking Buildbucket for the build status
[17:16:23.889] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:23.889] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:81:0) after 1m0s
[17:17:23.910] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:81:0)
[17:17:23.910] Timer tick, asking Buildbucket for the build status
[17:17:24.032] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:24.032] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:82:0) after 1m0s
[17:18:24.051] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:82:0)
[17:18:24.051] Timer tick, asking Buildbucket for the build status
[17:18:24.274] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:24.274] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:83:0) after 1m0s
[17:19:24.295] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:83:0)
[17:19:24.295] Timer tick, asking Buildbucket for the build status
[17:19:24.982] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:24.982] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:84:0) after 1m0s
[17:20:25.014] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:84:0)
[17:20:25.014] Timer tick, asking Buildbucket for the build status
[17:20:25.517] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:25.517] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:85:0) after 1m0s
[17:21:25.511] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:85:0)
[17:21:25.511] Timer tick, asking Buildbucket for the build status
[17:21:26.183] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:26.183] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:86:0) after 1m0s
[17:22:26.210] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:86:0)
[17:22:26.210] Timer tick, asking Buildbucket for the build status
[17:22:26.421] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:26.421] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:87:0) after 1m0s
[17:23:26.443] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:87:0)
[17:23:26.443] Timer tick, asking Buildbucket for the build status
[17:23:26.718] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:26.718] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:88:0) after 1m0s
[17:24:26.730] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:88:0)
[17:24:26.730] Timer tick, asking Buildbucket for the build status
[17:24:27.453] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:27.453] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:89:0) after 1m0s
[17:25:27.541] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:89:0)
[17:25:27.541] Timer tick, asking Buildbucket for the build status
[17:25:27.976] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:27.976] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:90:0) after 1m0s
[17:26:28.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:90:0)
[17:26:28.001] Timer tick, asking Buildbucket for the build status
[17:26:28.912] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:28.912] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:91:0) after 1m0s
[17:27:28.983] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:91:0)
[17:27:28.983] Timer tick, asking Buildbucket for the build status
[17:27:29.380] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:29.380] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:92:0) after 1m0s
[17:28:29.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:92:0)
[17:28:29.424] Timer tick, asking Buildbucket for the build status
[17:28:30.268] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:30.268] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:93:0) after 1m0s
[17:29:30.314] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:93:0)
[17:29:30.314] Timer tick, asking Buildbucket for the build status
[17:29:30.711] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:30.711] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:94:0) after 1m0s
[17:30:30.734] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:94:0)
[17:30:30.734] Timer tick, asking Buildbucket for the build status
[17:30:31.012] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:31.012] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:95:0) after 1m0s
[17:31:31.101] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:95:0)
[17:31:31.101] Timer tick, asking Buildbucket for the build status
[17:31:31.384] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:31.384] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:96:0) after 1m0s
[17:32:31.380] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:96:0)
[17:32:31.381] Timer tick, asking Buildbucket for the build status
[17:32:31.814] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:31.814] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:97:0) after 1m0s
[17:33:31.902] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:97:0)
[17:33:31.902] Timer tick, asking Buildbucket for the build status
[17:33:33.384] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:33.385] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:98:0) after 1m0s
[17:34:33.425] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:98:0)
[17:34:33.425] Timer tick, asking Buildbucket for the build status
[17:34:33.523] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:33.523] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:99:0) after 1m0s
[17:35:33.558] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:99:0)
[17:35:33.558] Timer tick, asking Buildbucket for the build status
[17:35:35.240] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:35.240] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:100:0) after 1m0s
[17:36:35.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:100:0)
[17:36:35.223] Timer tick, asking Buildbucket for the build status
[17:36:35.390] Build 8914557328706867728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:35.390] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9079987390927581104:101:0) after 1m0s
[17:36:59.624] Received PubSub notification, asking Buildbucket for the build status
[17:36:59.916] Build 8914557328706867728: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:36:59.916] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1556818618269417",
"created_by": "project:chromium",
"created_ts": "1556812655689306",
"id": "8914557328706867728",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"9a18951eadae5991f8c09c8fb71d233888e18505\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"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\": 12175, \"got_angle_revision\": \"565441b1078b6eabf6f3d1785c5c5de9fd5271a9\", \"got_dawn_revision\": \"00f6b1af41cab2dfa75a0c2759a3602307f2e9fd\", \"got_nacl_revision\": \"6abc006f6760ec49350cd45e8bccbff4809725ac\", \"got_revision\": \"9a18951eadae5991f8c09c8fb71d233888e18505\", \"got_revision_cp\": \"refs/heads/master@{#656030}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"ff36896da9edad2a41ba4c9f7b6bccf9b7d2ce39\", \"got_v8_revision_cp\": \"refs/heads/7.6.87@{#1}\", \"got_webrtc_revision\": \"4fb12b0caec9faa57cfbceb0f86b0e10c32a0cc2\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27828}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.chromiumos\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"9a18951eadae5991f8c09c8fb71d233888e18505\", \"swarm_hashes\": {\"accessibility_unittests\": \"4068aee8b1958029efba6097b141a18efd3526b0\", \"angle_unittests\": \"a5ea9ddb971fc46d4f4674df4da3b680228a1c51\", \"app_list_unittests\": \"f7af0d17df499908ce35c47dfcc3ac5531acdfc2\", \"app_shell_unittests\": \"f28eb1c139d95309a09421de4d3d5f88137db74c\", \"ash_unittests\": \"873ee9fefad7bafc0ec5c614fcb50b0cebaf44fa\", \"aura_unittests\": \"109d89839e05775f102caeb5b4e15d3827118b31\", \"base_unittests\": \"4f7d9ad8fc3ec749d8ccbd2ab59a1f71769187ef\", \"blink_common_unittests\": \"0c5a355367c3a4ee6bc8a2f9121d0eae6f84fb97\", \"blink_fuzzer_unittests\": \"81f4c810ee4ee8c8e13544b11e1625ad2b0d9a9a\", \"blink_heap_unittests\": \"d984cfa90cd40299ef138f8b12ee90950882a4e7\", \"blink_platform_unittests\": \"ddd840949151548fbe31bc1423132bf1bf1f8bbe\", \"blink_unittests\": \"e817262b65fcce8e77b0c22bc7574c9eb3a9a0c1\", \"boringssl_crypto_tests\": \"b529f61418973a4bee26b202476d0195df0b1643\", \"boringssl_ssl_tests\": \"066baa64cc55f86f94b843f7cf18c7434bfa2991\", \"browser_tests\": \"534833ecd4f2cfcdde7973a19c1e6d6517a4de3a\", \"cacheinvalidation_unittests\": \"c4e1cac3f3f27a95e302570e955c0039115b4d44\", \"capture_unittests\": \"8923e14770ec94464799d7cd0c5ec6be7639d9e7\", \"cast_unittests\": \"0a23bbae298fc836f52327a1c752cbb883123fb4\", \"cc_unittests\": \"25b426b52ba7195d37d0b3682511bc8b5a231e29\", \"chrome_app_unittests\": \"669291e6ba30b475077118c13bd084d3027c81e3\", \"chromedriver_unittests\": \"0bc3616baf74b83cba000bc59e6c68bf77da9e2b\", \"chromeos_components_unittests\": \"b9d6e7b3cbdeb07c892ac1f29f581a1d5a57724d\", \"chromeos_unittests\": \"ddd3d3e5adc6d8a3282a893cf6d649d1edc980a9\", \"components_browsertests\": \"acb3d598f6d549c8c39d386595cc990d96642176\", \"components_unittests\": \"e3602921c097c945e65d96c1587c0b48c1aba9af\", \"compositor_unittests\": \"ef94326064469e6ffe6fe67ad64e2cc8a4c909b5\", \"content_browsertests\": \"b7b743139b46e02f2dcfe14be288d3c7cacd92e9\", \"content_unittests\": \"768b82ff49f51818f58e16c7e8151bf47c87332a\", \"crypto_unittests\": \"c2fa7d01f6052aa7929f871414f0142026adcecd\", \"dbus_unittests\": \"f7c834c7fc914cc460ae33c3bd6ab4607b7355f8\", \"device_unittests\": \"b363c77b2dc7a829a5414890442f7b5d5f0d180f\", \"display_unittests\": \"85a96e723862ed8cf8a6c4f5cb99fe595eb84a63\", \"events_unittests\": \"18043116d016e2806efcf94b4f81a8f26a6538de\", \"exo_unittests\": \"cd7060079dfc2d8668302c3a6062fd98d49f529f\", \"extensions_browsertests\": \"b2010c5193ee85c4d6b9c090749abe20759528f3\", \"extensions_unittests\": \"ab17047408e4d1388e7a5a328675bb0c9402cf09\", \"filesystem_service_unittests\": \"7a997b57246bfa22f484fa6a539eb6d6e62d4e2f\", \"gcm_unit_tests\": \"e36bbef65aba3ec9a7df0f18566af93386daf28f\", \"gfx_unittests\": \"553bbe67a2d2a15eba72f4e98423fbc51658811b\", \"gin_unittests\": \"7adde6240513c1d7aaa96cfbb9ce47723f5d39c5\", \"gl_unittests_ozone\": \"b828b49bf23eee8d04901f5ef9c926464ecb5c6b\", \"google_apis_unittests\": \"5aeaa6d687f02c083e40a6400cce5b5756d2e75a\", \"gpu_unittests\": \"3dfeb10b4944f874d67cd22c51f3577df33b8562\", \"interactive_ui_tests\": \"482e58ff7a013919a2b3100b13d8efd3b96575ba\", \"ipc_tests\": \"e3164df4f5cee97ccd5519a84d84444b0e6cabd7\", \"jingle_unittests\": \"88db9d57983e8ff26ea157b521f8c50c8c7df3e0\", \"keyboard_unittests\": \"869851bfdfd5c498ce0bf9cc15d66422047a96fd\", \"latency_unittests\": \"4b2fff9850cd9f2781da1dcec96c31c4748d96e8\", \"leveldb_service_unittests\": \"bf0c238bdf4e0195924d0b7fdfb6690f7d9d131f\", \"libjingle_xmpp_unittests\": \"8153107f2b2aead93bd8b0d8aea10884055af120\", \"media_blink_unittests\": \"005dcfda527480e3067f9449798a9e4dfaa4843c\", \"media_service_unittests\": \"65e6b3872ed62e1d5d4b64b6bd6515abf4ed834b\", \"media_unittests\": \"378ae674bb780f14c7c8f09f97334ebb16445ae7\", \"message_center_unittests\": \"b0bb27fa6f8c19fb7b3a55b7ebf970ec46c6b4e2\", \"midi_unittests\": \"98188fcd2c10662b81f5ce9c6302383f66d1bf9a\", \"mojo_core_unittests\": \"1efea84f1d1bb5f16f7ebbbb1d1559847ce471d6\", \"mojo_unittests\": \"2a02206e3188d94ae42dafac7b05619d6d72da5b\", \"nacl_helper_nonsfi_unittests\": \"9eb5dcc606724e6913e0ce02a805fb33f913bf0e\", \"nacl_loader_unittests\": \"925f0198d40bbffb525c1589de4725879b0a0df9\", \"native_theme_unittests\": \"9eded1ed861b7c7c103ee0189e4df76aaf4c45b5\", \"net_unittests\": \"4b7f16d6da086a78eb563b3a342179bf91784559\", \"ozone_gl_unittests\": \"a03a07a2d13334983521a326396033ec8c6cc8a4\", \"ozone_unittests\": \"eb704e473205cef10e0aa435bbb2bb96c43e6da9\", \"ozone_x11_unittests\": \"cb04b04280f7a93d209aae84d175b32a71c72eb2\", \"pdf_unittests\": \"157ab556998997822f078e8d15a3121b147db6ef\", \"perfetto_unittests\": \"55a9c8b37a6532cfa9c184c4500d33aa284dbd87\", \"ppapi_unittests\": \"ea071e57397e9c1a566b130f4ac0d91cae541299\", \"printing_unittests\": \"ddcfa33996a7b5e4f0f7209d6b9ee7057c86752e\", \"remoting_unittests\": \"927205bb37a36749e1fc6df9727d2231a37c1872\", \"sandbox_linux_unittests\": \"a46b0551d262a9dcb8704c2aa07bb63a066d77fe\", \"service_manager_unittests\": \"7bef76c86e1bbaeaaffdcfe164eed755fd7301a8\", \"services_unittests\": \"8c6544d00e60b26d9aa65bc3271f19b2be529e6a\", \"shell_dialogs_unittests\": \"4222ba8f2924f3dfd47938e54bbb0bbccf5a20d9\", \"skia_unittests\": \"344faccdc32a6ddde41afccf8f7bbe651dc1259e\", \"snapshot_unittests\": \"f7ec2a46204430a59fecce2bb1916f49db1ff0ab\", \"sql_unittests\": \"f731abe5179d742e2c9042aeed8995bfd9b25e7c\", \"storage_unittests\": \"bc761e8f4158b1ba33a7f708f278b6b54da6a1e1\", \"sync_integration_tests\": \"243a2219d5da13123b199790009ea09f1fd7744f\", \"traffic_annotation_auditor_unittests\": \"575dd0836197012f1e8c6a3dc6617058021918ea\", \"ui_base_unittests\": \"b9f389eb7426124e1662c80817eb9a66bbd6ffc5\", \"ui_chromeos_unittests\": \"deb7ab1b89b9eab95d649fcbdf32204a1cbe107c\", \"ui_touch_selection_unittests\": \"cffbec2f97bd3479450b9188fedefe188d3808b5\", \"unit_tests\": \"50eb667942be96b587ac3cb140ac8814f4679504\", \"url_unittests\": \"3749c6dfb4760c75d192972f75f72639dde093b0\", \"usage_time_limit_unittests\": \"573f7f97b04bdf34c136b39c664c78cd6f71c962\", \"views_unittests\": \"737398c22283fe28039bcfa7228b7b7d70e5d1d0\", \"viz_unittests\": \"4379b3830cce7c9e86d736fdf3da6474f25d5c1a\", \"wm_unittests\": \"a7dc31ad3311ae93064e90acd71b2b479e262dc5\", \"wtf_unittests\": \"fe19f24636abf988500509be667d7a120c20a0f2\"}, \"swarm_hashes_refs/heads/master(at){#656030}_without_patch\": {\"accessibility_unittests\": \"4068aee8b1958029efba6097b141a18efd3526b0\", \"angle_unittests\": \"a5ea9ddb971fc46d4f4674df4da3b680228a1c51\", \"app_list_unittests\": \"f7af0d17df499908ce35c47dfcc3ac5531acdfc2\", \"app_shell_unittests\": \"f28eb1c139d95309a09421de4d3d5f88137db74c\", \"ash_unittests\": \"873ee9fefad7bafc0ec5c614fcb50b0cebaf44fa\", \"aura_unittests\": \"109d89839e05775f102caeb5b4e15d3827118b31\", \"base_unittests\": \"4f7d9ad8fc3ec749d8ccbd2ab59a1f71769187ef\", \"blink_common_unittests\": \"0c5a355367c3a4ee6bc8a2f9121d0eae6f84fb97\", \"blink_fuzzer_unittests\": \"81f4c810ee4ee8c8e13544b11e1625ad2b0d9a9a\", \"blink_heap_unittests\": \"d984cfa90cd40299ef138f8b12ee90950882a4e7\", \"blink_platform_unittests\": \"ddd840949151548fbe31bc1423132bf1bf1f8bbe\", \"blink_unittests\": \"e817262b65fcce8e77b0c22bc7574c9eb3a9a0c1\", \"boringssl_crypto_tests\": \"b529f61418973a4bee26b202476d0195df0b1643\", \"boringssl_ssl_tests\": \"066baa64cc55f86f94b843f7cf18c7434bfa2991\", \"browser_tests\": \"534833ecd4f2cfcdde7973a19c1e6d6517a4de3a\", \"cacheinvalidation_unittests\": \"c4e1cac3f3f27a95e302570e955c0039115b4d44\", \"capture_unittests\": \"8923e14770ec94464799d7cd0c5ec6be7639d9e7\", \"cast_unittests\": \"0a23bbae298fc836f52327a1c752cbb883123fb4\", \"cc_unittests\": \"25b426b52ba7195d37d0b3682511bc8b5a231e29\", \"chrome_app_unittests\": \"669291e6ba30b475077118c13bd084d3027c81e3\", \"chromedriver_unittests\": \"0bc3616baf74b83cba000bc59e6c68bf77da9e2b\", \"chromeos_components_unittests\": \"b9d6e7b3cbdeb07c892ac1f29f581a1d5a57724d\", \"chromeos_unittests\": \"ddd3d3e5adc6d8a3282a893cf6d649d1edc980a9\", \"components_browsertests\": \"acb3d598f6d549c8c39d386595cc990d96642176\", \"components_unittests\": \"e3602921c097c945e65d96c1587c0b48c1aba9af\", \"compositor_unittests\": \"ef94326064469e6ffe6fe67ad64e2cc8a4c909b5\", \"content_browsertests\": \"b7b743139b46e02f2dcfe14be288d3c7cacd92e9\", \"content_unittests\": \"768b82ff49f51818f58e16c7e8151bf47c87332a\", \"crypto_unittests\": \"c2fa7d01f6052aa7929f871414f0142026adcecd\", \"dbus_unittests\": \"f7c834c7fc914cc460ae33c3bd6ab4607b7355f8\", \"device_unittests\": \"b363c77b2dc7a829a5414890442f7b5d5f0d180f\", \"display_unittests\": \"85a96e723862ed8cf8a6c4f5cb99fe595eb84a63\", \"events_unittests\": \"18043116d016e2806efcf94b4f81a8f26a6538de\", \"exo_unittests\": \"cd7060079dfc2d8668302c3a6062fd98d49f529f\", \"extensions_browsertests\": \"b2010c5193ee85c4d6b9c090749abe20759528f3\", \"extensions_unittests\": \"ab17047408e4d1388e7a5a328675bb0c9402cf09\", \"filesystem_service_unittests\": \"7a997b57246bfa22f484fa6a539eb6d6e62d4e2f\", \"gcm_unit_tests\": \"e36bbef65aba3ec9a7df0f18566af93386daf28f\", \"gfx_unittests\": \"553bbe67a2d2a15eba72f4e98423fbc51658811b\", \"gin_unittests\": \"7adde6240513c1d7aaa96cfbb9ce47723f5d39c5\", \"gl_unittests_ozone\": \"b828b49bf23eee8d04901f5ef9c926464ecb5c6b\", \"google_apis_unittests\": \"5aeaa6d687f02c083e40a6400cce5b5756d2e75a\", \"gpu_unittests\": \"3dfeb10b4944f874d67cd22c51f3577df33b8562\", \"interactive_ui_tests\": \"482e58ff7a013919a2b3100b13d8efd3b96575ba\", \"ipc_tests\": \"e3164df4f5cee97ccd5519a84d84444b0e6cabd7\", \"jingle_unittests\": \"88db9d57983e8ff26ea157b521f8c50c8c7df3e0\", \"keyboard_unittests\": \"869851bfdfd5c498ce0bf9cc15d66422047a96fd\", \"latency_unittests\": \"4b2fff9850cd9f2781da1dcec96c31c4748d96e8\", \"leveldb_service_unittests\": \"bf0c238bdf4e0195924d0b7fdfb6690f7d9d131f\", \"libjingle_xmpp_unittests\": \"8153107f2b2aead93bd8b0d8aea10884055af120\", \"media_blink_unittests\": \"005dcfda527480e3067f9449798a9e4dfaa4843c\", \"media_service_unittests\": \"65e6b3872ed62e1d5d4b64b6bd6515abf4ed834b\", \"media_unittests\": \"378ae674bb780f14c7c8f09f97334ebb16445ae7\", \"message_center_unittests\": \"b0bb27fa6f8c19fb7b3a55b7ebf970ec46c6b4e2\", \"midi_unittests\": \"98188fcd2c10662b81f5ce9c6302383f66d1bf9a\", \"mojo_core_unittests\": \"1efea84f1d1bb5f16f7ebbbb1d1559847ce471d6\", \"mojo_unittests\": \"2a02206e3188d94ae42dafac7b05619d6d72da5b\", \"nacl_helper_nonsfi_unittests\": \"9eb5dcc606724e6913e0ce02a805fb33f913bf0e\", \"nacl_loader_unittests\": \"925f0198d40bbffb525c1589de4725879b0a0df9\", \"native_theme_unittests\": \"9eded1ed861b7c7c103ee0189e4df76aaf4c45b5\", \"net_unittests\": \"4b7f16d6da086a78eb563b3a342179bf91784559\", \"ozone_gl_unittests\": \"a03a07a2d13334983521a326396033ec8c6cc8a4\", \"ozone_unittests\": \"eb704e473205cef10e0aa435bbb2bb96c43e6da9\", \"ozone_x11_unittests\": \"cb04b04280f7a93d209aae84d175b32a71c72eb2\", \"pdf_unittests\": \"157ab556998997822f078e8d15a3121b147db6ef\", \"perfetto_unittests\": \"55a9c8b37a6532cfa9c184c4500d33aa284dbd87\", \"ppapi_unittests\": \"ea071e57397e9c1a566b130f4ac0d91cae541299\", \"printing_unittests\": \"ddcfa33996a7b5e4f0f7209d6b9ee7057c86752e\", \"remoting_unittests\": \"927205bb37a36749e1fc6df9727d2231a37c1872\", \"sandbox_linux_unittests\": \"a46b0551d262a9dcb8704c2aa07bb63a066d77fe\", \"service_manager_unittests\": \"7bef76c86e1bbaeaaffdcfe164eed755fd7301a8\", \"services_unittests\": \"8c6544d00e60b26d9aa65bc3271f19b2be529e6a\", \"shell_dialogs_unittests\": \"4222ba8f2924f3dfd47938e54bbb0bbccf5a20d9\", \"skia_unittests\": \"344faccdc32a6ddde41afccf8f7bbe651dc1259e\", \"snapshot_unittests\": \"f7ec2a46204430a59fecce2bb1916f49db1ff0ab\", \"sql_unittests\": \"f731abe5179d742e2c9042aeed8995bfd9b25e7c\", \"storage_unittests\": \"bc761e8f4158b1ba33a7f708f278b6b54da6a1e1\", \"sync_integration_tests\": \"243a2219d5da13123b199790009ea09f1fd7744f\", \"traffic_annotation_auditor_unittests\": \"575dd0836197012f1e8c6a3dc6617058021918ea\", \"ui_base_unittests\": \"b9f389eb7426124e1662c80817eb9a66bbd6ffc5\", \"ui_chromeos_unittests\": \"deb7ab1b89b9eab95d649fcbdf32204a1cbe107c\", \"ui_touch_selection_unittests\": \"cffbec2f97bd3479450b9188fedefe188d3808b5\", \"unit_tests\": \"50eb667942be96b587ac3cb140ac8814f4679504\", \"url_unittests\": \"3749c6dfb4760c75d192972f75f72639dde093b0\", \"usage_time_limit_unittests\": \"573f7f97b04bdf34c136b39c664c78cd6f71c962\", \"views_unittests\": \"737398c22283fe28039bcfa7228b7b7d70e5d1d0\", \"viz_unittests\": \"4379b3830cce7c9e86d736fdf3da6474f25d5c1a\", \"wm_unittests\": \"a7dc31ad3311ae93064e90acd71b2b479e262dc5\", \"wtf_unittests\": \"fe19f24636abf988500509be667d7a120c20a0f2\"}}, \"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\": [\"4282-c170fa6\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1556812657928564",
"status": "COMPLETED",
"status_changed_ts": "1556818619166292",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/12175",
"builder:linux-chromeos-dbg",
"buildset:commit/git/9a18951eadae5991f8c09c8fb71d233888e18505",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9a18951eadae5991f8c09c8fb71d233888e18505",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079987390927581104",
"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/8914557328706867728/+/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:4492157a2e5f6810",
"user_agent:luci-scheduler"
],
"updated_ts": "1556818619169772",
"url": "https://ci.chromium.org/b/8914557328706867728",
"utcnow_ts": "1556818619893296"
}
}
[17:36:59.916] Invocation finished in 1h39m25.729613793s with status SUCCEEDED