[02:11:50.545] New invocation is queued and will start shortly
[02:11:51.709] Starting the invocation (attempt 1)
[02:11:51.776] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:11:51.776] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:11:51.776] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027221309808657376",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"aa2fc3367f377c8f64117c02fd397f9dbfe57bf8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9027221309808657376",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/aa2fc3367f377c8f64117c02fd397f9dbfe57bf8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/aa2fc3367f377c8f64117c02fd397f9dbfe57bf8",
"gitiles_ref:refs/heads/master"
]
}
[02:11:52.546] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607134311888860",
"id": "8861791247735663168",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"aa2fc3367f377c8f64117c02fd397f9dbfe57bf8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607134312383595",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43458",
"builder:linux-chromeos-rel",
"buildset:commit/git/aa2fc3367f377c8f64117c02fd397f9dbfe57bf8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/aa2fc3367f377c8f64117c02fd397f9dbfe57bf8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027221309808657376",
"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/8861791247735663168/+/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": "1607134312383765",
"url": "https://ci.chromium.org/b/8861791247735663168",
"utcnow_ts": "1607134312534376"
}
}
[02:11:52.546] Task URL: https://ci.chromium.org/b/8861791247735663168
[02:11:52.546] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:2:0) after 1m0s
[02:12:17.944] Received PubSub notification, asking Buildbucket for the build status
[02:12:18.074] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:52.566] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:2:0)
[02:12:52.566] Timer tick, asking Buildbucket for the build status
[02:12:52.664] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:52.664] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:4:0) after 1m0s
[02:13:52.695] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:4:0)
[02:13:52.695] Timer tick, asking Buildbucket for the build status
[02:13:52.901] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:52.901] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:5:0) after 1m0s
[02:14:52.994] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:5:0)
[02:14:52.994] Timer tick, asking Buildbucket for the build status
[02:14:53.075] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:53.075] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:6:0) after 1m0s
[02:15:53.142] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:6:0)
[02:15:53.142] Timer tick, asking Buildbucket for the build status
[02:15:53.278] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:53.278] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:7:0) after 1m0s
[02:16:53.607] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:7:0)
[02:16:53.607] Timer tick, asking Buildbucket for the build status
[02:16:53.922] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:53.922] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:8:0) after 1m0s
[02:17:53.937] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:8:0)
[02:17:53.937] Timer tick, asking Buildbucket for the build status
[02:17:54.140] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:54.140] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:9:0) after 1m0s
[02:18:54.131] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:9:0)
[02:18:54.131] Timer tick, asking Buildbucket for the build status
[02:18:54.283] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:54.283] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:10:0) after 1m0s
[02:19:54.360] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:10:0)
[02:19:54.360] Timer tick, asking Buildbucket for the build status
[02:19:54.418] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:54.418] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:11:0) after 1m0s
[02:20:54.542] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:11:0)
[02:20:54.542] Timer tick, asking Buildbucket for the build status
[02:20:54.606] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:54.606] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:12:0) after 1m0s
[02:21:54.756] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:12:0)
[02:21:54.756] Timer tick, asking Buildbucket for the build status
[02:21:54.945] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:54.945] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:13:0) after 1m0s
[02:22:54.987] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:13:0)
[02:22:54.987] Timer tick, asking Buildbucket for the build status
[02:22:55.044] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:55.044] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:14:0) after 1m0s
[02:23:55.246] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:14:0)
[02:23:55.246] Timer tick, asking Buildbucket for the build status
[02:23:55.616] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:55.616] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:15:0) after 1m0s
[02:24:55.719] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:15:0)
[02:24:55.719] Timer tick, asking Buildbucket for the build status
[02:24:55.800] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:55.800] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:16:0) after 1m0s
[02:25:55.885] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:16:0)
[02:25:55.885] Timer tick, asking Buildbucket for the build status
[02:25:56.731] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:56.731] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:17:0) after 1m0s
[02:26:56.792] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:17:0)
[02:26:56.792] Timer tick, asking Buildbucket for the build status
[02:26:57.691] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:57.691] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:18:0) after 1m0s
[02:27:57.739] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:18:0)
[02:27:57.739] Timer tick, asking Buildbucket for the build status
[02:27:57.813] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:57.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:19:0) after 1m0s
[02:28:57.877] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:19:0)
[02:28:57.877] Timer tick, asking Buildbucket for the build status
[02:28:57.994] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:57.994] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:20:0) after 1m0s
[02:29:58.021] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:20:0)
[02:29:58.022] Timer tick, asking Buildbucket for the build status
[02:29:58.119] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:58.119] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:21:0) after 1m0s
[02:30:58.275] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:21:0)
[02:30:58.275] Timer tick, asking Buildbucket for the build status
[02:30:58.399] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:58.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:22:0) after 1m0s
[02:31:58.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:22:0)
[02:31:58.371] Timer tick, asking Buildbucket for the build status
[02:31:58.593] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:58.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:23:0) after 1m0s
[02:32:58.801] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:23:0)
[02:32:58.801] Timer tick, asking Buildbucket for the build status
[02:32:58.907] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:58.907] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:24:0) after 1m0s
[02:33:58.939] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:24:0)
[02:33:58.939] Timer tick, asking Buildbucket for the build status
[02:33:59.072] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:59.073] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:25:0) after 1m0s
[02:34:59.204] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:25:0)
[02:34:59.204] Timer tick, asking Buildbucket for the build status
[02:34:59.294] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:59.294] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:26:0) after 1m0s
[02:35:59.355] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:26:0)
[02:35:59.355] Timer tick, asking Buildbucket for the build status
[02:35:59.422] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:59.422] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:27:0) after 1m0s
[02:36:59.523] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:27:0)
[02:36:59.523] Timer tick, asking Buildbucket for the build status
[02:36:59.718] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:59.718] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:28:0) after 1m0s
[02:37:59.768] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:28:0)
[02:37:59.768] Timer tick, asking Buildbucket for the build status
[02:37:59.889] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:59.889] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:29:0) after 1m0s
[02:38:59.967] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:29:0)
[02:38:59.967] Timer tick, asking Buildbucket for the build status
[02:39:00.135] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:00.135] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:30:0) after 1m0s
[02:40:00.227] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:30:0)
[02:40:00.227] Timer tick, asking Buildbucket for the build status
[02:40:00.292] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:00.292] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:31:0) after 1m0s
[02:41:01.674] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:31:0)
[02:41:01.674] Timer tick, asking Buildbucket for the build status
[02:41:01.780] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:01.780] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:32:0) after 1m0s
[02:42:01.803] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:32:0)
[02:42:01.803] Timer tick, asking Buildbucket for the build status
[02:42:01.905] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:01.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:33:0) after 1m0s
[02:43:02.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:33:0)
[02:43:02.281] Timer tick, asking Buildbucket for the build status
[02:43:02.377] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:02.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:34:0) after 1m0s
[02:44:03.375] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:34:0)
[02:44:03.375] Timer tick, asking Buildbucket for the build status
[02:44:03.513] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:03.513] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:35:0) after 1m0s
[02:45:03.591] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:35:0)
[02:45:03.591] Timer tick, asking Buildbucket for the build status
[02:45:03.665] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:03.665] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:36:0) after 1m0s
[02:46:03.691] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:36:0)
[02:46:03.691] Timer tick, asking Buildbucket for the build status
[02:46:03.768] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:03.768] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:37:0) after 1m0s
[02:47:03.781] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:37:0)
[02:47:03.781] Timer tick, asking Buildbucket for the build status
[02:47:04.139] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:04.139] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:38:0) after 1m0s
[02:48:04.183] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:38:0)
[02:48:04.183] Timer tick, asking Buildbucket for the build status
[02:48:04.315] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:04.315] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:39:0) after 1m0s
[02:49:04.349] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:39:0)
[02:49:04.349] Timer tick, asking Buildbucket for the build status
[02:49:04.603] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:04.603] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:40:0) after 1m0s
[02:50:04.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:40:0)
[02:50:04.622] Timer tick, asking Buildbucket for the build status
[02:50:04.700] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:04.700] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:41:0) after 1m0s
[02:51:04.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:41:0)
[02:51:04.779] Timer tick, asking Buildbucket for the build status
[02:51:05.028] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:05.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:42:0) after 1m0s
[02:52:05.029] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:42:0)
[02:52:05.029] Timer tick, asking Buildbucket for the build status
[02:52:05.550] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:05.550] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:43:0) after 1m0s
[02:53:05.596] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:43:0)
[02:53:05.596] Timer tick, asking Buildbucket for the build status
[02:53:05.671] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:05.671] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:44:0) after 1m0s
[02:54:05.695] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:44:0)
[02:54:05.695] Timer tick, asking Buildbucket for the build status
[02:54:05.777] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:05.777] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:45:0) after 1m0s
[02:55:05.823] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:45:0)
[02:55:05.823] Timer tick, asking Buildbucket for the build status
[02:55:07.544] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:07.545] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:46:0) after 1m0s
[02:56:07.552] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:46:0)
[02:56:07.552] Timer tick, asking Buildbucket for the build status
[02:56:07.766] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:07.766] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:47:0) after 1m0s
[02:57:07.809] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:47:0)
[02:57:07.809] Timer tick, asking Buildbucket for the build status
[02:57:07.934] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:07.934] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:48:0) after 1m0s
[02:58:07.987] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:48:0)
[02:58:07.987] Timer tick, asking Buildbucket for the build status
[02:58:08.112] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:08.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:49:0) after 1m0s
[02:59:08.186] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:49:0)
[02:59:08.186] Timer tick, asking Buildbucket for the build status
[02:59:08.704] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:08.704] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:50:0) after 1m0s
[03:00:08.748] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:50:0)
[03:00:08.748] Timer tick, asking Buildbucket for the build status
[03:00:08.949] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:08.949] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:51:0) after 1m0s
[03:01:09.067] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:51:0)
[03:01:09.068] Timer tick, asking Buildbucket for the build status
[03:01:10.038] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:10.038] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:52:0) after 1m0s
[03:02:10.052] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:52:0)
[03:02:10.052] Timer tick, asking Buildbucket for the build status
[03:02:10.299] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:10.299] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:53:0) after 1m0s
[03:03:10.323] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:53:0)
[03:03:10.323] Timer tick, asking Buildbucket for the build status
[03:03:10.514] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:10.514] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:54:0) after 1m0s
[03:04:10.585] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:54:0)
[03:04:10.585] Timer tick, asking Buildbucket for the build status
[03:04:10.853] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:10.853] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:55:0) after 1m0s
[03:05:10.880] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:55:0)
[03:05:10.880] Timer tick, asking Buildbucket for the build status
[03:05:11.066] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:11.066] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:56:0) after 1m0s
[03:06:11.186] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:56:0)
[03:06:11.186] Timer tick, asking Buildbucket for the build status
[03:06:11.328] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:11.328] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:57:0) after 1m0s
[03:07:11.451] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:57:0)
[03:07:11.451] Timer tick, asking Buildbucket for the build status
[03:07:11.688] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:11.688] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:58:0) after 1m0s
[03:08:11.754] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:58:0)
[03:08:11.754] Timer tick, asking Buildbucket for the build status
[03:08:12.330] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:12.330] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:59:0) after 1m0s
[03:09:12.420] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:59:0)
[03:09:12.420] Timer tick, asking Buildbucket for the build status
[03:09:12.963] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:12.963] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:60:0) after 1m0s
[03:10:12.994] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:60:0)
[03:10:12.994] Timer tick, asking Buildbucket for the build status
[03:10:13.135] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:13.135] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:61:0) after 1m0s
[03:11:13.335] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:61:0)
[03:11:13.335] Timer tick, asking Buildbucket for the build status
[03:11:13.830] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:13.830] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:62:0) after 1m0s
[03:12:14.062] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:62:0)
[03:12:14.063] Timer tick, asking Buildbucket for the build status
[03:12:14.196] Build 8861791247735663168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:14.196] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027221309808657376:63:0) after 1m0s
[03:13:03.797] Received PubSub notification, asking Buildbucket for the build status
[03:13:03.922] Build 8861791247735663168: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:13:03.922] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607137978810465",
"created_by": "project:chromium",
"created_ts": "1607134311888860",
"id": "8861791247735663168",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"aa2fc3367f377c8f64117c02fd397f9dbfe57bf8\"}}",
"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\": \"aa2fc3367f377c8f64117c02fd397f9dbfe57bf8\", \"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-71-x0um\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 43458, \"got_angle_revision\": \"c859c0ac81bbca2066fcaeb6efa48eece5c10103\", \"got_dawn_revision\": \"c532048062befdee8bb57d18f01d3a3c403d49de\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"aa2fc3367f377c8f64117c02fd397f9dbfe57bf8\", \"got_revision_cp\": \"refs/heads/master@{#833970}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"bae138de13ac876cbca0e30a40b89dc808e2b29d\", \"got_v8_revision\": \"61f3e61408129afc0d9e1268d3354a283ea3c881\", \"got_v8_revision_cp\": \"refs/heads/8.9.100@{#1}\", \"got_webrtc_revision\": \"6c80aebd00360ee00c84c7b14422c5fe475d8916\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32774}\", \"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\": \"aa2fc3367f377c8f64117c02fd397f9dbfe57bf8\", \"swarm_hashes_refs/heads/master(at){#833970}_without_patch\": {\"absl_hardening_tests\": \"6d06764c2431c4875220577096afaa6c709f8332\", \"accessibility_unittests\": \"7689b14a045aee83f6fd5f5bc8ee618ed2951df4\", \"angle_unittests\": \"626a9e06a78e68d30282663e1f7011adf1ad8eed\", \"app_list_unittests\": \"cfa55314e94ba82a53f65bf8eb68a779f66a7f89\", \"app_shell_unittests\": \"c09512e2d3f370073817e38b9f1cd7ae5fc84809\", \"ash_unittests\": \"112cc0658a93f30c8ca7fbd32aa5d6a8c57e9526\", \"aura_unittests\": \"031db4862ad220355cd750d1ec08a38fb8ad5376\", \"base_unittests\": \"cc3a7b82dec56717bd98ee561915f7001d85c051\", \"base_util_unittests\": \"2e8be2d69133587afc998c3423a631d6546c4b62\", \"blink_common_unittests\": \"6ecba260369cda2b1d50ad372cfb9389450cb9c6\", \"blink_fuzzer_unittests\": \"00f0f06242bd262e8f57564a4890264e4debf49e\", \"blink_heap_unittests\": \"c146a481707004261436482f9b891f1e54fb80d2\", \"blink_platform_unittests\": \"6f03eef312e238795455bdc50d6bf8e0c481e524\", \"blink_unittests\": \"cc9cfb701946a64fbadff23f197cce984a23fb0f\", \"boringssl_crypto_tests\": \"049e786d677d2c3d67cd485881ca983eb5f374ef\", \"boringssl_ssl_tests\": \"67f17125db496d440235f2b7a1d398485a22677e\", \"browser_tests\": \"e85e167f822f07ad6dc625017dcfbd64f101d7e0\", \"capture_unittests\": \"48487257fe8c19351cc8722462d8d787946663ae\", \"cast_unittests\": \"63495456c7b642251fe4f296c53211207993f2bf\", \"cc_unittests\": \"e44f12876376886950bd8620466d41f832d7f85e\", \"chrome_app_unittests\": \"fcd425e005a837468ffbaff451474166498d15a4\", \"chromedriver_unittests\": \"82555673a416a27906d7aa1a3c07c2e471cc7a75\", \"chromeos_components_unittests\": \"1074ddb8b6c325d05cff827eb0b9c5955126f97e\", \"chromeos_unittests\": \"d7beb95fdde76a9b0918055c0fe61cc15108ea1d\", \"components_browsertests\": \"91f65c66240bc9a8b4b6a435595aabcce32eba5c\", \"components_unittests\": \"feab87071f9d201ef87f3073da1c5c5781ab8b26\", \"compositor_unittests\": \"e189aa93b760a62f3a8e355a1379b2bc63b40d38\", \"content_browsertests\": \"db84287199d317d3fd9f600c48fbf637d7e338c7\", \"content_unittests\": \"d106fcdace4f4ad0b9de18e2176582ec4e8821c2\", \"crashpad_tests\": \"63be958f8cdb8566e6a62bb9dc96485914758a02\", \"crypto_unittests\": \"4a56221996fc424edd008d1874ec6b0d5f2cbd8f\", \"dbus_unittests\": \"26c9bc7318d656d92f4114c8a7e79a7104a642fe\", \"device_unittests\": \"189dd2b1b66ee372dc1020a835f4e8f84347dff4\", \"display_unittests\": \"b6b23247cbb3efb48253c524d72699ea14ac20c6\", \"events_unittests\": \"529fcd874c96244f3723672730dd56655f8404ce\", \"exo_unittests\": \"9e31a203f1321d72367f3b5b94c9411745c9d3ca\", \"extensions_browsertests\": \"c1cca275262111839e81129f9ac70ad8346b5cbe\", \"extensions_unittests\": \"905e6e6715e42ffd0a849aacbe90ed34b9dab010\", \"filesystem_service_unittests\": \"14d98e911bd890fa0e82b0931bfdddfdf6b28ab2\", \"gcm_unit_tests\": \"a36a54eaf2303fb7ff796759daf2a20e686685aa\", \"gfx_unittests\": \"8762152bf3a4d0ec1bc43f1caa8a6d596a0fdf9b\", \"gin_unittests\": \"24590cb2470635e64d58a11b90ccdc3ef9a6a8ce\", \"gl_unittests_ozone\": \"d7c96757b36b8a8b7a8d1f5463ea521aadca435b\", \"google_apis_unittests\": \"1723f0683a46c7a2397d81c2d362bfbda50afff8\", \"gpu_unittests\": \"50b3163c64d9ae30af8488b727a12b502e2c4524\", \"gwp_asan_unittests\": \"7cc6a64de41098c96d401e1cbb53c59f1dc669ec\", \"interactive_ui_tests\": \"89994a52387a7c05a6ddb7866938854fff535519\", \"ipc_tests\": \"f3dcfd406da1e3ff61da80a5e49734af54efe535\", \"jingle_unittests\": \"b75259d18cb4a34197d3422f09c3a3af4baf5060\", \"keyboard_unittests\": \"1616fbb4cdededd8b08bfce984f88852eaf0b4d9\", \"latency_unittests\": \"48478afe8b520cd35b81cf7a76f991f56dd1a169\", \"libjingle_xmpp_unittests\": \"1479ecd1c01feb17dbc2f2c1298e39c57622dc4e\", \"liburlpattern_unittests\": \"88c1d773d027f8cb642ae0b597ee9201cdc53759\", \"media_blink_unittests\": \"5d9688c289f0549488a61b160801f0d54e3d1900\", \"media_unittests\": \"381e89628bdcc4d48e5365f7bcbac4dcaa775da7\", \"message_center_unittests\": \"0b459735ca2fb77f798ced94c8464afbb2c66770\", \"midi_unittests\": \"a91db25bd493154b235041cffb681a8c456e6113\", \"mojo_core_unittests\": \"d43b3498cfe1e7d077eeaa13361fe3d972f57aea\", \"mojo_unittests\": \"5eec74afc0fe78823ec0765fadb31658038ea4a3\", \"nacl_helper_nonsfi_unittests\": \"2c1ef58341ff106449306546e51708e7885584e7\", \"nacl_loader_unittests\": \"ca93b5e599e1721d6c85ecb14b35e863e98808ee\", \"native_theme_unittests\": \"39a6f49b794ed8e1dd9750e8ff5707e2518f992a\", \"net_unittests\": \"95eac12244fd8a58fd76761cf46dad2508e99a60\", \"ozone_gl_unittests\": \"d79f5ca87384e9740d194e3a057fa1a75a95207b\", \"ozone_unittests\": \"eef3eff2a2ddf0e14db06a321eeb0ed52f712c32\", \"ozone_x11_unittests\": \"b5bb0e0e987984574e607b642ad4ad1e4f0a75b8\", \"pdf_unittests\": \"d955f8c420428ec3f21f2841cf3a153f09776e91\", \"perfetto_unittests\": \"d5e8fd00e704b1d16ddc1eda81de9c208d670a1a\", \"ppapi_unittests\": \"1ba59b2c6d02131033c6c2a744ac4f2fe9a76d5f\", \"printing_unittests\": \"eab588515d82aa534dfc68ccded40266640a64fe\", \"remoting_unittests\": \"3c4806e453d987701c78a5ed1e0a9e00e2869dc7\", \"sandbox_linux_unittests\": \"a4a3614355f5c1a2e341fac584f8f7b8a78237c8\", \"service_manager_unittests\": \"09b2cfa73b28d3d1d1a104b3e4c043494fc190c3\", \"services_unittests\": \"5694e1f9c342e509ddb65758f1190aa63231f9eb\", \"shell_dialogs_unittests\": \"5902d3530a7fa26e3c17924446060f7981ca3f88\", \"shell_encryption_unittests\": \"c83859fef7b69e7ce05ea1f25fc45534d5b3a290\", \"skia_unittests\": \"fa4e7a449f817ddf579e4b831713ad9dbf96b3b1\", \"snapshot_unittests\": \"cc182713515c8707a2cfa746b86449e222dda777\", \"sql_unittests\": \"336c6b6d609f688633e14a938137cb1bc1426a88\", \"storage_unittests\": \"ab8e4afcf08ffed03a95e25023d51a1447b254b5\", \"sync_integration_tests\": \"979bd8ccdb38c9420ec025ba23ac39a7554dc4ff\", \"traffic_annotation_auditor_unittests\": \"64bc0ca4e12ae3d73af1f1fcf079f7607b441cbf\", \"ui_base_unittests\": \"3763d9e5fd53b42a95d52210f8fe8db16c81bd94\", \"ui_chromeos_unittests\": \"bc8f346a6922117a9bc5b14d22f4131bff3668d0\", \"ui_touch_selection_unittests\": \"603fe35e1a2af3d8b64a8ea57e60561e8fd1aa55\", \"unit_tests\": \"621d03eef8cc3fe4180c2413506ecf46991b07b5\", \"url_unittests\": \"23231d68c3ccf0c19814bd5333386f7cefc1f49c\", \"usage_time_limit_unittests\": \"2e755c378a86de361f80e473b6a1cc8ec2b206a9\", \"views_unittests\": \"21bfdb965f6b5bb6b18917dd98f19957a0b34618\", \"viz_unittests\": \"efb67e483b38bd25a11be79ed52956ebd2b789ce\", \"wayland_client_perftests\": \"cb901694bdaf4f7e36b07bbefba0a5a042e8b6d3\", \"wm_unittests\": \"7e3773cd34136e8bc943cc24a06f662ac38deaab\", \"wtf_unittests\": \"47353affaf800d402d5acf86859e47949515891b\", \"zlib_unittests\": \"eaff9779b98c7fb14dd536444ca3c1a054cf45d1\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_361cbaa79261c75a92fc63a8f8c6fc117764238b3277286fa51086e6da3301df_v2\", \"builder_4107b14b7476412be0315d38f8e370eb2ac2c1b388bb8ffb59d7bdd8f08032e9_v2\", \"builder_51829829c31fbaf3768e3309e640f1ed37e0867e138bde8c875431bcab07adc2_v2\", \"builder_7d75f6999c07e42a7f7fde2313383264fbd8ed871e2008bde33b278973c1f4c8_v2\", \"builder_8afdcfef4ae6fecf44bdc59bc36ab1bd30dfc75b00b262fd5e6b3421af127659_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_9864f67f40ba0a637f020c00db69aed5c54155e2e95bfe26df83308425415c7f_v2\", \"builder_98a60cf4ba341a05f49601a94ea91d205798f47ee731869ed66ec9c0f2c54ce9_v2\", \"builder_d2010e0a6a440fb89731b061caf7d596c92723d209ffefd62489cc037db4f7a7_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-71-x0um\"], \"image\": [\"chrome-xenial-20112200-f7cdea8df03\"], \"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": "1607134336231481",
"status": "COMPLETED",
"status_changed_ts": "1607137983131710",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43458",
"builder:linux-chromeos-rel",
"buildset:commit/git/aa2fc3367f377c8f64117c02fd397f9dbfe57bf8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/aa2fc3367f377c8f64117c02fd397f9dbfe57bf8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027221309808657376",
"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/8861791247735663168/+/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:50497d018c91c010",
"user_agent:luci-scheduler"
],
"updated_ts": "1607137983141596",
"url": "https://ci.chromium.org/b/8861791247735663168",
"utcnow_ts": "1607137983909220"
}
}
[03:13:03.922] Invocation finished in 1h1m13.394723481s with status SUCCEEDED