[22:33:32.341] New invocation is queued and will start shortly
[22:33:33.404] Starting the invocation (attempt 1)
[22:33:33.440] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:33:33.440] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:33:33.440] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027869223035733712",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a9c3ffc6390a8d5852530c1259d1bf5721e9b23c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9027869223035733712",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/a9c3ffc6390a8d5852530c1259d1bf5721e9b23c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a9c3ffc6390a8d5852530c1259d1bf5721e9b23c",
"gitiles_ref:refs/heads/master"
]
}
[22:33:34.051] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606516413548842",
"id": "8862439161105364640",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a9c3ffc6390a8d5852530c1259d1bf5721e9b23c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606516413938545",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43255",
"builder:linux-chromeos-rel",
"buildset:commit/git/a9c3ffc6390a8d5852530c1259d1bf5721e9b23c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a9c3ffc6390a8d5852530c1259d1bf5721e9b23c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027869223035733712",
"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/8862439161105364640/+/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": "1606516413938677",
"url": "https://ci.chromium.org/b/8862439161105364640",
"utcnow_ts": "1606516414043836"
}
}
[22:33:34.051] Task URL: https://ci.chromium.org/b/8862439161105364640
[22:33:34.051] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:2:0) after 1m0s
[22:33:52.500] Received PubSub notification, asking Buildbucket for the build status
[22:33:52.571] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:36.369] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:2:0)
[22:34:36.369] Timer tick, asking Buildbucket for the build status
[22:34:37.418] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:37.418] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:4:0) after 1m0s
[22:35:37.710] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:4:0)
[22:35:37.710] Timer tick, asking Buildbucket for the build status
[22:35:38.048] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:38.048] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:5:0) after 1m0s
[22:36:38.160] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:5:0)
[22:36:38.160] Timer tick, asking Buildbucket for the build status
[22:36:40.676] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:40.676] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:6:0) after 1m0s
[22:37:41.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:6:0)
[22:37:41.224] Timer tick, asking Buildbucket for the build status
[22:37:41.621] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:41.621] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:7:0) after 1m0s
[22:38:41.739] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:7:0)
[22:38:41.739] Timer tick, asking Buildbucket for the build status
[22:38:41.845] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:41.845] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:8:0) after 1m0s
[22:39:41.993] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:8:0)
[22:39:41.993] Timer tick, asking Buildbucket for the build status
[22:39:42.129] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:42.129] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:9:0) after 1m0s
[22:40:42.196] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:9:0)
[22:40:42.196] Timer tick, asking Buildbucket for the build status
[22:40:42.266] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:42.266] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:10:0) after 1m0s
[22:41:42.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:10:0)
[22:41:42.305] Timer tick, asking Buildbucket for the build status
[22:41:42.382] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:42.383] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:11:0) after 1m0s
[22:42:42.401] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:11:0)
[22:42:42.401] Timer tick, asking Buildbucket for the build status
[22:42:42.523] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:42.524] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:12:0) after 1m0s
[22:43:42.543] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:12:0)
[22:43:42.543] Timer tick, asking Buildbucket for the build status
[22:43:42.728] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:42.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:13:0) after 1m0s
[22:44:42.744] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:13:0)
[22:44:42.744] Timer tick, asking Buildbucket for the build status
[22:44:42.920] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:42.920] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:14:0) after 1m0s
[22:45:42.924] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:14:0)
[22:45:42.924] Timer tick, asking Buildbucket for the build status
[22:45:42.989] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:42.989] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:15:0) after 1m0s
[22:46:43.005] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:15:0)
[22:46:43.005] Timer tick, asking Buildbucket for the build status
[22:46:43.098] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:43.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:16:0) after 1m0s
[22:47:43.103] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:16:0)
[22:47:43.103] Timer tick, asking Buildbucket for the build status
[22:47:43.199] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:43.200] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:17:0) after 1m0s
[22:48:43.215] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:17:0)
[22:48:43.215] Timer tick, asking Buildbucket for the build status
[22:48:43.289] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:43.289] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:18:0) after 1m0s
[22:49:43.310] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:18:0)
[22:49:43.310] Timer tick, asking Buildbucket for the build status
[22:49:43.413] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:43.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:19:0) after 1m0s
[22:50:43.423] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:19:0)
[22:50:43.423] Timer tick, asking Buildbucket for the build status
[22:50:43.521] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:43.521] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:20:0) after 1m0s
[22:51:43.617] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:20:0)
[22:51:43.617] Timer tick, asking Buildbucket for the build status
[22:51:43.677] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:43.677] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:21:0) after 1m0s
[22:52:43.674] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:21:0)
[22:52:43.674] Timer tick, asking Buildbucket for the build status
[22:52:43.761] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:43.761] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:22:0) after 1m0s
[22:53:43.768] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:22:0)
[22:53:43.768] Timer tick, asking Buildbucket for the build status
[22:53:44.122] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:44.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:23:0) after 1m0s
[22:54:44.163] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:23:0)
[22:54:44.163] Timer tick, asking Buildbucket for the build status
[22:54:44.524] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:44.524] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:24:0) after 1m0s
[22:55:44.613] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:24:0)
[22:55:44.613] Timer tick, asking Buildbucket for the build status
[22:55:44.678] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:44.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:25:0) after 1m0s
[22:56:44.698] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:25:0)
[22:56:44.698] Timer tick, asking Buildbucket for the build status
[22:56:44.756] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:44.756] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:26:0) after 1m0s
[22:57:44.764] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:26:0)
[22:57:44.764] Timer tick, asking Buildbucket for the build status
[22:57:44.862] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:44.862] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:27:0) after 1m0s
[22:58:44.911] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:27:0)
[22:58:44.911] Timer tick, asking Buildbucket for the build status
[22:58:44.985] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:44.985] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:28:0) after 1m0s
[22:59:45.035] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:28:0)
[22:59:45.035] Timer tick, asking Buildbucket for the build status
[22:59:45.137] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:45.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:29:0) after 1m0s
[23:00:45.258] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:29:0)
[23:00:45.258] Timer tick, asking Buildbucket for the build status
[23:00:45.335] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:45.335] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:30:0) after 1m0s
[23:01:45.325] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:30:0)
[23:01:45.325] Timer tick, asking Buildbucket for the build status
[23:01:45.381] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:45.381] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:31:0) after 1m0s
[23:02:45.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:31:0)
[23:02:45.424] Timer tick, asking Buildbucket for the build status
[23:02:45.486] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:45.486] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:32:0) after 1m0s
[23:03:45.603] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:32:0)
[23:03:45.603] Timer tick, asking Buildbucket for the build status
[23:03:45.714] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:45.714] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:33:0) after 1m0s
[23:04:45.827] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:33:0)
[23:04:45.827] Timer tick, asking Buildbucket for the build status
[23:04:45.974] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:45.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:34:0) after 1m0s
[23:05:46.120] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:34:0)
[23:05:46.120] Timer tick, asking Buildbucket for the build status
[23:05:46.188] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:46.188] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:35:0) after 1m0s
[23:06:46.307] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:35:0)
[23:06:46.307] Timer tick, asking Buildbucket for the build status
[23:06:46.359] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:46.359] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:36:0) after 1m0s
[23:07:46.343] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:36:0)
[23:07:46.343] Timer tick, asking Buildbucket for the build status
[23:07:46.399] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:46.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:37:0) after 1m0s
[23:08:46.459] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:37:0)
[23:08:46.459] Timer tick, asking Buildbucket for the build status
[23:08:47.090] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:47.090] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:38:0) after 1m0s
[23:09:47.134] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:38:0)
[23:09:47.134] Timer tick, asking Buildbucket for the build status
[23:09:49.230] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:49.230] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:39:0) after 1m0s
[23:10:49.349] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:39:0)
[23:10:49.349] Timer tick, asking Buildbucket for the build status
[23:10:49.413] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:49.413] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:40:0) after 1m0s
[23:11:49.455] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:40:0)
[23:11:49.455] Timer tick, asking Buildbucket for the build status
[23:11:49.510] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:49.510] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:41:0) after 1m0s
[23:12:49.527] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:41:0)
[23:12:49.527] Timer tick, asking Buildbucket for the build status
[23:12:49.649] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:49.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:42:0) after 1m0s
[23:13:49.668] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:42:0)
[23:13:49.668] Timer tick, asking Buildbucket for the build status
[23:13:49.777] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:49.777] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:43:0) after 1m0s
[23:14:49.798] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:43:0)
[23:14:49.798] Timer tick, asking Buildbucket for the build status
[23:14:49.927] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:49.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:44:0) after 1m0s
[23:15:49.929] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:44:0)
[23:15:49.929] Timer tick, asking Buildbucket for the build status
[23:15:50.040] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:50.040] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:45:0) after 1m0s
[23:16:50.091] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:45:0)
[23:16:50.091] Timer tick, asking Buildbucket for the build status
[23:16:50.344] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:50.344] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:46:0) after 1m0s
[23:17:50.362] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:46:0)
[23:17:50.362] Timer tick, asking Buildbucket for the build status
[23:17:50.842] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:50.842] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:47:0) after 1m0s
[23:18:50.867] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:47:0)
[23:18:50.867] Timer tick, asking Buildbucket for the build status
[23:18:51.081] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:51.081] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:48:0) after 1m0s
[23:19:51.097] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:48:0)
[23:19:51.097] Timer tick, asking Buildbucket for the build status
[23:19:51.261] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:51.261] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:49:0) after 1m0s
[23:20:51.251] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:49:0)
[23:20:51.251] Timer tick, asking Buildbucket for the build status
[23:20:51.574] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:51.574] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:50:0) after 1m0s
[23:21:51.602] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:50:0)
[23:21:51.602] Timer tick, asking Buildbucket for the build status
[23:21:52.117] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:52.117] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:51:0) after 1m0s
[23:22:52.181] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:51:0)
[23:22:52.181] Timer tick, asking Buildbucket for the build status
[23:22:52.342] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:52.342] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:52:0) after 1m0s
[23:23:52.361] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:52:0)
[23:23:52.361] Timer tick, asking Buildbucket for the build status
[23:23:53.469] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:53.469] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:53:0) after 1m0s
[23:24:53.478] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:53:0)
[23:24:53.478] Timer tick, asking Buildbucket for the build status
[23:24:53.613] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:53.613] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:54:0) after 1m0s
[23:25:53.666] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:54:0)
[23:25:53.666] Timer tick, asking Buildbucket for the build status
[23:25:53.763] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:53.763] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:55:0) after 1m0s
[23:26:53.782] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:55:0)
[23:26:53.782] Timer tick, asking Buildbucket for the build status
[23:26:53.951] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:53.951] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:56:0) after 1m0s
[23:27:53.981] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:56:0)
[23:27:53.981] Timer tick, asking Buildbucket for the build status
[23:27:54.259] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:54.259] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:57:0) after 1m0s
[23:28:54.280] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:57:0)
[23:28:54.280] Timer tick, asking Buildbucket for the build status
[23:28:54.678] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:54.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:58:0) after 1m0s
[23:29:54.679] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:58:0)
[23:29:54.679] Timer tick, asking Buildbucket for the build status
[23:29:54.786] Build 8862439161105364640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:54.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027869223035733712:59:0) after 1m0s
[23:30:20.648] Received PubSub notification, asking Buildbucket for the build status
[23:30:20.921] Build 8862439161105364640: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:30:20.921] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606519819173278",
"created_by": "project:chromium",
"created_ts": "1606516413548842",
"id": "8862439161105364640",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a9c3ffc6390a8d5852530c1259d1bf5721e9b23c\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"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\": \"a9c3ffc6390a8d5852530c1259d1bf5721e9b23c\", \"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-73-0e77\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 43255, \"got_angle_revision\": \"a5da17cd4e19649288b9634e7fff32455403b856\", \"got_dawn_revision\": \"e96986149080ca37e82788c907f439bc070aaa7b\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"a9c3ffc6390a8d5852530c1259d1bf5721e9b23c\", \"got_revision_cp\": \"refs/heads/master@{#831651}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"9dff6a3bb5950edd890e5141f2d3d2f3b4ef351e\", \"got_v8_revision\": \"8d791670ba247239ef6ce9091183f11f8c5f0825\", \"got_v8_revision_cp\": \"refs/heads/8.9.65@{#1}\", \"got_webrtc_revision\": \"bcca3b08a90fccaa6dd8d86f6079f0ca0553ab4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32715}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a9c3ffc6390a8d5852530c1259d1bf5721e9b23c\", \"swarm_hashes_refs/heads/master(at){#831651}_without_patch\": {\"absl_hardening_tests\": \"9b0d71c9b3791f07bd2c9890aff17f76f18cad2b\", \"accessibility_unittests\": \"9b964f43bbc17e680dcd28513e82e611f947867d\", \"angle_unittests\": \"53c38fee96f1928c1f36ad37823f78a386aff098\", \"app_list_unittests\": \"617f9032acfa018540ac80c6e050781562304eea\", \"app_shell_unittests\": \"06de6e49a3cd7e3e15b22090b0093a16422275c3\", \"ash_unittests\": \"01d8e3bf103e9292a7334efff202b7c2649e943b\", \"aura_unittests\": \"581e7c6213316760bdffab849fdf915c4a195034\", \"base_unittests\": \"b374808513ed8cc4283c0938f899ceec45fd7d4a\", \"base_util_unittests\": \"378a9569e4f52a040106b8d857b7b237feef94f4\", \"blink_common_unittests\": \"17c60e2769e9fc3c7bdf55946096386e1284425f\", \"blink_fuzzer_unittests\": \"2e83ec2dc8c1c1b8616127778c41d625c5cb0f27\", \"blink_heap_unittests\": \"e296ea90fb22c601ab1e70d757e7d0ad30634d33\", \"blink_platform_unittests\": \"ffa55c76fee2a1054495037165e837bf1c7a631a\", \"blink_unittests\": \"294968964f05501d3df5cfb2839fddafc8130e10\", \"boringssl_crypto_tests\": \"db7f48dfe70d407137512476f673348f2d3f034b\", \"boringssl_ssl_tests\": \"7cbfa3d76c74cdb9511b790053719f1116f5fc9d\", \"browser_tests\": \"17c052d10afad0d3aad528c386570d3872326c8a\", \"capture_unittests\": \"dd079a557e21e2206e0a1f52e9b5e9828b0aafe1\", \"cast_unittests\": \"0f526086917472f0be41fbaeab2a510daea39a61\", \"cc_unittests\": \"3ecba9fd31e2ab0b4162b89b3bf90363b1f343ec\", \"chrome_app_unittests\": \"5cdc925f2d8cf9a9a8fea7097165a824e02a268c\", \"chromedriver_unittests\": \"d26eebd171b227fe1ccf18cc9c5ba60edc93246f\", \"chromeos_components_unittests\": \"d4693348f0ecc3a9f4aad66b32cedb6a77b35a9b\", \"chromeos_unittests\": \"cda891edc2d257abacc61b344baa1b9dcbfea737\", \"components_browsertests\": \"d111ec4373c48b2123f24eb8a4ee344fc72ab746\", \"components_unittests\": \"03c31001e01765600cf39ab9c3559542465837ac\", \"compositor_unittests\": \"8802c0ec4a0fac2f29f300041f985600689d1682\", \"content_browsertests\": \"25f2872e21f77af9f2dcced4d12fab7ed4657824\", \"content_unittests\": \"8f447b4a16b97d3c2c828090be22f99894a02ab8\", \"crashpad_tests\": \"abcb1ed7390474088ff0eade371ff14600ee99f0\", \"crypto_unittests\": \"bc9c8e0fd65732ccd5cc5c4855bec821c39b2ca4\", \"dbus_unittests\": \"2a5ed01058a7763341eab95946fbf0fec3fa7ff3\", \"device_unittests\": \"f4204593a4ad9e716aefc12900b3cd62700ebdb6\", \"display_unittests\": \"a7c843abd962a3e9bd01c01aaf4a3dd45630cc03\", \"events_unittests\": \"001970af320162dd1402fc9f55f4029a758f7deb\", \"exo_unittests\": \"66813e229072a83a01c53b7a319e531cd1273e42\", \"extensions_browsertests\": \"939cf385a48a1f05d0ba3200556e86571589a67c\", \"extensions_unittests\": \"5df8443549b785f0ec30b71454b7de2f313b8353\", \"filesystem_service_unittests\": \"71203ffa5b0a08385376af80320f85273693eb3a\", \"gcm_unit_tests\": \"059d7d86eb65611ace4be87b0d158c688dfabe35\", \"gfx_unittests\": \"158df54df67aa436466f28a4577abd24baa73df8\", \"gin_unittests\": \"656d70106d4c4652ec17778118cc755007fcc7aa\", \"gl_unittests_ozone\": \"15c3cf035452ce7acd4eab651c820679bba94502\", \"google_apis_unittests\": \"96eb19e21a3de1740fc9b4e7f5b43ba0a3399d86\", \"gpu_unittests\": \"bce4379c4eb4f4db240a7af7b10b31195254c9a5\", \"gwp_asan_unittests\": \"67ab34b02a73c36638873bbb9d0d5fefe648a965\", \"interactive_ui_tests\": \"697e6d727472940f6cd782e8fe47bd5ec8e519e7\", \"ipc_tests\": \"e64ba4977430a36b9d1ed36ff37e60b79c7fe4b3\", \"jingle_unittests\": \"2f8ef16df62d4eb7dc283ed7f8225b81059e08f0\", \"keyboard_unittests\": \"fe67b82828e119a508ed353292b7e69ae491b524\", \"latency_unittests\": \"e58b53eaf3ac52d7d781e44d2cdbe603dcf2a8fd\", \"libjingle_xmpp_unittests\": \"b19ca2676c0f62f9274600d5335b95e779a90cdc\", \"liburlpattern_unittests\": \"8f8f2b0c5ffaa0be0895dca21fca4516b904f118\", \"media_blink_unittests\": \"65daa3b0f2bdd3bcdf73a6eca72110a463bd6893\", \"media_unittests\": \"c6d1590637480c5eacd48f5d176504b7f6ed2175\", \"message_center_unittests\": \"4aa64709526be80b596a5e7054b70d7ef87e5d70\", \"midi_unittests\": \"0061f1b71d6ef663640156ff8af6149718adaf37\", \"mojo_core_unittests\": \"0c7de22a3e7c4f490b8f7abb88c262b9bcca861e\", \"mojo_unittests\": \"50a851f4da4d7eb88c60e9ed399c47c58216767b\", \"nacl_helper_nonsfi_unittests\": \"b76370972dcd5e86c5b2f69d7772f5be583a1a6a\", \"nacl_loader_unittests\": \"9cdcb9e3382c679757aa88862e857cdbc802810a\", \"native_theme_unittests\": \"03e6f889534c35c6ba4843a1f7425bebedaf9845\", \"net_unittests\": \"0cfe30330de595f3a177baf91793585f1ba96a7e\", \"ozone_gl_unittests\": \"6885e28009e1f48a81a65e7eaf603209e4d1e5ac\", \"ozone_unittests\": \"2903f3bc6ad06954bcf5ddec4e11c508ce4e8350\", \"ozone_x11_unittests\": \"09673d1007fdeb764beebc068e3a459835e53b2c\", \"pdf_unittests\": \"bf578cd332320f626f10d36ea4b40e498fd5b84e\", \"perfetto_unittests\": \"d80096f35bcf109df4cadd4544a3c9e19dcc6f2b\", \"ppapi_unittests\": \"8a671c1c4bad6551010157b30ad09d5a2888bb77\", \"printing_unittests\": \"2796c8b820b8aa639cf1165494221c4df058f985\", \"remoting_unittests\": \"9c2e8d0e99101b4cda2a15524eb9c58a40ca93c0\", \"sandbox_linux_unittests\": \"0ed5deced38d61cf258b0243af07710178b6032d\", \"service_manager_unittests\": \"fab9975f1d4b731f2afc5bcab9da9344339b9d45\", \"services_unittests\": \"aadb6b83cbf0f18572cd744e28cfaa98b66a6478\", \"shell_dialogs_unittests\": \"43e3062ced6170c04d3574e5e4418c4ebe1065a5\", \"skia_unittests\": \"41a73804bce2925d12c14f86d5655adae6c2db67\", \"snapshot_unittests\": \"a4445fa9476d3ccbc38f2d03db05022624d821ae\", \"sql_unittests\": \"88f30eeb8a26080ab8616621b7b1e76bae095371\", \"storage_unittests\": \"7403394aff1e416c9d85adc6c6f20f6948094455\", \"sync_integration_tests\": \"d06ceea841b90d8f4723e2edd2d3c72a897322de\", \"traffic_annotation_auditor_unittests\": \"f934ce441e09366750ef63dd476d2d577afb9688\", \"ui_base_unittests\": \"3fba9b594e12fb617329e3b03eebeb8f872a1bd1\", \"ui_chromeos_unittests\": \"ab5191adc937ccfdfc083134c7e215611ccdda8e\", \"ui_touch_selection_unittests\": \"48413626450fdbc58a982ae2fa4a37aa9b5b7ba9\", \"unit_tests\": \"09e56e7206c177fc5694ce0020e74b4a1464ac26\", \"url_unittests\": \"84b5450da94fcba7cd8d2df6b6809418ebed116b\", \"usage_time_limit_unittests\": \"0cab644f4e0558057971d54cdf5b7d4eb9fb53a3\", \"views_unittests\": \"b2761037bc46a1ab7053419e02ab674ff1a76da5\", \"viz_unittests\": \"a538067a921f0d2bab9df9d24fdd95e4c9efffeb\", \"wayland_client_perftests\": \"056a062ceb6275e8af21e11e60da710dff134e7c\", \"wm_unittests\": \"1a2f96d35e81f8804b09699ee86a8b6812674144\", \"wtf_unittests\": \"97638e750bd4e896ef143626faf6a2161acfb44f\", \"zlib_unittests\": \"f444b41f9947dcebaaae517413aacd223b52fc88\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_40238b720ec4feb1f57c327a3b72021d0e440ababc8fca32fd370c36be82ffcf_v2\", \"builder_7f66e4b9df9a4fde5765030780da31d05e9d75a20736158540de9973fc71d08c_v2\", \"builder_94dc7d486ab1bafc5bfb11010ef18f0cf20e8e07ae86b58c09f57ba294c280ac_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_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-73-0e77\"], \"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\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1606516431739204",
"status": "COMPLETED",
"status_changed_ts": "1606519819607934",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43255",
"builder:linux-chromeos-rel",
"buildset:commit/git/a9c3ffc6390a8d5852530c1259d1bf5721e9b23c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a9c3ffc6390a8d5852530c1259d1bf5721e9b23c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027869223035733712",
"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/8862439161105364640/+/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:5024a8c743562c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1606519819612667",
"url": "https://ci.chromium.org/b/8862439161105364640",
"utcnow_ts": "1606519820906115"
}
}
[23:30:20.921] Invocation finished in 56m48.596967495s with status SUCCEEDED