[04:45:46.136] New invocation is queued and will start shortly
[04:45:47.223] Starting the invocation (attempt 1)
[04:45:47.260] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:45:47.261] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:45:47.261] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9083834729589287952",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"a9fbdd4af307eee6ef2f042dcc626d0b0ec758cd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9083834729589287952",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/a9fbdd4af307eee6ef2f042dcc626d0b0ec758cd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a9fbdd4af307eee6ef2f042dcc626d0b0ec758cd",
"gitiles_ref:refs/heads/master"
]
}
[04:45:48.397] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1553143547450889",
"id": "8918404667547581920",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a9fbdd4af307eee6ef2f042dcc626d0b0ec758cd\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553143548138194",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11477",
"builder:linux-chromeos-dbg",
"buildset:commit/git/a9fbdd4af307eee6ef2f042dcc626d0b0ec758cd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a9fbdd4af307eee6ef2f042dcc626d0b0ec758cd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083834729589287952",
"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/8918404667547581920/+/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:43b7634f8e02de10",
"user_agent:luci-scheduler"
],
"updated_ts": "1553143548138311",
"url": "https://ci.chromium.org/b/8918404667547581920",
"utcnow_ts": "1553143548379070"
}
}
[04:45:48.397] Task URL: https://ci.chromium.org/b/8918404667547581920
[04:45:48.397] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:2:0) after 1m0s
[04:46:23.189] Received PubSub notification, asking Buildbucket for the build status
[04:46:23.613] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:48.438] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:2:0)
[04:46:48.438] Timer tick, asking Buildbucket for the build status
[04:46:48.862] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:48.862] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:4:0) after 1m0s
[04:47:48.892] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:4:0)
[04:47:48.892] Timer tick, asking Buildbucket for the build status
[04:47:49.060] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:49.060] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:5:0) after 1m0s
[04:48:49.079] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:5:0)
[04:48:49.079] Timer tick, asking Buildbucket for the build status
[04:48:49.386] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:49.386] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:6:0) after 1m0s
[04:49:49.403] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:6:0)
[04:49:49.403] Timer tick, asking Buildbucket for the build status
[04:49:49.517] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:49.517] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:7:0) after 1m0s
[04:50:49.533] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:7:0)
[04:50:49.533] Timer tick, asking Buildbucket for the build status
[04:50:49.891] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:49.891] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:8:0) after 1m0s
[04:51:49.912] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:8:0)
[04:51:49.912] Timer tick, asking Buildbucket for the build status
[04:51:50.056] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:50.056] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:9:0) after 1m0s
[04:52:50.076] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:9:0)
[04:52:50.076] Timer tick, asking Buildbucket for the build status
[04:52:50.216] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:50.216] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:10:0) after 1m0s
[04:53:50.205] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:10:0)
[04:53:50.205] Timer tick, asking Buildbucket for the build status
[04:53:50.322] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:50.322] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:11:0) after 1m0s
[04:54:50.345] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:11:0)
[04:54:50.345] Timer tick, asking Buildbucket for the build status
[04:54:50.455] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:50.455] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:12:0) after 1m0s
[04:55:50.457] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:12:0)
[04:55:50.457] Timer tick, asking Buildbucket for the build status
[04:55:50.886] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:50.886] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:13:0) after 1m0s
[04:56:50.905] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:13:0)
[04:56:50.905] Timer tick, asking Buildbucket for the build status
[04:56:51.028] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:51.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:14:0) after 1m0s
[04:57:51.051] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:14:0)
[04:57:51.051] Timer tick, asking Buildbucket for the build status
[04:57:51.889] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:51.889] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:15:0) after 1m0s
[04:58:51.907] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:15:0)
[04:58:51.907] Timer tick, asking Buildbucket for the build status
[04:58:52.031] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:52.031] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:16:0) after 1m0s
[04:59:52.007] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:16:0)
[04:59:52.007] Timer tick, asking Buildbucket for the build status
[04:59:52.171] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:52.171] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:17:0) after 1m0s
[05:00:52.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:17:0)
[05:00:52.199] Timer tick, asking Buildbucket for the build status
[05:00:52.392] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:52.392] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:18:0) after 1m0s
[05:01:52.421] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:18:0)
[05:01:52.421] Timer tick, asking Buildbucket for the build status
[05:01:52.774] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:52.774] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:19:0) after 1m0s
[05:02:52.783] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:19:0)
[05:02:52.783] Timer tick, asking Buildbucket for the build status
[05:02:52.905] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:52.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:20:0) after 1m0s
[05:03:52.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:20:0)
[05:03:52.891] Timer tick, asking Buildbucket for the build status
[05:03:53.200] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:53.200] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:21:0) after 1m0s
[05:04:53.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:21:0)
[05:04:53.223] Timer tick, asking Buildbucket for the build status
[05:04:53.324] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:53.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:22:0) after 1m0s
[05:05:53.352] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:22:0)
[05:05:53.352] Timer tick, asking Buildbucket for the build status
[05:05:53.773] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:53.773] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:23:0) after 1m0s
[05:06:53.775] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:23:0)
[05:06:53.775] Timer tick, asking Buildbucket for the build status
[05:06:53.898] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:53.898] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:24:0) after 1m0s
[05:07:53.916] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:24:0)
[05:07:53.916] Timer tick, asking Buildbucket for the build status
[05:07:54.034] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:54.034] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:25:0) after 1m0s
[05:08:54.052] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:25:0)
[05:08:54.052] Timer tick, asking Buildbucket for the build status
[05:08:54.142] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:54.142] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:26:0) after 1m0s
[05:09:54.162] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:26:0)
[05:09:54.162] Timer tick, asking Buildbucket for the build status
[05:09:54.376] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:54.376] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:27:0) after 1m0s
[05:10:54.367] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:27:0)
[05:10:54.367] Timer tick, asking Buildbucket for the build status
[05:10:54.495] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:54.495] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:28:0) after 1m0s
[05:11:54.479] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:28:0)
[05:11:54.479] Timer tick, asking Buildbucket for the build status
[05:11:54.748] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:54.748] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:29:0) after 1m0s
[05:12:54.768] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:29:0)
[05:12:54.768] Timer tick, asking Buildbucket for the build status
[05:12:55.379] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:55.379] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:30:0) after 1m0s
[05:13:55.404] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:30:0)
[05:13:55.404] Timer tick, asking Buildbucket for the build status
[05:13:55.514] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:55.514] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:31:0) after 1m0s
[05:14:55.520] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:31:0)
[05:14:55.520] Timer tick, asking Buildbucket for the build status
[05:14:56.027] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:56.027] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:32:0) after 1m0s
[05:15:56.014] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:32:0)
[05:15:56.014] Timer tick, asking Buildbucket for the build status
[05:15:56.259] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:56.259] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:33:0) after 1m0s
[05:16:56.288] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:33:0)
[05:16:56.288] Timer tick, asking Buildbucket for the build status
[05:16:57.027] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:57.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:34:0) after 1m0s
[05:17:57.028] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:34:0)
[05:17:57.028] Timer tick, asking Buildbucket for the build status
[05:17:57.307] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:57.307] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:35:0) after 1m0s
[05:18:57.342] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:35:0)
[05:18:57.342] Timer tick, asking Buildbucket for the build status
[05:18:57.500] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:57.500] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:36:0) after 1m0s
[05:19:57.525] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:36:0)
[05:19:57.525] Timer tick, asking Buildbucket for the build status
[05:19:57.660] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:57.660] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:37:0) after 1m0s
[05:20:57.679] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:37:0)
[05:20:57.679] Timer tick, asking Buildbucket for the build status
[05:20:58.040] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:58.040] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:38:0) after 1m0s
[05:21:58.078] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:38:0)
[05:21:58.078] Timer tick, asking Buildbucket for the build status
[05:21:58.232] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:58.232] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:39:0) after 1m0s
[05:22:58.237] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:39:0)
[05:22:58.237] Timer tick, asking Buildbucket for the build status
[05:22:58.875] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:58.875] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:40:0) after 1m0s
[05:23:58.894] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:40:0)
[05:23:58.894] Timer tick, asking Buildbucket for the build status
[05:23:59.209] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:59.209] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:41:0) after 1m0s
[05:24:59.193] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:41:0)
[05:24:59.193] Timer tick, asking Buildbucket for the build status
[05:24:59.351] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:59.351] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:42:0) after 1m0s
[05:25:59.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:42:0)
[05:25:59.371] Timer tick, asking Buildbucket for the build status
[05:25:59.878] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:59.878] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:43:0) after 1m0s
[05:26:59.934] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:43:0)
[05:26:59.934] Timer tick, asking Buildbucket for the build status
[05:27:00.224] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:00.224] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:44:0) after 1m0s
[05:28:00.243] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:44:0)
[05:28:00.243] Timer tick, asking Buildbucket for the build status
[05:28:01.014] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:01.014] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:45:0) after 1m0s
[05:29:00.988] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:45:0)
[05:29:00.988] Timer tick, asking Buildbucket for the build status
[05:29:01.474] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:01.474] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:46:0) after 1m0s
[05:30:01.507] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:46:0)
[05:30:01.507] Timer tick, asking Buildbucket for the build status
[05:30:01.918] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:01.918] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:47:0) after 1m0s
[05:31:01.940] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:47:0)
[05:31:01.940] Timer tick, asking Buildbucket for the build status
[05:31:02.534] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:02.534] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:48:0) after 1m0s
[05:32:02.565] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:48:0)
[05:32:02.565] Timer tick, asking Buildbucket for the build status
[05:32:02.840] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:02.840] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:49:0) after 1m0s
[05:33:02.857] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:49:0)
[05:33:02.857] Timer tick, asking Buildbucket for the build status
[05:33:03.668] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:03.668] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:50:0) after 1m0s
[05:34:03.691] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:50:0)
[05:34:03.691] Timer tick, asking Buildbucket for the build status
[05:34:03.890] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:03.890] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:51:0) after 1m0s
[05:35:03.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:51:0)
[05:35:03.891] Timer tick, asking Buildbucket for the build status
[05:35:04.053] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:04.053] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:52:0) after 1m0s
[05:36:04.072] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:52:0)
[05:36:04.072] Timer tick, asking Buildbucket for the build status
[05:36:04.235] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:04.235] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:53:0) after 1m0s
[05:37:04.274] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:53:0)
[05:37:04.274] Timer tick, asking Buildbucket for the build status
[05:37:04.782] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:04.782] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:54:0) after 1m0s
[05:38:04.770] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:54:0)
[05:38:04.770] Timer tick, asking Buildbucket for the build status
[05:38:05.408] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:05.408] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:55:0) after 1m0s
[05:39:05.408] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:55:0)
[05:39:05.408] Timer tick, asking Buildbucket for the build status
[05:39:05.925] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:05.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:56:0) after 1m0s
[05:40:05.905] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:56:0)
[05:40:05.905] Timer tick, asking Buildbucket for the build status
[05:40:06.051] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:06.051] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:57:0) after 1m0s
[05:41:06.091] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:57:0)
[05:41:06.091] Timer tick, asking Buildbucket for the build status
[05:41:06.673] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:06.673] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:58:0) after 1m0s
[05:42:06.668] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:58:0)
[05:42:06.668] Timer tick, asking Buildbucket for the build status
[05:42:06.801] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:06.801] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:59:0) after 1m0s
[05:43:06.838] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:59:0)
[05:43:06.838] Timer tick, asking Buildbucket for the build status
[05:43:06.963] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:06.963] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:60:0) after 1m0s
[05:44:07.021] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:60:0)
[05:44:07.021] Timer tick, asking Buildbucket for the build status
[05:44:09.335] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:09.335] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:61:0) after 1m0s
[05:45:09.333] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:61:0)
[05:45:09.333] Timer tick, asking Buildbucket for the build status
[05:45:09.562] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:09.562] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:62:0) after 1m0s
[05:46:09.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:62:0)
[05:46:09.641] Timer tick, asking Buildbucket for the build status
[05:46:10.082] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:10.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:63:0) after 1m0s
[05:47:10.056] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:63:0)
[05:47:10.056] Timer tick, asking Buildbucket for the build status
[05:47:10.218] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:10.218] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:64:0) after 1m0s
[05:48:10.237] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:64:0)
[05:48:10.237] Timer tick, asking Buildbucket for the build status
[05:48:10.394] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:10.394] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:65:0) after 1m0s
[05:49:10.413] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:65:0)
[05:49:10.413] Timer tick, asking Buildbucket for the build status
[05:49:11.723] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:11.723] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:66:0) after 1m0s
[05:50:11.728] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:66:0)
[05:50:11.728] Timer tick, asking Buildbucket for the build status
[05:50:12.058] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:12.058] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:67:0) after 1m0s
[05:51:12.074] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:67:0)
[05:51:12.074] Timer tick, asking Buildbucket for the build status
[05:51:12.213] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:12.213] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:68:0) after 1m0s
[05:52:12.238] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:68:0)
[05:52:12.238] Timer tick, asking Buildbucket for the build status
[05:52:12.378] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:12.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:69:0) after 1m0s
[05:53:12.404] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:69:0)
[05:53:12.404] Timer tick, asking Buildbucket for the build status
[05:53:12.974] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:12.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:70:0) after 1m0s
[05:54:13.008] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:70:0)
[05:54:13.008] Timer tick, asking Buildbucket for the build status
[05:54:13.173] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:13.173] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:71:0) after 1m0s
[05:55:13.167] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:71:0)
[05:55:13.167] Timer tick, asking Buildbucket for the build status
[05:55:13.811] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:13.811] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:72:0) after 1m0s
[05:56:13.831] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:72:0)
[05:56:13.831] Timer tick, asking Buildbucket for the build status
[05:56:13.967] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:13.967] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:73:0) after 1m0s
[05:57:13.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:73:0)
[05:57:13.986] Timer tick, asking Buildbucket for the build status
[05:57:14.136] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:14.136] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:74:0) after 1m0s
[05:58:14.155] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:74:0)
[05:58:14.155] Timer tick, asking Buildbucket for the build status
[05:58:14.304] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:14.304] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:75:0) after 1m0s
[05:59:14.315] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:75:0)
[05:59:14.315] Timer tick, asking Buildbucket for the build status
[05:59:14.466] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:14.466] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:76:0) after 1m0s
[06:00:14.475] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:76:0)
[06:00:14.475] Timer tick, asking Buildbucket for the build status
[06:00:14.889] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:14.889] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:77:0) after 1m0s
[06:01:14.898] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:77:0)
[06:01:14.898] Timer tick, asking Buildbucket for the build status
[06:01:15.110] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:15.110] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:78:0) after 1m0s
[06:02:15.132] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:78:0)
[06:02:15.132] Timer tick, asking Buildbucket for the build status
[06:02:15.241] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:15.241] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:79:0) after 1m0s
[06:03:15.263] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:79:0)
[06:03:15.263] Timer tick, asking Buildbucket for the build status
[06:03:15.488] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:15.488] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:80:0) after 1m0s
[06:04:15.472] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:80:0)
[06:04:15.472] Timer tick, asking Buildbucket for the build status
[06:04:15.599] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:15.600] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:81:0) after 1m0s
[06:05:15.640] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:81:0)
[06:05:15.640] Timer tick, asking Buildbucket for the build status
[06:05:15.805] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:15.805] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:82:0) after 1m0s
[06:06:15.790] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:82:0)
[06:06:15.790] Timer tick, asking Buildbucket for the build status
[06:06:16.219] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:16.219] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:83:0) after 1m0s
[06:07:16.240] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:83:0)
[06:07:16.240] Timer tick, asking Buildbucket for the build status
[06:07:17.066] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:17.066] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:84:0) after 1m0s
[06:08:17.087] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:84:0)
[06:08:17.087] Timer tick, asking Buildbucket for the build status
[06:08:17.264] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:17.264] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:85:0) after 1m0s
[06:09:17.285] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:85:0)
[06:09:17.285] Timer tick, asking Buildbucket for the build status
[06:09:17.795] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:17.795] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:86:0) after 1m0s
[06:10:17.810] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:86:0)
[06:10:17.810] Timer tick, asking Buildbucket for the build status
[06:10:18.406] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:18.406] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:87:0) after 1m0s
[06:11:18.410] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:87:0)
[06:11:18.410] Timer tick, asking Buildbucket for the build status
[06:11:18.678] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:18.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:88:0) after 1m0s
[06:12:18.704] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:88:0)
[06:12:18.704] Timer tick, asking Buildbucket for the build status
[06:12:18.820] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:18.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:89:0) after 1m0s
[06:13:18.814] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:89:0)
[06:13:18.814] Timer tick, asking Buildbucket for the build status
[06:13:19.094] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:19.094] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:90:0) after 1m0s
[06:14:19.132] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:90:0)
[06:14:19.132] Timer tick, asking Buildbucket for the build status
[06:14:19.874] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:19.874] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:91:0) after 1m0s
[06:15:19.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:91:0)
[06:15:19.896] Timer tick, asking Buildbucket for the build status
[06:15:20.094] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:20.094] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:92:0) after 1m0s
[06:16:20.128] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:92:0)
[06:16:20.128] Timer tick, asking Buildbucket for the build status
[06:16:20.380] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:20.380] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:93:0) after 1m0s
[06:17:20.401] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:93:0)
[06:17:20.401] Timer tick, asking Buildbucket for the build status
[06:17:20.778] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:20.778] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:94:0) after 1m0s
[06:18:20.803] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:94:0)
[06:18:20.803] Timer tick, asking Buildbucket for the build status
[06:18:21.289] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:21.289] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:95:0) after 1m0s
[06:19:21.315] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:95:0)
[06:19:21.315] Timer tick, asking Buildbucket for the build status
[06:19:21.491] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:21.491] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:96:0) after 1m0s
[06:20:21.523] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:96:0)
[06:20:21.523] Timer tick, asking Buildbucket for the build status
[06:20:22.700] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:22.700] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:97:0) after 1m0s
[06:21:22.714] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:97:0)
[06:21:22.715] Timer tick, asking Buildbucket for the build status
[06:21:22.862] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:22.862] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:98:0) after 1m0s
[06:22:22.872] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:98:0)
[06:22:22.872] Timer tick, asking Buildbucket for the build status
[06:22:23.335] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:23.335] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:99:0) after 1m0s
[06:23:23.327] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:99:0)
[06:23:23.327] Timer tick, asking Buildbucket for the build status
[06:23:23.724] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:23.724] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:100:0) after 1m0s
[06:24:23.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:100:0)
[06:24:23.712] Timer tick, asking Buildbucket for the build status
[06:24:24.488] Build 8918404667547581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:24.488] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083834729589287952:101:0) after 1m0s
[06:25:10.101] Received PubSub notification, asking Buildbucket for the build status
[06:25:10.704] Build 8918404667547581920: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[06:25:10.704] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1553149509093866",
"created_by": "project:chromium",
"created_ts": "1553143547450889",
"failure_reason": "BUILD_FAILURE",
"id": "8918404667547581920",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a9fbdd4af307eee6ef2f042dcc626d0b0ec758cd\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"linux-chromeos-dbg\"], \"kvm\": [\"1\"], \"id\": [\"swarm1567-c4\"], \"server_version\": [\"4211-90dafba\"], \"image\": [\"chrome-trusty-18042300-b7223b463e3\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"Failure browser_tests\\nFailure non_network_service_interactive_ui_tests\\nFailure non_single_process_mash_browser_tests\\nFailure non_network_service_browser_tests\"}, \"properties\": {\"got_nacl_revision\": \"7d24fb15d81f95ed30dde0c04b68584749709657\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_revision\": \"a9fbdd4af307eee6ef2f042dcc626d0b0ec758cd\", \"recipe\": \"chromium\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"got_dawn_revision\": \"54e4d47db4910ebd1ffce0247b60d4e6f984774f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27211}\", \"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"got_revision_cp\": \"refs/heads/master@{#642820}\", \"branch\": \"refs/heads/master\", \"swarm_hashes\": {\"mojo_core_unittests\": \"0aa0de5f9f92c78c9590b9b88cec5c52ae8786b3\", \"leveldb_service_unittests\": \"497de0bbd19f52cc3105ab91ddcf17105edaa868\", \"storage_unittests\": \"8d226d0319eee4f3b57affa0d0c70cc285f1300d\", \"compositor_unittests\": \"e1b1c506c57d3c04ad7f51876d894534e511ad1c\", \"angle_unittests\": \"c8bf8b22be33de120de28c9af3498875b3fc8bc6\", \"cast_unittests\": \"b67f41eb6112bf8670bff515119f3810267bb37b\", \"blink_unittests\": \"f3bc9e1348899622bfda140e8b7eaf63ebd501c7\", \"cc_unittests\": \"bd8a46e062c862d2d2ab8498698ac8d600fa2552\", \"blink_common_unittests\": \"b0b3bad6d7dc2651b5114cae35c9e5a343ffd63b\", \"chromeos_components_unittests\": \"1d2ff0f268a41193e948d77cdad09acd24756c86\", \"shell_dialogs_unittests\": \"acf65199af9bf9c226b267f923540341f1baca98\", \"message_center_unittests\": \"2419503d201301ab006bfed5bb447b0410914f4c\", \"ppapi_unittests\": \"e2579a088c8691ffab3407467367c629953e5e0a\", \"extensions_unittests\": \"b8d659db1ba700c8b06d6f088ac2bd7dc89dec14\", \"gl_unittests_ozone\": \"8f444124f012a1738565d0ed0427308a25260e69\", \"app_shell_unittests\": \"0c2305204f383c529e53fc5083d262a01be29816\", \"chrome_app_unittests\": \"7fd9053ad3bc37ef6c85a9fdb03585f620f403e9\", \"gcm_unit_tests\": \"dfeb0ac00b654b25768aa7661f2f52da87987f65\", \"views_unittests\": \"e39ae47786b981c2627adeebef6921f6c10637fb\", \"ash_unittests\": \"9a1272d3e7fa6591ff7a00b50b6e72820adca5e0\", \"ui_base_unittests\": \"7a326d426f051e8770d75b96e22fc141aec94f82\", \"wm_unittests\": \"9118d8946eaf15cf6009f2404eaec3b101168d27\", \"boringssl_ssl_tests\": \"b262e4443bac3bf69386c1cf0ee010b2cfd73ad5\", \"gin_unittests\": \"92101da2d40c5e129424aa7105fdef275b37a351\", \"extensions_browsertests\": \"6cbd273f4f50c6e1f038a6bb4604a36e6f9a269b\", \"content_unittests\": \"95e8b107572c081f32823ae4ca58846530fe38ed\", \"ozone_gl_unittests\": \"b1e164c2761fce0c5dbe1539ebd3eb549c8ddca8\", \"libjingle_xmpp_unittests\": \"a693f4853414c8a3d851724a534b64ddf61275e8\", \"remoting_unittests\": \"d07c51dfc8ffa2f4f29a54106f15e17a22412206\", \"filesystem_service_unittests\": \"c0434b5e3f07dedffbb28d525066aa311045a317\", \"gfx_unittests\": \"7a69ea099b1b8a92973855e679cd1832317d00c5\", \"latency_unittests\": \"418320ef68dd848bbac1308accb25a5fed955ab5\", \"usage_time_limit_unittests\": \"e4974736470f8f2d41f80af1275828bc250584d7\", \"boringssl_crypto_tests\": \"9aa1a3164cf172bfb3538982b80efe6c80e1c423\", \"media_unittests\": \"b7f3bd3e076d78ec7c71632a7cccb8dd600576da\", \"chromedriver_unittests\": \"33165a0ea7d4ac00d0d7068688717ed2c591000e\", \"traffic_annotation_auditor_unittests\": \"dad4f88c10c1d68313e2a73501354d75b0e6b17b\", \"chromeos_unittests\": \"c41bf3bea61a724dcb6184c513590f27948ff606\", \"media_service_unittests\": \"4f89eb78d3330d546b6a75a511dd0d3fd303ff49\", \"jingle_unittests\": \"595787768b278d966861b5026ca7fe5881a3b267\", \"capture_unittests\": \"52d65f7746a0a716e57afffad531947c93d9d05f\", \"ui_chromeos_unittests\": \"51af83d98eca73bec6b28beca7758c3f57b292e6\", \"service_manager_unittests\": \"488c15d35d50df33c745f61a286a4b3e743e1cb2\", \"media_blink_unittests\": \"ce46a41d64098adfba319048f44b33f2f75c8269\", \"components_unittests\": \"b1c3408391a747fe30c0e5ea7ddd06c5eaf4edd7\", \"snapshot_unittests\": \"53de22ac5358871d0b7c0fec19d6ea1c65f32b9b\", \"accessibility_unittests\": \"0e3dc0397ebc6cea3023df0db6e66f200f2a556c\", \"aura_unittests\": \"95bd3387c44e26d0b2da238ce62561ade58e4c71\", \"services_unittests\": \"dec56cc4dbb9d501f28176bc794ccbde455bea06\", \"ozone_unittests\": \"8315505dd0bc4ed85fbce25615b8788d09017d72\", \"events_unittests\": \"9c68ab59f6e4b245a16b0336aa64fc7c337f85bc\", \"ui_touch_selection_unittests\": \"2833271ef90c85f856ebba474445cf950b566c58\", \"cacheinvalidation_unittests\": \"b83be3e69d1c3adcebcb222c1ce7a664dd2b2eb7\", \"viz_unittests\": \"076e258f971d10b439367587054551aa8d290cab\", \"browser_tests\": \"c815c8853c1cab0f0433a39142da3269360d312a\", \"blink_platform_unittests\": \"6150253afb588ff87c4989283fbe2959e32c8d4c\", \"midi_unittests\": \"530992158f532721e8095c818d16436143de5ece\", \"dbus_unittests\": \"f516b422915b8487897066ff3f130cdb46b6cd01\", \"gpu_unittests\": \"efed07fe39211fa310d9e701a0e252d717df07d0\", \"exo_unittests\": \"02e1b7244ff22bf05f432bfceb49cb491150abea\", \"native_theme_unittests\": \"e84f47fe899ad8af789945bad9acfb981ab2f7b8\", \"ozone_x11_unittests\": \"8181199229cb67c9fe61db32cfd7d4fce8f35834\", \"interactive_ui_tests\": \"07b8cb918c64632e79e6ebc9b1654f9bd9323674\", \"mojo_unittests\": \"4903e80737dd57cd6af75afd8a67bd9d01e22877\", \"url_unittests\": \"c1605b0ef9b9ddac4866cd7c612b83b176c07135\", \"google_apis_unittests\": \"efd1693a43cf32254a4241e423a2f6acfbb8ee0d\", \"blink_heap_unittests\": \"5edb4700843ac02f966d63cb42235e4de8851108\", \"base_unittests\": \"88d18990e185a9cce4fab4a5a90e19e238e7d60d\", \"sync_integration_tests\": \"213f972d02dc52e617d070efaa02d2cb2b02e96f\", \"sql_unittests\": \"f13a427bc4d8f554a8cbd7661a80f93c4ded6986\", \"nacl_helper_nonsfi_unittests\": \"93806aaf9335d96f6b0cd69b59d149687afe8fe6\", \"keyboard_unittests\": \"75afd034e9b78700952dac0d592b9b61c31faf94\", \"blink_fuzzer_unittests\": \"52fad2701bfdde862c02f09fd690bb553c105b3c\", \"app_list_unittests\": \"12b822bc6387151213900926f81f8872497b1717\", \"ipc_tests\": \"fef08b8d1b0168919ee7d2fd1a027f930d274228\", \"perfetto_unittests\": \"596fdc2ac3356ea08f3144c65f1499062d0eb35d\", \"net_unittests\": \"0d88aa3067ae45ce81fd1de2dc67090c7d33d20b\", \"components_browsertests\": \"3d3fb95e0a6b3c8cc1f94ee3562a040181384bda\", \"display_unittests\": \"aedae101d67c37f14c824ab70280bb4babef5b9c\", \"wtf_unittests\": \"4cbc0faab402ceb82ecb8ec88b8eb0f50ecc16e3\", \"pdf_unittests\": \"bb053b5e82188ad6c096a48c625cf91476f8328b\", \"skia_unittests\": \"11a1a215f5bc75d0179700398ce040fabc4092b4\", \"crypto_unittests\": \"3ecd4e823fc8d451ac1e8d6fdef90c9074109742\", \"nacl_loader_unittests\": \"3b19009bfec67164a440ec111bde1d1bccd3b0ac\", \"unit_tests\": \"7f851b11d0133786e006a389ea719c812c612b30\", \"sandbox_linux_unittests\": \"4f6c4aace957a0d0e836346ed6003e334fc59a0b\", \"content_browsertests\": \"cdceb0851e891f494ec30bbd8af630442b04bf74\", \"printing_unittests\": \"a62679bcd1d3b3626f63f4f5d063d6d66070329c\", \"device_unittests\": \"337094c60349408d09f575be6f6760b77a63b6f1\"}, \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"linux-chromeos-dbg\", \"got_webrtc_revision\": \"f7f9845d9e5b24c73a70fceb278a20b43e9d9919\", \"mastername\": \"chromium.chromiumos\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"got_v8_revision\": \"da7a3d8e0374cb531d9ce0a3215650eccdd9a8f0\", \"got_v8_revision_cp\": \"refs/heads/7.5.75@{#2}\", \"got_angle_revision\": \"f455f7565a7ad654b97aef553cc67e418fbd0e3c\", \"swarm_hashes_refs/heads/master(at){#642820}_without_patch\": {\"mojo_core_unittests\": \"0aa0de5f9f92c78c9590b9b88cec5c52ae8786b3\", \"leveldb_service_unittests\": \"497de0bbd19f52cc3105ab91ddcf17105edaa868\", \"storage_unittests\": \"8d226d0319eee4f3b57affa0d0c70cc285f1300d\", \"jingle_unittests\": \"595787768b278d966861b5026ca7fe5881a3b267\", \"angle_unittests\": \"c8bf8b22be33de120de28c9af3498875b3fc8bc6\", \"cast_unittests\": \"b67f41eb6112bf8670bff515119f3810267bb37b\", \"blink_unittests\": \"f3bc9e1348899622bfda140e8b7eaf63ebd501c7\", \"cc_unittests\": \"bd8a46e062c862d2d2ab8498698ac8d600fa2552\", \"blink_common_unittests\": \"b0b3bad6d7dc2651b5114cae35c9e5a343ffd63b\", \"chromeos_components_unittests\": \"1d2ff0f268a41193e948d77cdad09acd24756c86\", \"shell_dialogs_unittests\": \"acf65199af9bf9c226b267f923540341f1baca98\", \"message_center_unittests\": \"2419503d201301ab006bfed5bb447b0410914f4c\", \"ppapi_unittests\": \"e2579a088c8691ffab3407467367c629953e5e0a\", \"extensions_unittests\": \"b8d659db1ba700c8b06d6f088ac2bd7dc89dec14\", \"gl_unittests_ozone\": \"8f444124f012a1738565d0ed0427308a25260e69\", \"app_shell_unittests\": \"0c2305204f383c529e53fc5083d262a01be29816\", \"chrome_app_unittests\": \"7fd9053ad3bc37ef6c85a9fdb03585f620f403e9\", \"gcm_unit_tests\": \"dfeb0ac00b654b25768aa7661f2f52da87987f65\", \"views_unittests\": \"e39ae47786b981c2627adeebef6921f6c10637fb\", \"ash_unittests\": \"9a1272d3e7fa6591ff7a00b50b6e72820adca5e0\", \"ui_base_unittests\": \"7a326d426f051e8770d75b96e22fc141aec94f82\", \"wm_unittests\": \"9118d8946eaf15cf6009f2404eaec3b101168d27\", \"boringssl_ssl_tests\": \"b262e4443bac3bf69386c1cf0ee010b2cfd73ad5\", \"gin_unittests\": \"92101da2d40c5e129424aa7105fdef275b37a351\", \"extensions_browsertests\": \"6cbd273f4f50c6e1f038a6bb4604a36e6f9a269b\", \"content_unittests\": \"95e8b107572c081f32823ae4ca58846530fe38ed\", \"ozone_gl_unittests\": \"b1e164c2761fce0c5dbe1539ebd3eb549c8ddca8\", \"traffic_annotation_auditor_unittests\": \"dad4f88c10c1d68313e2a73501354d75b0e6b17b\", \"libjingle_xmpp_unittests\": \"a693f4853414c8a3d851724a534b64ddf61275e8\", \"remoting_unittests\": \"d07c51dfc8ffa2f4f29a54106f15e17a22412206\", \"filesystem_service_unittests\": \"c0434b5e3f07dedffbb28d525066aa311045a317\", \"gfx_unittests\": \"7a69ea099b1b8a92973855e679cd1832317d00c5\", \"latency_unittests\": \"418320ef68dd848bbac1308accb25a5fed955ab5\", \"usage_time_limit_unittests\": \"e4974736470f8f2d41f80af1275828bc250584d7\", \"boringssl_crypto_tests\": \"9aa1a3164cf172bfb3538982b80efe6c80e1c423\", \"media_unittests\": \"b7f3bd3e076d78ec7c71632a7cccb8dd600576da\", \"chromedriver_unittests\": \"33165a0ea7d4ac00d0d7068688717ed2c591000e\", \"sandbox_linux_unittests\": \"4f6c4aace957a0d0e836346ed6003e334fc59a0b\", \"chromeos_unittests\": \"c41bf3bea61a724dcb6184c513590f27948ff606\", \"media_service_unittests\": \"4f89eb78d3330d546b6a75a511dd0d3fd303ff49\", \"compositor_unittests\": \"e1b1c506c57d3c04ad7f51876d894534e511ad1c\", \"capture_unittests\": \"52d65f7746a0a716e57afffad531947c93d9d05f\", \"ui_chromeos_unittests\": \"51af83d98eca73bec6b28beca7758c3f57b292e6\", \"wtf_unittests\": \"4cbc0faab402ceb82ecb8ec88b8eb0f50ecc16e3\", \"media_blink_unittests\": \"ce46a41d64098adfba319048f44b33f2f75c8269\", \"components_unittests\": \"b1c3408391a747fe30c0e5ea7ddd06c5eaf4edd7\", \"snapshot_unittests\": \"53de22ac5358871d0b7c0fec19d6ea1c65f32b9b\", \"accessibility_unittests\": \"0e3dc0397ebc6cea3023df0db6e66f200f2a556c\", \"aura_unittests\": \"95bd3387c44e26d0b2da238ce62561ade58e4c71\", \"services_unittests\": \"dec56cc4dbb9d501f28176bc794ccbde455bea06\", \"ozone_unittests\": \"8315505dd0bc4ed85fbce25615b8788d09017d72\", \"events_unittests\": \"9c68ab59f6e4b245a16b0336aa64fc7c337f85bc\", \"ui_touch_selection_unittests\": \"2833271ef90c85f856ebba474445cf950b566c58\", \"cacheinvalidation_unittests\": \"b83be3e69d1c3adcebcb222c1ce7a664dd2b2eb7\", \"viz_unittests\": \"076e258f971d10b439367587054551aa8d290cab\", \"browser_tests\": \"c815c8853c1cab0f0433a39142da3269360d312a\", \"blink_platform_unittests\": \"6150253afb588ff87c4989283fbe2959e32c8d4c\", \"midi_unittests\": \"530992158f532721e8095c818d16436143de5ece\", \"dbus_unittests\": \"f516b422915b8487897066ff3f130cdb46b6cd01\", \"gpu_unittests\": \"efed07fe39211fa310d9e701a0e252d717df07d0\", \"exo_unittests\": \"02e1b7244ff22bf05f432bfceb49cb491150abea\", \"native_theme_unittests\": \"e84f47fe899ad8af789945bad9acfb981ab2f7b8\", \"ozone_x11_unittests\": \"8181199229cb67c9fe61db32cfd7d4fce8f35834\", \"interactive_ui_tests\": \"07b8cb918c64632e79e6ebc9b1654f9bd9323674\", \"mojo_unittests\": \"4903e80737dd57cd6af75afd8a67bd9d01e22877\", \"url_unittests\": \"c1605b0ef9b9ddac4866cd7c612b83b176c07135\", \"google_apis_unittests\": \"efd1693a43cf32254a4241e423a2f6acfbb8ee0d\", \"blink_heap_unittests\": \"5edb4700843ac02f966d63cb42235e4de8851108\", \"base_unittests\": \"88d18990e185a9cce4fab4a5a90e19e238e7d60d\", \"sync_integration_tests\": \"213f972d02dc52e617d070efaa02d2cb2b02e96f\", \"sql_unittests\": \"f13a427bc4d8f554a8cbd7661a80f93c4ded6986\", \"nacl_helper_nonsfi_unittests\": \"93806aaf9335d96f6b0cd69b59d149687afe8fe6\", \"keyboard_unittests\": \"75afd034e9b78700952dac0d592b9b61c31faf94\", \"blink_fuzzer_unittests\": \"52fad2701bfdde862c02f09fd690bb553c105b3c\", \"app_list_unittests\": \"12b822bc6387151213900926f81f8872497b1717\", \"ipc_tests\": \"fef08b8d1b0168919ee7d2fd1a027f930d274228\", \"perfetto_unittests\": \"596fdc2ac3356ea08f3144c65f1499062d0eb35d\", \"net_unittests\": \"0d88aa3067ae45ce81fd1de2dc67090c7d33d20b\", \"components_browsertests\": \"3d3fb95e0a6b3c8cc1f94ee3562a040181384bda\", \"display_unittests\": \"aedae101d67c37f14c824ab70280bb4babef5b9c\", \"service_manager_unittests\": \"488c15d35d50df33c745f61a286a4b3e743e1cb2\", \"pdf_unittests\": \"bb053b5e82188ad6c096a48c625cf91476f8328b\", \"skia_unittests\": \"11a1a215f5bc75d0179700398ce040fabc4092b4\", \"crypto_unittests\": \"3ecd4e823fc8d451ac1e8d6fdef90c9074109742\", \"nacl_loader_unittests\": \"3b19009bfec67164a440ec111bde1d1bccd3b0ac\", \"unit_tests\": \"7f851b11d0133786e006a389ea719c812c612b30\", \"content_browsertests\": \"cdceb0851e891f494ec30bbd8af630442b04bf74\", \"printing_unittests\": \"a62679bcd1d3b3626f63f4f5d063d6d66070329c\", \"device_unittests\": \"337094c60349408d09f575be6f6760b77a63b6f1\"}, \"buildnumber\": 11477, \"path_config\": \"generic\", \"revision\": \"a9fbdd4af307eee6ef2f042dcc626d0b0ec758cd\", \"bot_id\": \"swarm1567-c4\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1553143582377772",
"status": "COMPLETED",
"status_changed_ts": "1553149509778117",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11477",
"builder:linux-chromeos-dbg",
"buildset:commit/git/a9fbdd4af307eee6ef2f042dcc626d0b0ec758cd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a9fbdd4af307eee6ef2f042dcc626d0b0ec758cd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083834729589287952",
"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/8918404667547581920/+/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:43b7634f8e02de10",
"user_agent:luci-scheduler"
],
"updated_ts": "1553149509782373",
"url": "https://ci.chromium.org/b/8918404667547581920",
"utcnow_ts": "1553149510692292"
}
}
[06:25:10.704] Invocation finished in 1h39m24.585454661s with status FAILED