[02:58:32.210] New invocation is queued and will start shortly
[02:58:33.295] Starting the invocation (attempt 1)
[02:58:33.350] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:58:33.350] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:58:33.350] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028577326547517760",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"86c3def7d09629f4021f263ec16d54d5beff12d9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9028577326547517760",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/86c3def7d09629f4021f263ec16d54d5beff12d9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/86c3def7d09629f4021f263ec16d54d5beff12d9",
"gitiles_ref:refs/heads/master"
]
}
[02:58:34.327] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605841113462511",
"id": "8863147264568416512",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"86c3def7d09629f4021f263ec16d54d5beff12d9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605841113925539",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/21391",
"builder:linux-chromeos-dbg",
"buildset:commit/git/86c3def7d09629f4021f263ec16d54d5beff12d9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/86c3def7d09629f4021f263ec16d54d5beff12d9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028577326547517760",
"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/8863147264568416512/+/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": "1605841113925646",
"url": "https://ci.chromium.org/b/8863147264568416512",
"utcnow_ts": "1605841114317144"
}
}
[02:58:34.327] Task URL: https://ci.chromium.org/b/8863147264568416512
[02:58:34.327] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:2:0) after 1m0s
[02:59:34.376] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:2:0)
[02:59:34.376] Timer tick, asking Buildbucket for the build status
[02:59:34.573] Build 8863147264568416512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:59:34.573] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:3:0) after 1m0s
[03:00:34.648] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:3:0)
[03:00:34.648] Timer tick, asking Buildbucket for the build status
[03:00:34.732] Build 8863147264568416512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:00:34.732] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:4:0) after 1m0s
[03:01:28.288] Received PubSub notification, asking Buildbucket for the build status
[03:01:28.512] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:34.733] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:4:0)
[03:01:34.733] Timer tick, asking Buildbucket for the build status
[03:01:34.857] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:34.857] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:6:0) after 1m0s
[03:02:34.880] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:6:0)
[03:02:34.880] Timer tick, asking Buildbucket for the build status
[03:02:35.014] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:35.014] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:7:0) after 1m0s
[03:03:35.068] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:7:0)
[03:03:35.068] Timer tick, asking Buildbucket for the build status
[03:03:35.177] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:35.177] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:8:0) after 1m0s
[03:04:35.192] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:8:0)
[03:04:35.192] Timer tick, asking Buildbucket for the build status
[03:04:35.314] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:35.314] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:9:0) after 1m0s
[03:05:35.433] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:9:0)
[03:05:35.433] Timer tick, asking Buildbucket for the build status
[03:05:35.914] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:35.914] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:10:0) after 1m0s
[03:06:36.038] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:10:0)
[03:06:36.038] Timer tick, asking Buildbucket for the build status
[03:06:36.140] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:36.140] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:11:0) after 1m0s
[03:07:36.171] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:11:0)
[03:07:36.171] Timer tick, asking Buildbucket for the build status
[03:07:36.288] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:36.288] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:12:0) after 1m0s
[03:08:36.331] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:12:0)
[03:08:36.331] Timer tick, asking Buildbucket for the build status
[03:08:36.563] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:36.563] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:13:0) after 1m0s
[03:09:36.642] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:13:0)
[03:09:36.642] Timer tick, asking Buildbucket for the build status
[03:09:36.728] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:36.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:14:0) after 1m0s
[03:10:36.790] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:14:0)
[03:10:36.790] Timer tick, asking Buildbucket for the build status
[03:10:36.883] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:36.883] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:15:0) after 1m0s
[03:11:36.941] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:15:0)
[03:11:36.941] Timer tick, asking Buildbucket for the build status
[03:11:37.049] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:37.049] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:16:0) after 1m0s
[03:12:37.022] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:16:0)
[03:12:37.022] Timer tick, asking Buildbucket for the build status
[03:12:37.392] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:37.392] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:17:0) after 1m0s
[03:13:37.386] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:17:0)
[03:13:37.386] Timer tick, asking Buildbucket for the build status
[03:13:37.555] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:37.555] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:18:0) after 1m0s
[03:14:37.584] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:18:0)
[03:14:37.584] Timer tick, asking Buildbucket for the build status
[03:14:37.649] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:37.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:19:0) after 1m0s
[03:15:37.767] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:19:0)
[03:15:37.767] Timer tick, asking Buildbucket for the build status
[03:15:38.007] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:38.007] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:20:0) after 1m0s
[03:16:38.035] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:20:0)
[03:16:38.035] Timer tick, asking Buildbucket for the build status
[03:16:38.695] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:38.695] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:21:0) after 1m0s
[03:17:38.759] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:21:0)
[03:17:38.759] Timer tick, asking Buildbucket for the build status
[03:17:39.070] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:39.070] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:22:0) after 1m0s
[03:18:39.074] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:22:0)
[03:18:39.074] Timer tick, asking Buildbucket for the build status
[03:18:39.456] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:39.456] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:23:0) after 1m0s
[03:19:39.448] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:23:0)
[03:19:39.448] Timer tick, asking Buildbucket for the build status
[03:19:39.505] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:39.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:24:0) after 1m0s
[03:20:39.574] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:24:0)
[03:20:39.574] Timer tick, asking Buildbucket for the build status
[03:20:39.793] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:39.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:25:0) after 1m0s
[03:21:39.814] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:25:0)
[03:21:39.814] Timer tick, asking Buildbucket for the build status
[03:21:39.991] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:39.991] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:26:0) after 1m0s
[03:22:40.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:26:0)
[03:22:40.003] Timer tick, asking Buildbucket for the build status
[03:22:40.074] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:40.074] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:27:0) after 1m0s
[03:23:40.127] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:27:0)
[03:23:40.127] Timer tick, asking Buildbucket for the build status
[03:23:41.924] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:41.924] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:28:0) after 1m0s
[03:24:41.928] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:28:0)
[03:24:41.928] Timer tick, asking Buildbucket for the build status
[03:24:41.990] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:41.990] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:29:0) after 1m0s
[03:25:42.048] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:29:0)
[03:25:42.048] Timer tick, asking Buildbucket for the build status
[03:25:42.133] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:42.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:30:0) after 1m0s
[03:26:42.176] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:30:0)
[03:26:42.176] Timer tick, asking Buildbucket for the build status
[03:26:42.262] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:42.262] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:31:0) after 1m0s
[03:27:42.329] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:31:0)
[03:27:42.329] Timer tick, asking Buildbucket for the build status
[03:27:42.388] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:42.388] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:32:0) after 1m0s
[03:28:42.463] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:32:0)
[03:28:42.463] Timer tick, asking Buildbucket for the build status
[03:28:42.876] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:42.876] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:33:0) after 1m0s
[03:29:42.901] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:33:0)
[03:29:42.901] Timer tick, asking Buildbucket for the build status
[03:29:42.967] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:42.967] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:34:0) after 1m0s
[03:30:43.035] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:34:0)
[03:30:43.035] Timer tick, asking Buildbucket for the build status
[03:30:43.526] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:43.526] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:35:0) after 1m0s
[03:31:43.614] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:35:0)
[03:31:43.614] Timer tick, asking Buildbucket for the build status
[03:31:43.730] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:43.730] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:36:0) after 1m0s
[03:32:43.739] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:36:0)
[03:32:43.739] Timer tick, asking Buildbucket for the build status
[03:32:43.800] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:43.800] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:37:0) after 1m0s
[03:33:43.850] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:37:0)
[03:33:43.850] Timer tick, asking Buildbucket for the build status
[03:33:44.009] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:44.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:38:0) after 1m0s
[03:34:44.105] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:38:0)
[03:34:44.105] Timer tick, asking Buildbucket for the build status
[03:34:44.214] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:44.214] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:39:0) after 1m0s
[03:35:44.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:39:0)
[03:35:44.199] Timer tick, asking Buildbucket for the build status
[03:35:44.515] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:44.515] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:40:0) after 1m0s
[03:36:44.624] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:40:0)
[03:36:44.624] Timer tick, asking Buildbucket for the build status
[03:36:45.458] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:45.458] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:41:0) after 1m0s
[03:37:45.494] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:41:0)
[03:37:45.494] Timer tick, asking Buildbucket for the build status
[03:37:45.576] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:45.576] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:42:0) after 1m0s
[03:38:45.609] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:42:0)
[03:38:45.609] Timer tick, asking Buildbucket for the build status
[03:38:45.725] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:45.725] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:43:0) after 1m0s
[03:39:45.774] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:43:0)
[03:39:45.774] Timer tick, asking Buildbucket for the build status
[03:39:45.862] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:45.862] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:44:0) after 1m0s
[03:40:45.894] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:44:0)
[03:40:45.894] Timer tick, asking Buildbucket for the build status
[03:40:45.971] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:45.971] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:45:0) after 1m0s
[03:41:45.997] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:45:0)
[03:41:45.997] Timer tick, asking Buildbucket for the build status
[03:41:46.075] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:46.075] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:46:0) after 1m0s
[03:42:46.117] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:46:0)
[03:42:46.117] Timer tick, asking Buildbucket for the build status
[03:42:46.171] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:46.171] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:47:0) after 1m0s
[03:43:46.208] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:47:0)
[03:43:46.208] Timer tick, asking Buildbucket for the build status
[03:43:46.318] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:46.318] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:48:0) after 1m0s
[03:44:46.341] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:48:0)
[03:44:46.341] Timer tick, asking Buildbucket for the build status
[03:44:46.503] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:46.503] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:49:0) after 1m0s
[03:45:46.526] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:49:0)
[03:45:46.526] Timer tick, asking Buildbucket for the build status
[03:45:46.585] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:46.585] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:50:0) after 1m0s
[03:46:46.655] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:50:0)
[03:46:46.655] Timer tick, asking Buildbucket for the build status
[03:46:46.717] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:46.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:51:0) after 1m0s
[03:47:46.706] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:51:0)
[03:47:46.706] Timer tick, asking Buildbucket for the build status
[03:47:46.808] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:46.808] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:52:0) after 1m0s
[03:48:46.859] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:52:0)
[03:48:46.859] Timer tick, asking Buildbucket for the build status
[03:48:46.970] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:46.970] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:53:0) after 1m0s
[03:49:47.004] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:53:0)
[03:49:47.004] Timer tick, asking Buildbucket for the build status
[03:49:47.082] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:47.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:54:0) after 1m0s
[03:50:47.111] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:54:0)
[03:50:47.111] Timer tick, asking Buildbucket for the build status
[03:50:47.179] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:47.179] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:55:0) after 1m0s
[03:51:47.209] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:55:0)
[03:51:47.209] Timer tick, asking Buildbucket for the build status
[03:51:47.650] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:47.650] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:56:0) after 1m0s
[03:52:47.670] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:56:0)
[03:52:47.670] Timer tick, asking Buildbucket for the build status
[03:52:47.748] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:47.748] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:57:0) after 1m0s
[03:53:47.769] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:57:0)
[03:53:47.770] Timer tick, asking Buildbucket for the build status
[03:53:47.971] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:47.971] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:58:0) after 1m0s
[03:54:47.997] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:58:0)
[03:54:47.997] Timer tick, asking Buildbucket for the build status
[03:54:48.105] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:48.105] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:59:0) after 1m0s
[03:55:48.149] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:59:0)
[03:55:48.149] Timer tick, asking Buildbucket for the build status
[03:55:48.412] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:48.412] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:60:0) after 1m0s
[03:56:48.415] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:60:0)
[03:56:48.415] Timer tick, asking Buildbucket for the build status
[03:56:48.498] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:48.498] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:61:0) after 1m0s
[03:57:48.495] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:61:0)
[03:57:48.495] Timer tick, asking Buildbucket for the build status
[03:57:48.912] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:48.912] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:62:0) after 1m0s
[03:58:49.071] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:62:0)
[03:58:49.071] Timer tick, asking Buildbucket for the build status
[03:58:49.240] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:49.240] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:63:0) after 1m0s
[03:59:49.326] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:63:0)
[03:59:49.326] Timer tick, asking Buildbucket for the build status
[03:59:49.988] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:49.988] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:64:0) after 1m0s
[04:00:50.022] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:64:0)
[04:00:50.022] Timer tick, asking Buildbucket for the build status
[04:00:50.162] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:50.162] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:65:0) after 1m0s
[04:01:50.165] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:65:0)
[04:01:50.165] Timer tick, asking Buildbucket for the build status
[04:01:50.824] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:50.824] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:66:0) after 1m0s
[04:02:50.873] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:66:0)
[04:02:50.873] Timer tick, asking Buildbucket for the build status
[04:02:51.002] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:51.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:67:0) after 1m0s
[04:03:51.087] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:67:0)
[04:03:51.087] Timer tick, asking Buildbucket for the build status
[04:03:51.207] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:51.207] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:68:0) after 1m0s
[04:04:51.312] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:68:0)
[04:04:51.312] Timer tick, asking Buildbucket for the build status
[04:04:51.793] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:51.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:69:0) after 1m0s
[04:05:51.965] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:69:0)
[04:05:51.965] Timer tick, asking Buildbucket for the build status
[04:05:52.132] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:52.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:70:0) after 1m0s
[04:06:52.214] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:70:0)
[04:06:52.214] Timer tick, asking Buildbucket for the build status
[04:06:52.482] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:52.482] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:71:0) after 1m0s
[04:07:52.590] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:71:0)
[04:07:52.590] Timer tick, asking Buildbucket for the build status
[04:07:52.697] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:52.697] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:72:0) after 1m0s
[04:08:52.836] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:72:0)
[04:08:52.836] Timer tick, asking Buildbucket for the build status
[04:08:53.284] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:53.284] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:73:0) after 1m0s
[04:09:53.369] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:73:0)
[04:09:53.369] Timer tick, asking Buildbucket for the build status
[04:09:53.815] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:53.815] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:74:0) after 1m0s
[04:10:53.881] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:74:0)
[04:10:53.881] Timer tick, asking Buildbucket for the build status
[04:10:53.979] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:53.979] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:75:0) after 1m0s
[04:11:54.009] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:75:0)
[04:11:54.009] Timer tick, asking Buildbucket for the build status
[04:11:54.163] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:54.163] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:76:0) after 1m0s
[04:12:54.186] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:76:0)
[04:12:54.186] Timer tick, asking Buildbucket for the build status
[04:12:54.346] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:54.346] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:77:0) after 1m0s
[04:13:54.402] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:77:0)
[04:13:54.402] Timer tick, asking Buildbucket for the build status
[04:13:54.547] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:54.547] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:78:0) after 1m0s
[04:14:54.566] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:78:0)
[04:14:54.566] Timer tick, asking Buildbucket for the build status
[04:14:54.669] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:54.669] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:79:0) after 1m0s
[04:15:54.715] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:79:0)
[04:15:54.715] Timer tick, asking Buildbucket for the build status
[04:15:54.832] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:54.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:80:0) after 1m0s
[04:16:54.833] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:80:0)
[04:16:54.833] Timer tick, asking Buildbucket for the build status
[04:16:55.003] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:55.004] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:81:0) after 1m0s
[04:17:55.039] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:81:0)
[04:17:55.039] Timer tick, asking Buildbucket for the build status
[04:17:55.220] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:55.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:82:0) after 1m0s
[04:18:55.277] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:82:0)
[04:18:55.277] Timer tick, asking Buildbucket for the build status
[04:18:55.416] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:55.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:83:0) after 1m0s
[04:19:55.517] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:83:0)
[04:19:55.517] Timer tick, asking Buildbucket for the build status
[04:19:55.638] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:55.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:84:0) after 1m0s
[04:20:55.713] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:84:0)
[04:20:55.713] Timer tick, asking Buildbucket for the build status
[04:20:55.900] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:55.900] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:85:0) after 1m0s
[04:21:56.025] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:85:0)
[04:21:56.025] Timer tick, asking Buildbucket for the build status
[04:21:56.126] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:56.126] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:86:0) after 1m0s
[04:22:56.179] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:86:0)
[04:22:56.179] Timer tick, asking Buildbucket for the build status
[04:22:56.271] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:56.271] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:87:0) after 1m0s
[04:23:56.361] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:87:0)
[04:23:56.361] Timer tick, asking Buildbucket for the build status
[04:23:56.818] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:56.818] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:88:0) after 1m0s
[04:24:56.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:88:0)
[04:24:56.847] Timer tick, asking Buildbucket for the build status
[04:24:56.938] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:56.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:89:0) after 1m0s
[04:25:56.956] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:89:0)
[04:25:56.956] Timer tick, asking Buildbucket for the build status
[04:25:57.028] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:57.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:90:0) after 1m0s
[04:26:57.093] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:90:0)
[04:26:57.093] Timer tick, asking Buildbucket for the build status
[04:26:57.289] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:57.289] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:91:0) after 1m0s
[04:27:57.320] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:91:0)
[04:27:57.320] Timer tick, asking Buildbucket for the build status
[04:27:57.403] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:57.403] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:92:0) after 1m0s
[04:28:57.456] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:92:0)
[04:28:57.456] Timer tick, asking Buildbucket for the build status
[04:28:57.643] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:57.643] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:93:0) after 1m0s
[04:29:57.687] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:93:0)
[04:29:57.687] Timer tick, asking Buildbucket for the build status
[04:29:57.827] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:57.827] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:94:0) after 1m0s
[04:30:57.840] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:94:0)
[04:30:57.840] Timer tick, asking Buildbucket for the build status
[04:30:57.937] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:57.937] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:95:0) after 1m0s
[04:31:58.007] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:95:0)
[04:31:58.007] Timer tick, asking Buildbucket for the build status
[04:31:58.088] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:58.088] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:96:0) after 1m0s
[04:32:58.348] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:96:0)
[04:32:58.348] Timer tick, asking Buildbucket for the build status
[04:32:58.448] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:58.448] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:97:0) after 1m0s
[04:33:58.531] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:97:0)
[04:33:58.531] Timer tick, asking Buildbucket for the build status
[04:33:59.945] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:59.945] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:98:0) after 1m0s
[04:35:00.049] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:98:0)
[04:35:00.049] Timer tick, asking Buildbucket for the build status
[04:35:00.456] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:00.456] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:99:0) after 1m0s
[04:36:00.505] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:99:0)
[04:36:00.505] Timer tick, asking Buildbucket for the build status
[04:36:00.630] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:00.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:100:0) after 1m0s
[04:37:00.819] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:100:0)
[04:37:00.819] Timer tick, asking Buildbucket for the build status
[04:37:01.304] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:01.304] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:101:0) after 1m0s
[04:38:01.708] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:101:0)
[04:38:01.708] Timer tick, asking Buildbucket for the build status
[04:38:01.771] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:01.772] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:102:0) after 1m0s
[04:39:01.855] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:102:0)
[04:39:01.855] Timer tick, asking Buildbucket for the build status
[04:39:02.054] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:02.054] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:103:0) after 1m0s
[04:40:02.291] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:103:0)
[04:40:02.291] Timer tick, asking Buildbucket for the build status
[04:40:02.388] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:02.388] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:104:0) after 1m0s
[04:41:02.511] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:104:0)
[04:41:02.511] Timer tick, asking Buildbucket for the build status
[04:41:02.914] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:02.914] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:105:0) after 1m0s
[04:42:03.063] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:105:0)
[04:42:03.063] Timer tick, asking Buildbucket for the build status
[04:42:03.159] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:03.159] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:106:0) after 1m0s
[04:43:03.248] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:106:0)
[04:43:03.248] Timer tick, asking Buildbucket for the build status
[04:43:03.407] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:03.407] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:107:0) after 1m0s
[04:44:03.544] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:107:0)
[04:44:03.544] Timer tick, asking Buildbucket for the build status
[04:44:03.699] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:03.699] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:108:0) after 1m0s
[04:45:03.791] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:108:0)
[04:45:03.791] Timer tick, asking Buildbucket for the build status
[04:45:04.269] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:04.269] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:109:0) after 1m0s
[04:46:04.367] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:109:0)
[04:46:04.367] Timer tick, asking Buildbucket for the build status
[04:46:04.488] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:04.488] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:110:0) after 1m0s
[04:47:04.606] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:110:0)
[04:47:04.606] Timer tick, asking Buildbucket for the build status
[04:47:04.913] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:04.913] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:111:0) after 1m0s
[04:48:04.964] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:111:0)
[04:48:04.964] Timer tick, asking Buildbucket for the build status
[04:48:05.077] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:05.077] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:112:0) after 1m0s
[04:49:05.218] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:112:0)
[04:49:05.218] Timer tick, asking Buildbucket for the build status
[04:49:05.830] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:05.831] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:113:0) after 1m0s
[04:50:05.959] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:113:0)
[04:50:05.959] Timer tick, asking Buildbucket for the build status
[04:50:06.048] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:06.048] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:114:0) after 1m0s
[04:51:06.370] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:114:0)
[04:51:06.370] Timer tick, asking Buildbucket for the build status
[04:51:06.882] Build 8863147264568416512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:06.882] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9028577326547517760:115:0) after 1m0s
[04:51:41.628] Received PubSub notification, asking Buildbucket for the build status
[04:51:41.754] Build 8863147264568416512: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:51:41.754] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605847898911421",
"created_by": "project:chromium",
"created_ts": "1605841113462511",
"failure_reason": "BUILD_FAILURE",
"id": "8863147264568416512",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"86c3def7d09629f4021f263ec16d54d5beff12d9\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- RecordingServiceBrowserTest.RecordingInterruptedOnCaptureLocked\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"86c3def7d09629f4021f263ec16d54d5beff12d9\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-25-37bk\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 21391, \"got_angle_revision\": \"46107d3ece07f5cd1946b1013f69c110c0229a93\", \"got_dawn_revision\": \"1a14e7e0244114fe737caf46e3a2690355aceafe\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"86c3def7d09629f4021f263ec16d54d5beff12d9\", \"got_revision_cp\": \"refs/heads/master@{#829494}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"de9e649438ed3cc2ecf5c30ebf08f033d01270e6\", \"got_v8_revision\": \"94b2c943f7f37e0450e7c5d029fbd22a9d7f6946\", \"got_v8_revision_cp\": \"refs/heads/8.9.14@{#1}\", \"got_webrtc_revision\": \"afaca889d46b2c493de34a8f0771c89dea17bb1a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32614}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-dbg\", \"tryserver.chromium.chromiumos:linux-chromeos-compile-dbg\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"86c3def7d09629f4021f263ec16d54d5beff12d9\", \"swarm_hashes_refs/heads/master(at){#829494}_without_patch\": {\"absl_hardening_tests\": \"b0ff26a8b39d98a6262c99a0ec5f1cf48892aafd\", \"accessibility_unittests\": \"7372361707ffa5c32f6ce6783170f1b3d7be1c11\", \"angle_unittests\": \"e93aae7092564a3eb0313c1c565e589674f35ede\", \"app_list_unittests\": \"86bcb86e3c2d8a0f0ee94914aefdebf94b6e8c14\", \"app_shell_unittests\": \"5db90a788cf390bd5196baebec6b5e8a6bc2bf08\", \"ash_unittests\": \"04df1ab58e3f55007329a9ee8e2a494972535b8c\", \"aura_unittests\": \"4da4a6face91e5984e4d607d886721c65d6ff824\", \"base_unittests\": \"148340798afc52c392f23e933ff41c10eb2443ad\", \"base_util_unittests\": \"46f214296bea92948e6bc59be542acaa610db1bb\", \"blink_common_unittests\": \"7f3dc892515a8180bd7762ac2fecf9741542175f\", \"blink_fuzzer_unittests\": \"07ec3056fb85dd2c0b17290c7d851f548e956ad8\", \"blink_heap_unittests\": \"4a9b13c012f3d38a892f28e2eaf8c55f805b0312\", \"blink_platform_unittests\": \"6447f7a67a7aeb26e8409bb1f9acf436ac8e5766\", \"blink_unittests\": \"e51147ebea1c9790cef4bc669cc98cf47f1b289c\", \"boringssl_crypto_tests\": \"b1e014521df54adf9270e0a4bce0eff46bcc8da1\", \"boringssl_ssl_tests\": \"30c658df511f053d82e59aac4afd08af00de5762\", \"browser_tests\": \"537db6629971cd3d7679cd069d0581fab95887a2\", \"capture_unittests\": \"f474b0fd333e3dafd17656f5911e39d52483d951\", \"cast_unittests\": \"250fcc06459687854ef2b400cfd1762d26e37152\", \"cc_unittests\": \"f17361ed8772dec3eb94532ecc960c3b45b12799\", \"chrome_app_unittests\": \"22f569366ea4cdf604d0a48f004430f3056b903a\", \"chromedriver_unittests\": \"3a6aabdcedb8023093ca3072b3082495765b8105\", \"chromeos_components_unittests\": \"fc77baac9c5841ab66eac506a5aff734dcef1f74\", \"chromeos_unittests\": \"290cd6e40289b1091fdaf0a7c8658c29dda28739\", \"components_browsertests\": \"9c216a2d26b36cf584c6bab0ab6ae9b0e11c8bc7\", \"components_unittests\": \"93e3d97cdde2ec8d8cf433476354a5521b6eec8f\", \"compositor_unittests\": \"761519f131a159f023d72f751ddde53b96214312\", \"content_browsertests\": \"fe4605fdf5be0a675f96e189bd45c9cb8b636a58\", \"content_unittests\": \"116ec19bdf990098e0e4e63351be2eed15c7e087\", \"crashpad_tests\": \"51ce5178009a94fc08532aef84d3ae88cec90c1e\", \"crypto_unittests\": \"7fc1dd02e2778b5dec5f6ab486738f0b648f8856\", \"dbus_unittests\": \"52df17ccf42ccab933d541fb617a3707c1563233\", \"device_unittests\": \"ef75888d4ebed216f73eda01f9530f735378455f\", \"display_unittests\": \"27340eec8eddeaff1f4c0f75319086648861dcac\", \"events_unittests\": \"e039c8f8619fdd0624417835f7cfbd11a0c26d42\", \"exo_unittests\": \"a1d7ea56df0973e83fad7d810012a9917a01ae67\", \"extensions_browsertests\": \"8cc869d26bbe6668d30650318aa24512aa167986\", \"extensions_unittests\": \"20c762d41f00c28bc1aa898d78f76c9d99c68504\", \"filesystem_service_unittests\": \"efb9039f7e8c25ebe544ee711d64af8e45e14776\", \"gcm_unit_tests\": \"abb3c0cde41488c1cb446601ea46e6f5051bf2b8\", \"gfx_unittests\": \"3320df5a55fa1bceaf297e16b20438145f52d954\", \"gin_unittests\": \"36346058ac32630e22d24119deafe5715bd96bb1\", \"gl_unittests_ozone\": \"d7c5fd0e23ba9e74e2fef4c9f8bb7ca08ed1bda6\", \"google_apis_unittests\": \"d49a6f3f8469bff62ba7321409271fc98efa32fc\", \"gpu_unittests\": \"b37173c02641b16b93f6414fc45b6a7b3039dea6\", \"gwp_asan_unittests\": \"5c2b69524199bae3c69550d0d0013edbc725796e\", \"interactive_ui_tests\": \"58210b69066c3679b6f0a047722f5c4e4141e6ee\", \"ipc_tests\": \"2384f2c7a9c025337f539272af771fbcacb3a941\", \"jingle_unittests\": \"2e477a1e387a51053e1a702d2346117057040fc4\", \"keyboard_unittests\": \"a709d05fab1de0aab276dd652cfdf706b0ea8985\", \"latency_unittests\": \"e68cc0c20989c560e770ace34b9d333276fb58f5\", \"libjingle_xmpp_unittests\": \"3b2a3d82b8d486d65cec175172d61782dc591dcb\", \"liburlpattern_unittests\": \"86664b603df73ba59539ebf5b9051df54de55f5f\", \"media_blink_unittests\": \"b725cf34765a032a8174da390110d4bb3e3186d0\", \"media_unittests\": \"733e6b8169bc9280259ffcaf757d94704a32f1cf\", \"message_center_unittests\": \"70b293f3558819d0c61b527434eb1e4d014b926a\", \"midi_unittests\": \"763ad906901f91eaefc27c76c384ded015ccbdd8\", \"mojo_core_unittests\": \"6e8f60870430e54288b8c7bfad8c2eaec1dbacd4\", \"mojo_unittests\": \"b2e045234a05b00a0c1de6bd13a43d910d063055\", \"nacl_helper_nonsfi_unittests\": \"ebe15de9796ba12ea6c3f7ac68aa5ba2b75fba97\", \"nacl_loader_unittests\": \"cd1fd7e25c188fe388c4f7dcfa3d4a1d3a3d7509\", \"native_theme_unittests\": \"1efd6da7383ef1b015e9818ee79f161cda4cbb34\", \"net_unittests\": \"1138687ff1af4834f57d555bd9ec83f1ff538ca3\", \"ozone_gl_unittests\": \"df0702635272d0545da949cce732fd085a1eda81\", \"ozone_unittests\": \"7cda5a07ebf7cb3653c2827be152c3d01cc9f80b\", \"ozone_x11_unittests\": \"abeb550d64577f4246d2542dbcb2fb2303c803ec\", \"pdf_unittests\": \"2f66db7e633dec3472756bee3b2d2b7c743323ea\", \"perfetto_unittests\": \"c0ca9ed25ea701224411bdccffbcb77916ea351b\", \"ppapi_unittests\": \"202f9e7f34519c6eade9453d7b16ea1bd48fb169\", \"printing_unittests\": \"7eb60e15bbf1b16f125e678881deec756d662295\", \"remoting_unittests\": \"ea11076a79db76a0cc8c3ea1cd34f446cac8a38d\", \"sandbox_linux_unittests\": \"738f327bef8274af2dfd976d33a68edeabadd3a6\", \"service_manager_unittests\": \"55ce8224a5bea02d6258701f119eccbf73da0e73\", \"services_unittests\": \"fc6c6ae339eaef2b64a8cc450472c80927e04645\", \"shell_dialogs_unittests\": \"01dfd7900333e908511cec9a053bda2fb735811d\", \"skia_unittests\": \"d59da7e4ef9f57adf58e47eccb580e4db008d3df\", \"snapshot_unittests\": \"a32adf1cb02f48d87a2d6cbf655f2e9f2257a5a2\", \"sql_unittests\": \"05d91de4e186f65e1a19c05ca04ff55d8e485aa6\", \"storage_unittests\": \"e9e07213d4014fd3770994b30b7ad3456eb58426\", \"sync_integration_tests\": \"f78aaaaf0fc45a43b9a3ff474e0cb22bd4101ed5\", \"traffic_annotation_auditor_unittests\": \"683cdbe9284aee86d822e175ca0433bac65350b2\", \"ui_base_unittests\": \"bd1714d407b087b756115c30b2f7b3c1fecdbbbb\", \"ui_chromeos_unittests\": \"232305921fd49c1dd01e2043bfd838922c8d735b\", \"ui_touch_selection_unittests\": \"77291afa8753972722fb6bad8334d936cb4f0c9a\", \"unit_tests\": \"6637a4fedde9b0557ecd40202ec7f50ff2015f8a\", \"url_unittests\": \"5454bf7427a74456174b19258b123a785d475b8f\", \"usage_time_limit_unittests\": \"f5fdcf08f6f3f05e59b67476f843baa07b061186\", \"views_unittests\": \"a9cd847032af24f7996913be8446e0a67cde1d90\", \"viz_unittests\": \"804d92c096683064ab532935b67b5c9b0c22ebd6\", \"wm_unittests\": \"810c3e2284aab0add7d00408271c30b0bb96e9a9\", \"wtf_unittests\": \"9fff29402441f203e83caf481b37d44446c4cc16\", \"zlib_unittests\": \"e132b8394b7702cf6f211327b07566f79a785bcf\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_093d924ecb526dae9a1dcf9db4725f8944eec2b4c0f2ddc6c2ac4a04802f7f15_v2\", \"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_afc8749136e067b5c6411dbdfbab6620ae3d4779be0275b01641a900d0501e10_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-25-37bk\"], \"image\": [\"chrome-xenial-20111600-a937a23cefb\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5637-4d8b832\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- RecordingServiceBrowserTest.RecordingInterruptedOnCaptureLocked\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605841287418339",
"status": "COMPLETED",
"status_changed_ts": "1605841288016991",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/21391",
"builder:linux-chromeos-dbg",
"buildset:commit/git/86c3def7d09629f4021f263ec16d54d5beff12d9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/86c3def7d09629f4021f263ec16d54d5beff12d9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028577326547517760",
"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/8863147264568416512/+/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:4ffc685daf15f810",
"user_agent:luci-scheduler"
],
"updated_ts": "1605847899679997",
"url": "https://ci.chromium.org/b/8863147264568416512",
"utcnow_ts": "1605847901743478"
}
}
[04:51:41.754] Invocation finished in 1h53m9.562899366s with status FAILED