[03:43:33.379] New invocation is queued and will start shortly
[03:43:34.775] Starting the invocation (attempt 1)
[03:43:34.825] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:43:34.825] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:43:34.825] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021145542976441312",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b41839cf2c6d2b1deb33d42f3cede4f6ddc98d5b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9021145542976441312",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/b41839cf2c6d2b1deb33d42f3cede4f6ddc98d5b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b41839cf2c6d2b1deb33d42f3cede4f6ddc98d5b",
"gitiles_ref:refs/heads/master"
]
}
[03:43:36.533] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612928615005736",
"id": "8855715480551228208",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b41839cf2c6d2b1deb33d42f3cede4f6ddc98d5b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612928615398073",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45288",
"builder:linux-chromeos-rel",
"buildset:commit/git/b41839cf2c6d2b1deb33d42f3cede4f6ddc98d5b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b41839cf2c6d2b1deb33d42f3cede4f6ddc98d5b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021145542976441312",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8855715480551228208/+/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": "1612928615398186",
"url": "https://ci.chromium.org/b/8855715480551228208",
"utcnow_ts": "1612928616522179"
}
}
[03:43:36.533] Task URL: https://ci.chromium.org/b/8855715480551228208
[03:43:36.533] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:2:0) after 1m0s
[03:43:40.240] Received PubSub notification, asking Buildbucket for the build status
[03:43:42.784] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:36.716] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:2:0)
[03:44:36.716] Timer tick, asking Buildbucket for the build status
[03:44:37.244] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:37.244] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:4:0) after 1m0s
[03:45:37.344] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:4:0)
[03:45:37.344] Timer tick, asking Buildbucket for the build status
[03:45:37.416] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:37.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:5:0) after 1m0s
[03:46:37.457] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:5:0)
[03:46:37.457] Timer tick, asking Buildbucket for the build status
[03:46:37.554] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:37.554] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:6:0) after 1m0s
[03:47:37.790] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:6:0)
[03:47:37.790] Timer tick, asking Buildbucket for the build status
[03:47:37.928] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:37.928] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:7:0) after 1m0s
[03:48:38.047] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:7:0)
[03:48:38.047] Timer tick, asking Buildbucket for the build status
[03:48:38.245] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:38.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:8:0) after 1m0s
[03:49:38.722] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:8:0)
[03:49:38.722] Timer tick, asking Buildbucket for the build status
[03:49:38.876] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:38.876] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:9:0) after 1m0s
[03:50:38.984] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:9:0)
[03:50:38.984] Timer tick, asking Buildbucket for the build status
[03:50:39.210] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:39.210] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:10:0) after 1m0s
[03:51:39.255] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:10:0)
[03:51:39.255] Timer tick, asking Buildbucket for the build status
[03:51:39.630] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:39.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:11:0) after 1m0s
[03:52:40.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:11:0)
[03:52:40.281] Timer tick, asking Buildbucket for the build status
[03:52:40.384] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:40.384] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:12:0) after 1m0s
[03:53:40.639] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:12:0)
[03:53:40.639] Timer tick, asking Buildbucket for the build status
[03:53:40.764] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:40.764] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:13:0) after 1m0s
[03:54:41.309] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:13:0)
[03:54:41.309] Timer tick, asking Buildbucket for the build status
[03:54:41.380] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:41.380] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:14:0) after 1m0s
[03:55:41.608] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:14:0)
[03:55:41.608] Timer tick, asking Buildbucket for the build status
[03:55:41.716] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:41.716] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:15:0) after 1m0s
[03:56:42.070] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:15:0)
[03:56:42.070] Timer tick, asking Buildbucket for the build status
[03:56:42.198] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:42.198] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:16:0) after 1m0s
[03:57:42.216] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:16:0)
[03:57:42.216] Timer tick, asking Buildbucket for the build status
[03:57:42.290] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:42.290] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:17:0) after 1m0s
[03:58:42.316] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:17:0)
[03:58:42.316] Timer tick, asking Buildbucket for the build status
[03:58:42.529] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:42.529] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:18:0) after 1m0s
[03:59:42.573] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:18:0)
[03:59:42.573] Timer tick, asking Buildbucket for the build status
[03:59:42.769] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:42.769] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:19:0) after 1m0s
[04:00:42.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:19:0)
[04:00:42.806] Timer tick, asking Buildbucket for the build status
[04:00:42.928] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:42.928] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:20:0) after 1m0s
[04:01:42.994] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:20:0)
[04:01:42.994] Timer tick, asking Buildbucket for the build status
[04:01:43.076] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:43.076] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:21:0) after 1m0s
[04:02:43.192] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:21:0)
[04:02:43.192] Timer tick, asking Buildbucket for the build status
[04:02:43.254] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:43.254] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:22:0) after 1m0s
[04:03:43.350] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:22:0)
[04:03:43.350] Timer tick, asking Buildbucket for the build status
[04:03:43.482] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:43.482] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:23:0) after 1m0s
[04:04:43.531] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:23:0)
[04:04:43.531] Timer tick, asking Buildbucket for the build status
[04:04:43.649] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:43.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:24:0) after 1m0s
[04:05:43.672] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:24:0)
[04:05:43.672] Timer tick, asking Buildbucket for the build status
[04:05:43.755] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:43.755] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:25:0) after 1m0s
[04:06:43.786] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:25:0)
[04:06:43.786] Timer tick, asking Buildbucket for the build status
[04:06:43.871] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:43.871] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:26:0) after 1m0s
[04:07:43.933] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:26:0)
[04:07:43.933] Timer tick, asking Buildbucket for the build status
[04:07:44.032] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:44.032] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:27:0) after 1m0s
[04:08:44.056] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:27:0)
[04:08:44.056] Timer tick, asking Buildbucket for the build status
[04:08:44.150] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:44.150] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:28:0) after 1m0s
[04:09:44.231] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:28:0)
[04:09:44.231] Timer tick, asking Buildbucket for the build status
[04:09:45.583] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:45.583] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:29:0) after 1m0s
[04:10:45.686] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:29:0)
[04:10:45.686] Timer tick, asking Buildbucket for the build status
[04:10:45.913] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:45.913] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:30:0) after 1m0s
[04:11:46.011] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:30:0)
[04:11:46.011] Timer tick, asking Buildbucket for the build status
[04:11:46.077] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:46.077] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:31:0) after 1m0s
[04:12:46.153] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:31:0)
[04:12:46.153] Timer tick, asking Buildbucket for the build status
[04:12:46.214] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:46.214] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:32:0) after 1m0s
[04:13:46.295] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:32:0)
[04:13:46.295] Timer tick, asking Buildbucket for the build status
[04:13:46.410] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:46.411] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:33:0) after 1m0s
[04:14:46.434] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:33:0)
[04:14:46.434] Timer tick, asking Buildbucket for the build status
[04:14:46.571] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:46.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:34:0) after 1m0s
[04:15:46.635] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:34:0)
[04:15:46.635] Timer tick, asking Buildbucket for the build status
[04:15:46.731] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:46.731] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:35:0) after 1m0s
[04:16:46.773] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:35:0)
[04:16:46.773] Timer tick, asking Buildbucket for the build status
[04:16:46.845] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:46.845] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:36:0) after 1m0s
[04:17:46.887] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:36:0)
[04:17:46.887] Timer tick, asking Buildbucket for the build status
[04:17:46.950] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:46.950] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:37:0) after 1m0s
[04:18:47.020] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:37:0)
[04:18:47.020] Timer tick, asking Buildbucket for the build status
[04:18:47.147] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:47.147] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:38:0) after 1m0s
[04:19:47.184] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:38:0)
[04:19:47.184] Timer tick, asking Buildbucket for the build status
[04:19:47.493] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:47.493] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:39:0) after 1m0s
[04:20:47.517] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:39:0)
[04:20:47.517] Timer tick, asking Buildbucket for the build status
[04:20:48.495] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:48.495] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:40:0) after 1m0s
[04:21:48.539] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:40:0)
[04:21:48.539] Timer tick, asking Buildbucket for the build status
[04:21:48.708] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:48.708] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:41:0) after 1m0s
[04:22:48.727] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:41:0)
[04:22:48.727] Timer tick, asking Buildbucket for the build status
[04:22:48.917] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:48.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:42:0) after 1m0s
[04:23:48.967] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:42:0)
[04:23:48.967] Timer tick, asking Buildbucket for the build status
[04:23:49.137] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:49.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:43:0) after 1m0s
[04:24:49.206] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:43:0)
[04:24:49.206] Timer tick, asking Buildbucket for the build status
[04:24:49.278] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:49.278] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:44:0) after 1m0s
[04:25:49.403] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:44:0)
[04:25:49.403] Timer tick, asking Buildbucket for the build status
[04:25:49.614] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:49.614] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:45:0) after 1m0s
[04:26:49.635] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:45:0)
[04:26:49.635] Timer tick, asking Buildbucket for the build status
[04:26:49.731] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:49.731] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:46:0) after 1m0s
[04:27:49.807] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:46:0)
[04:27:49.807] Timer tick, asking Buildbucket for the build status
[04:27:50.115] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:50.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:47:0) after 1m0s
[04:28:50.105] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:47:0)
[04:28:50.105] Timer tick, asking Buildbucket for the build status
[04:28:50.217] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:50.217] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:48:0) after 1m0s
[04:29:50.240] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:48:0)
[04:29:50.240] Timer tick, asking Buildbucket for the build status
[04:29:50.390] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:50.390] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:49:0) after 1m0s
[04:30:50.437] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:49:0)
[04:30:50.437] Timer tick, asking Buildbucket for the build status
[04:30:50.729] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:50.729] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:50:0) after 1m0s
[04:31:50.751] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:50:0)
[04:31:50.751] Timer tick, asking Buildbucket for the build status
[04:31:50.874] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:50.874] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:51:0) after 1m0s
[04:32:50.933] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:51:0)
[04:32:50.933] Timer tick, asking Buildbucket for the build status
[04:32:51.043] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:51.043] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:52:0) after 1m0s
[04:33:51.094] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:52:0)
[04:33:51.094] Timer tick, asking Buildbucket for the build status
[04:33:51.217] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:51.217] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:53:0) after 1m0s
[04:34:51.243] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:53:0)
[04:34:51.243] Timer tick, asking Buildbucket for the build status
[04:34:51.448] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:51.448] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:54:0) after 1m0s
[04:35:51.521] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:54:0)
[04:35:51.521] Timer tick, asking Buildbucket for the build status
[04:35:51.616] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:51.616] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:55:0) after 1m0s
[04:36:51.702] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:55:0)
[04:36:51.702] Timer tick, asking Buildbucket for the build status
[04:36:52.108] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:52.108] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:56:0) after 1m0s
[04:37:52.152] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:56:0)
[04:37:52.152] Timer tick, asking Buildbucket for the build status
[04:37:52.294] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:52.294] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:57:0) after 1m0s
[04:38:52.308] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:57:0)
[04:38:52.308] Timer tick, asking Buildbucket for the build status
[04:38:52.414] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:52.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:58:0) after 1m0s
[04:39:52.427] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:58:0)
[04:39:52.427] Timer tick, asking Buildbucket for the build status
[04:39:52.532] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:52.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:59:0) after 1m0s
[04:40:52.551] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:59:0)
[04:40:52.551] Timer tick, asking Buildbucket for the build status
[04:40:52.716] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:52.716] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:60:0) after 1m0s
[04:41:52.789] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:60:0)
[04:41:52.789] Timer tick, asking Buildbucket for the build status
[04:41:52.958] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:52.958] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:61:0) after 1m0s
[04:42:52.959] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:61:0)
[04:42:52.959] Timer tick, asking Buildbucket for the build status
[04:42:53.152] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:53.152] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:62:0) after 1m0s
[04:43:53.176] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:62:0)
[04:43:53.176] Timer tick, asking Buildbucket for the build status
[04:43:53.324] Build 8855715480551228208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:53.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021145542976441312:63:0) after 1m0s
[04:44:17.154] Received PubSub notification, asking Buildbucket for the build status
[04:44:17.239] Build 8855715480551228208: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:44:17.239] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612932254321033",
"created_by": "project:chromium",
"created_ts": "1612928615005736",
"failure_reason": "BUILD_FAILURE",
"id": "8855715480551228208",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b41839cf2c6d2b1deb33d42f3cede4f6ddc98d5b\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**ash_unittests** failed because of:\\n\\n- LayerUtilTest.CopyContentToExistingLayer\"}, \"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\": \"b41839cf2c6d2b1deb33d42f3cede4f6ddc98d5b\", \"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-central1-b-8-162-831p\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45288, \"got_angle_revision\": \"aca0dc35f482ab6de0bf3be11fffcf15a0d53076\", \"got_dawn_revision\": \"b6b0090b501799fb98f8ca3cae1d87bd991635ed\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"b41839cf2c6d2b1deb33d42f3cede4f6ddc98d5b\", \"got_revision_cp\": \"refs/heads/master@{#852456}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"94805df16b6f458269eba42e630b1fbf9eb8bbf6\", \"got_v8_revision\": \"c369eee9f5827733bc6784843744c4ac8a9bea0b\", \"got_v8_revision_cp\": \"refs/heads/9.0.147@{#1}\", \"got_webrtc_revision\": \"54ea85c2cd3a2cb1b8f440a71aa9676a9b88135c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33190}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-inverse-fieldtrials-fyi-rel\", \"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b41839cf2c6d2b1deb33d42f3cede4f6ddc98d5b\", \"swarm_hashes_refs/heads/master(at){#852456}_without_patch\": {\"absl_hardening_tests\": \"a07ca3e7391af625b843b4172e45f58a139b3908\", \"accessibility_unittests\": \"f5eb4de738db8bcb9d1875de76173f7872fab91d\", \"angle_unittests\": \"4ccf71f8a9b9209bc46f2e9547bac06a2c07278f\", \"app_list_unittests\": \"935a51162abb8596cd30ba26757403a7816736b6\", \"app_shell_unittests\": \"89e6be189144c5c7e6bd7c0992a5738e581e2aff\", \"ash_unittests\": \"24d4600f3119bab5bb1f636eae46526aa0de6878\", \"aura_unittests\": \"a5717a9001bad176e0f87fa3c85f9457c1c50701\", \"base_unittests\": \"0fd38beb23f59ac46189513ca08b9c262297c091\", \"base_util_unittests\": \"9528987b442b87e45f4e8db16f0079946de8e785\", \"blink_common_unittests\": \"8c3a35d0a1e1bbdb13eb4b5a65b17463182043d8\", \"blink_fuzzer_unittests\": \"9443778fae6b7d52ca3c79755dcc790cd3cc8d5b\", \"blink_heap_unittests\": \"d0aa82813f0d4ca77e55ae244a796ed3faf56daa\", \"blink_platform_unittests\": \"f0a7f77bb626b8faf935a43b6394ad4279e02962\", \"blink_unittests\": \"2251d68e8cde2f82426d3ea68cc1a535425a0f65\", \"boringssl_crypto_tests\": \"839a626a81dc0fc99497e0a91aa1d3cb47f94596\", \"boringssl_ssl_tests\": \"a0364f75bad3db4526dfe781aaa8e685475dfb57\", \"browser_tests\": \"6b9df5d20872fc75d87516a679a4d5c2fe8bd47a\", \"capture_unittests\": \"4b97af5983fa248efc82a572719480ab01a8e0d1\", \"cast_unittests\": \"636e0050d8a2c1dbca6e03dcc674542e58a40711\", \"cc_unittests\": \"7c54060472c8e1631090fa65b8c642c8d87943c2\", \"chrome_app_unittests\": \"20066931187184f5cd5b82f98c7519001970bef0\", \"chromedriver_unittests\": \"f4bc72310413aeabd6fe1a4285d01c3c9ffacf3a\", \"chromeos_components_unittests\": \"8883b46fb7bcb22bde360d87e87ac02241647d57\", \"chromeos_unittests\": \"790c5ab490884793f66b4be7e9cdaf5830b2660b\", \"components_browsertests\": \"8ddf48ece9175fc6bc6df8ce014b4494929571ec\", \"components_unittests\": \"7c99882fab147632b8b2470b7dffb83258a6cd7c\", \"compositor_unittests\": \"d7d87f045545c51cf492e9ac4450d44ef8606161\", \"content_browsertests\": \"24f5944757b73b317784ba0a5b7004b87ed5a1b0\", \"content_unittests\": \"97431c556a27f8cd956b71698b218b9d1ff84a53\", \"crashpad_tests\": \"0da5368fcf3d03e7895f95b669ca1405036e4958\", \"crypto_unittests\": \"7bbe66e2184c2070ed3250cca82139bdca469b51\", \"dbus_unittests\": \"b951e37499dd073b3f0126ac7a86ce6a0f2bf85a\", \"device_unittests\": \"51c89da3ddcad41dca9c81e7063e1b742e8027b9\", \"display_unittests\": \"d90bb56de19abf607a4e241bc9c48fcbe5d51c69\", \"events_unittests\": \"f7045d9affb29d1d93760f6ae4ff3cfa39fbfa98\", \"exo_unittests\": \"73e536e4dcda27d86153cfcf783958cdfa150354\", \"extensions_browsertests\": \"c20fda9024590a9e5ed11acb4327fdb33f2e5fb6\", \"extensions_unittests\": \"ccfbbb1537f0cb7aa2f7359ff57b4a4e570f89a8\", \"filesystem_service_unittests\": \"b8d1b7e8835217991b082db6facb95793ac7f3e4\", \"gcm_unit_tests\": \"5dad71666acaa243974301257d49ae85d881355a\", \"gfx_unittests\": \"930141fe7f9e0760bfcf7758ae224b2774a3b8d5\", \"gin_unittests\": \"53b97a137c230e691e7000fdd1e10e8bf3e216f7\", \"gl_unittests_ozone\": \"d19d92054efbde0782ec3d27c8f9fc75c0a3b0ac\", \"google_apis_unittests\": \"17d9021586033fc4c7210a48e0e4556cd2db23ad\", \"gpu_unittests\": \"25d43eaf42fce58f2d7a2abfee4f06c31228bece\", \"gwp_asan_unittests\": \"ba71c889f3667f714dc5aee47c21ee77636be16e\", \"interactive_ui_tests\": \"72ef8353956e8c113c75a8da9c46e41a8e5ef489\", \"ipc_tests\": \"b2cdef11b645af61c901fcc665a575f846e72cfa\", \"jingle_unittests\": \"77eb5fd4a88ce7bb3704370a7a79f7833ada509b\", \"keyboard_unittests\": \"3e18b3a44390fe00b9fd72888baf80538da9976a\", \"latency_unittests\": \"4b1458e0bb84b6f0a43caf29d0e116b257f22c49\", \"libjingle_xmpp_unittests\": \"51c1dadcb5808ec7846badfce642bc2623f5c0cb\", \"liburlpattern_unittests\": \"6cd836efcb085ed0223e8f26c22ac9dadfe18508\", \"media_blink_unittests\": \"eac252107e1e59218097b7d9113c2867865123a6\", \"media_unittests\": \"c331b87061e91131875e7a3ca5fbcfe394caeaff\", \"message_center_unittests\": \"936e820a7f454e40661fdae530f45d61f34b5f75\", \"midi_unittests\": \"f31e5b74eab62a58e14b713f7d811343b424d424\", \"mojo_core_unittests\": \"40a487a11c87fc9770a16da32c592ee04a1efb0f\", \"mojo_unittests\": \"ef0825e26aee325657badd14e4ee5b97e67da7b9\", \"nacl_helper_nonsfi_unittests\": \"8fc3d152470e8776338d0692f1e50026635af444\", \"nacl_loader_unittests\": \"a6b04bba1f0daef770b1b7443faa713cadddf995\", \"native_theme_unittests\": \"b09ab668fbf6389a5890de48b246ed6b3da3307d\", \"net_unittests\": \"1ad4111007e75525791728ea0ef4ad2cac24bd95\", \"ozone_gl_unittests\": \"3fa2657fe56102df6257b0a160ded4a357ac58b7\", \"ozone_unittests\": \"383e8ff2aa21b5da9f4a4e8b0c3acb074863b4cb\", \"ozone_x11_unittests\": \"0753c01b5ca043fca4dd1d6b4658e68606c04fb4\", \"pdf_unittests\": \"f6d7e71c759b4c3a60279962ea624b98b9a00e34\", \"perfetto_unittests\": \"1852ebc578d4f8494d90c4f94751acd15d8fb790\", \"ppapi_unittests\": \"7bf9326f9383a3e1a1410b8a0aca861f1f89c52a\", \"printing_unittests\": \"877d801b6c1eafa7ed3fbf6fc0a17d3a909cbf7c\", \"remoting_unittests\": \"ebae38a27a15147bd01b4f8e8fa172ec6aaa7087\", \"sandbox_linux_unittests\": \"65bd27c745bfaebae2f395e53ab4e548109decd5\", \"service_manager_unittests\": \"51b6f6326cada1d7559e91bb67bf40d4d26069a3\", \"services_unittests\": \"f2af3898d7f28e12fbee498d6f0002210d7ab2c7\", \"shell_dialogs_unittests\": \"e775de120ac9fa62798bfda1376af8b437974adf\", \"shell_encryption_unittests\": \"41db21b05b02e70a4d26e521d85e5550005ec305\", \"skia_unittests\": \"389cc35371f58ea776de0fb631246902bad913f4\", \"snapshot_unittests\": \"187081a247160c36841f1b7e136d725427c9eb6e\", \"sql_unittests\": \"2c6fbfa80e15f4ebce668a1f4a9a1d6a3d8c8ab8\", \"storage_unittests\": \"a84ea507528de33715b4f8ad131de73419cfac53\", \"sync_integration_tests\": \"77808ec20bcae83932e666096a9d216803aa176b\", \"traffic_annotation_auditor_unittests\": \"b3148cee370f50287d7eda2a6558e4913aa59e6e\", \"ui_base_unittests\": \"33d1cbf2e18fdad0627ec02078e20a6f0c44b044\", \"ui_chromeos_unittests\": \"4748459c29410173433c6dab1ddabfdc75e01746\", \"ui_touch_selection_unittests\": \"af9b243a6db65e70f777ae6966432f7d46b1a532\", \"unit_tests\": \"b1d393275adcd2784d4d1b0d869275dee004da52\", \"url_unittests\": \"1ca8b420dbc912e14fca65cbf4cfc00bc450d46b\", \"usage_time_limit_unittests\": \"2d5b73ee2ddcf179213172e68dc1374788fa7ed2\", \"views_unittests\": \"23adee92a2b9faa59c342db8d7e4b222271950d8\", \"viz_unittests\": \"bcc82ed007247eb45563bcd77875e9fa4d5a2dd0\", \"wayland_client_perftests\": \"ed2f29ce8e58e046989b9ebc2c3c4433bee5a01b\", \"wm_unittests\": \"bcf5d60b23339c4dd5edd37c955020dea88f6ae9\", \"wtf_unittests\": \"0a0f29a41d54acb02e7f21f8048a0e332053493e\", \"zlib_unittests\": \"09382952b2d456fa5fcc0ba3e670647ec0bc4694\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_678d8b556ed6d2ea4aa43b80debb7b547a1d6d4433ddff28e80ab170912c760d_v2\", \"builder_6ab0eeab06ceac077c4f5e67fdebd04a4013e6231a211afb401025a2c2ae11ff_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_a15c792d02d243f2865d956ab51326d361a8fcfed3c44b30d209df7676d4718c_v2\", \"builder_bcc6b3e5c0858529730294da730af8ea17f2c5c79b8b0dc7af4e722a8412ac73_v2\", \"builder_d7cc7c59327c05146a034898ae78722818cd22c61c9435c4c4348e98bea5a132_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-central1-b-8-162-831p\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"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\": [\"5685-1877293\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**ash_unittests** failed because of:\\n\\n- LayerUtilTest.CopyContentToExistingLayer\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612928618949583",
"status": "COMPLETED",
"status_changed_ts": "1612928619915758",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45288",
"builder:linux-chromeos-rel",
"buildset:commit/git/b41839cf2c6d2b1deb33d42f3cede4f6ddc98d5b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b41839cf2c6d2b1deb33d42f3cede4f6ddc98d5b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021145542976441312",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8855715480551228208/+/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:51a2db1115395710",
"user_agent:luci-scheduler"
],
"updated_ts": "1612932256426401",
"url": "https://ci.chromium.org/b/8855715480551228208",
"utcnow_ts": "1612932257231688"
}
}
[04:44:17.239] Invocation finished in 1h0m43.931497758s with status FAILED