[10:07:34.308] New invocation is queued and will start shortly
[10:07:35.387] Starting the invocation (attempt 1)
[10:07:35.442] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:07:35.442] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:07:35.442] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9036160479178448352",
"parameters_json": "{\"builder_name\":\"win-archive-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4bf6d3988344437b58061622f985da6ce825be53\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-archive-rel",
"scheduler_invocation_id:9036160479178448352",
"scheduler_job_id:chromium/win-archive-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/4bf6d3988344437b58061622f985da6ce825be53",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4bf6d3988344437b58061622f985da6ce825be53",
"gitiles_ref:refs/heads/master"
]
}
[10:07:36.055] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1598609255523522",
"id": "8870730417238548032",
"parameters_json": "{\"builder_name\": \"win-archive-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4bf6d3988344437b58061622f985da6ce825be53\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1598609255919414",
"tags": [
"build_address:luci.chromium.ci/win-archive-rel/17031",
"builder:win-archive-rel",
"buildset:commit/git/4bf6d3988344437b58061622f985da6ce825be53",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4bf6d3988344437b58061622f985da6ce825be53",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9036160479178448352",
"scheduler_job_id:chromium/win-archive-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8870730417238548032/+/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": "1598609255919534",
"url": "https://ci.chromium.org/b/8870730417238548032",
"utcnow_ts": "1598609256048903"
}
}
[10:07:36.055] Task URL: https://ci.chromium.org/b/8870730417238548032
[10:07:36.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:2:0) after 1m0s
[10:08:36.146] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:2:0)
[10:08:36.146] Timer tick, asking Buildbucket for the build status
[10:08:36.535] Build 8870730417238548032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:08:36.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:3:0) after 1m0s
[10:09:36.616] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:3:0)
[10:09:36.616] Timer tick, asking Buildbucket for the build status
[10:09:38.968] Build 8870730417238548032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:09:38.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:4:0) after 1m0s
[10:10:39.047] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:4:0)
[10:10:39.047] Timer tick, asking Buildbucket for the build status
[10:10:39.193] Build 8870730417238548032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:10:39.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:5:0) after 1m0s
[10:11:39.289] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:5:0)
[10:11:39.289] Timer tick, asking Buildbucket for the build status
[10:11:39.362] Build 8870730417238548032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:11:39.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:6:0) after 1m0s
[10:12:31.827] Received PubSub notification, asking Buildbucket for the build status
[10:12:31.923] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:39.483] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:6:0)
[10:12:39.483] Timer tick, asking Buildbucket for the build status
[10:12:39.612] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:39.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:8:0) after 1m0s
[10:13:39.651] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:8:0)
[10:13:39.651] Timer tick, asking Buildbucket for the build status
[10:13:39.757] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:39.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:9:0) after 1m0s
[10:14:39.872] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:9:0)
[10:14:39.872] Timer tick, asking Buildbucket for the build status
[10:14:40.117] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:40.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:10:0) after 1m0s
[10:15:40.181] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:10:0)
[10:15:40.181] Timer tick, asking Buildbucket for the build status
[10:15:40.251] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:40.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:11:0) after 1m0s
[10:16:40.504] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:11:0)
[10:16:40.504] Timer tick, asking Buildbucket for the build status
[10:16:40.569] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:40.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:12:0) after 1m0s
[10:17:40.660] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:12:0)
[10:17:40.660] Timer tick, asking Buildbucket for the build status
[10:17:40.788] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:40.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:13:0) after 1m0s
[10:18:40.926] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:13:0)
[10:18:40.926] Timer tick, asking Buildbucket for the build status
[10:18:40.981] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:40.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:14:0) after 1m0s
[10:19:41.175] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:14:0)
[10:19:41.175] Timer tick, asking Buildbucket for the build status
[10:19:41.231] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:41.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:15:0) after 1m0s
[10:20:41.333] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:15:0)
[10:20:41.333] Timer tick, asking Buildbucket for the build status
[10:20:41.413] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:41.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:16:0) after 1m0s
[10:21:41.507] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:16:0)
[10:21:41.507] Timer tick, asking Buildbucket for the build status
[10:21:41.796] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:41.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:17:0) after 1m0s
[10:22:41.772] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:17:0)
[10:22:41.772] Timer tick, asking Buildbucket for the build status
[10:22:41.900] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:41.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:18:0) after 1m0s
[10:23:41.998] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:18:0)
[10:23:41.998] Timer tick, asking Buildbucket for the build status
[10:23:42.321] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:42.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:19:0) after 1m0s
[10:24:42.364] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:19:0)
[10:24:42.364] Timer tick, asking Buildbucket for the build status
[10:24:42.540] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:42.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:20:0) after 1m0s
[10:25:42.619] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:20:0)
[10:25:42.619] Timer tick, asking Buildbucket for the build status
[10:25:42.884] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:42.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:21:0) after 1m0s
[10:26:42.952] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:21:0)
[10:26:42.952] Timer tick, asking Buildbucket for the build status
[10:26:43.246] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:43.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:22:0) after 1m0s
[10:27:43.274] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:22:0)
[10:27:43.274] Timer tick, asking Buildbucket for the build status
[10:27:43.383] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:43.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:23:0) after 1m0s
[10:28:43.406] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:23:0)
[10:28:43.406] Timer tick, asking Buildbucket for the build status
[10:28:43.468] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:43.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:24:0) after 1m0s
[10:29:43.520] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:24:0)
[10:29:43.520] Timer tick, asking Buildbucket for the build status
[10:29:43.624] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:43.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:25:0) after 1m0s
[10:30:43.606] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:25:0)
[10:30:43.606] Timer tick, asking Buildbucket for the build status
[10:30:43.676] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:43.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:26:0) after 1m0s
[10:31:43.724] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:26:0)
[10:31:43.724] Timer tick, asking Buildbucket for the build status
[10:31:43.894] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:43.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:27:0) after 1m0s
[10:32:43.914] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:27:0)
[10:32:43.914] Timer tick, asking Buildbucket for the build status
[10:32:44.164] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:44.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:28:0) after 1m0s
[10:33:44.196] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:28:0)
[10:33:44.196] Timer tick, asking Buildbucket for the build status
[10:33:44.296] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:44.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:29:0) after 1m0s
[10:34:44.375] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:29:0)
[10:34:44.375] Timer tick, asking Buildbucket for the build status
[10:34:44.546] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:44.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:30:0) after 1m0s
[10:35:44.562] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:30:0)
[10:35:44.562] Timer tick, asking Buildbucket for the build status
[10:35:45.288] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:45.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:31:0) after 1m0s
[10:36:45.308] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:31:0)
[10:36:45.308] Timer tick, asking Buildbucket for the build status
[10:36:45.541] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:45.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:32:0) after 1m0s
[10:37:45.602] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:32:0)
[10:37:45.602] Timer tick, asking Buildbucket for the build status
[10:37:45.806] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:45.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:33:0) after 1m0s
[10:38:45.836] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:33:0)
[10:38:45.836] Timer tick, asking Buildbucket for the build status
[10:38:45.949] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:45.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:34:0) after 1m0s
[10:39:45.950] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:34:0)
[10:39:45.950] Timer tick, asking Buildbucket for the build status
[10:39:46.128] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:46.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:35:0) after 1m0s
[10:40:46.181] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:35:0)
[10:40:46.181] Timer tick, asking Buildbucket for the build status
[10:40:46.288] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:46.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:36:0) after 1m0s
[10:41:46.406] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:36:0)
[10:41:46.406] Timer tick, asking Buildbucket for the build status
[10:41:46.485] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:46.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:37:0) after 1m0s
[10:42:46.587] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:37:0)
[10:42:46.587] Timer tick, asking Buildbucket for the build status
[10:42:46.711] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:46.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:38:0) after 1m0s
[10:43:46.747] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:38:0)
[10:43:46.747] Timer tick, asking Buildbucket for the build status
[10:43:46.835] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:46.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:39:0) after 1m0s
[10:44:46.904] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:39:0)
[10:44:46.904] Timer tick, asking Buildbucket for the build status
[10:44:46.979] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:46.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:40:0) after 1m0s
[10:45:47.027] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:40:0)
[10:45:47.027] Timer tick, asking Buildbucket for the build status
[10:45:47.111] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:47.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:41:0) after 1m0s
[10:46:47.192] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:41:0)
[10:46:47.192] Timer tick, asking Buildbucket for the build status
[10:46:47.379] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:47.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:42:0) after 1m0s
[10:47:47.448] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:42:0)
[10:47:47.448] Timer tick, asking Buildbucket for the build status
[10:47:47.563] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:47.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:43:0) after 1m0s
[10:48:47.601] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:43:0)
[10:48:47.601] Timer tick, asking Buildbucket for the build status
[10:48:47.735] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:47.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:44:0) after 1m0s
[10:49:47.757] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:44:0)
[10:49:47.757] Timer tick, asking Buildbucket for the build status
[10:49:47.894] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:47.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:45:0) after 1m0s
[10:50:47.901] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:45:0)
[10:50:47.901] Timer tick, asking Buildbucket for the build status
[10:50:48.190] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:48.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:46:0) after 1m0s
[10:51:48.238] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:46:0)
[10:51:48.238] Timer tick, asking Buildbucket for the build status
[10:51:48.532] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:48.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:47:0) after 1m0s
[10:52:48.558] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:47:0)
[10:52:48.558] Timer tick, asking Buildbucket for the build status
[10:52:48.730] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:48.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:48:0) after 1m0s
[10:53:48.810] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:48:0)
[10:53:48.810] Timer tick, asking Buildbucket for the build status
[10:53:48.889] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:48.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:49:0) after 1m0s
[10:54:48.917] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:49:0)
[10:54:48.917] Timer tick, asking Buildbucket for the build status
[10:54:49.190] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:49.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:50:0) after 1m0s
[10:55:49.271] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:50:0)
[10:55:49.271] Timer tick, asking Buildbucket for the build status
[10:55:49.346] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:49.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:51:0) after 1m0s
[10:56:49.351] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:51:0)
[10:56:49.351] Timer tick, asking Buildbucket for the build status
[10:56:49.422] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:49.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:52:0) after 1m0s
[10:57:49.447] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:52:0)
[10:57:49.447] Timer tick, asking Buildbucket for the build status
[10:57:49.632] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:49.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:53:0) after 1m0s
[10:58:49.665] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:53:0)
[10:58:49.665] Timer tick, asking Buildbucket for the build status
[10:58:49.994] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:49.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:54:0) after 1m0s
[10:59:50.016] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:54:0)
[10:59:50.016] Timer tick, asking Buildbucket for the build status
[10:59:50.106] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:50.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:55:0) after 1m0s
[11:00:50.128] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:55:0)
[11:00:50.128] Timer tick, asking Buildbucket for the build status
[11:00:50.238] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:50.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:56:0) after 1m0s
[11:01:50.265] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:56:0)
[11:01:50.265] Timer tick, asking Buildbucket for the build status
[11:01:50.364] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:50.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:57:0) after 1m0s
[11:02:50.444] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:57:0)
[11:02:50.444] Timer tick, asking Buildbucket for the build status
[11:02:50.563] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:50.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:58:0) after 1m0s
[11:03:50.616] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:58:0)
[11:03:50.616] Timer tick, asking Buildbucket for the build status
[11:03:50.703] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:50.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:59:0) after 1m0s
[11:04:50.744] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:59:0)
[11:04:50.744] Timer tick, asking Buildbucket for the build status
[11:04:50.831] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:50.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:60:0) after 1m0s
[11:05:50.831] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:60:0)
[11:05:50.831] Timer tick, asking Buildbucket for the build status
[11:05:50.907] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:50.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:61:0) after 1m0s
[11:06:50.917] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:61:0)
[11:06:50.917] Timer tick, asking Buildbucket for the build status
[11:06:51.175] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:51.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:62:0) after 1m0s
[11:07:51.240] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:62:0)
[11:07:51.240] Timer tick, asking Buildbucket for the build status
[11:07:51.437] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:51.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:63:0) after 1m0s
[11:08:51.471] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:63:0)
[11:08:51.471] Timer tick, asking Buildbucket for the build status
[11:08:51.919] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:51.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:64:0) after 1m0s
[11:09:52.007] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:64:0)
[11:09:52.007] Timer tick, asking Buildbucket for the build status
[11:09:52.127] Build 8870730417238548032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:52.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9036160479178448352:65:0) after 1m0s
[11:10:49.927] Received PubSub notification, asking Buildbucket for the build status
[11:10:50.302] Build 8870730417238548032: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:10:50.302] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1598613049103605",
"created_by": "project:chromium",
"created_ts": "1598609255523522",
"id": "8870730417238548032",
"parameters_json": "{\"builder_name\": \"win-archive-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4bf6d3988344437b58061622f985da6ce825be53\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-8-y5xw\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-archive-rel\", \"buildnumber\": 17031, \"got_angle_revision\": \"d3eba1db0a1451029dcb34a028d8e347107cf929\", \"got_dawn_revision\": \"900bd341a3c6fc71dfbaeac8adb45b1da0de53d0\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"4bf6d3988344437b58061622f985da6ce825be53\", \"got_revision_cp\": \"refs/heads/master@{#802598}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"ec3650f56ca2cc032a46ee4eeb86cccd2b78a6b5\", \"got_v8_revision\": \"1f2195b49c6d7611165ebd149b2825f27693930c\", \"got_v8_revision_cp\": \"refs/heads/8.7.25@{#1}\", \"got_webrtc_revision\": \"89760badbdb09af158de5e1568ba0eff4240fa77\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32012}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4bf6d3988344437b58061622f985da6ce825be53\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-8-y5xw\"], \"image\": [\"chrome-win10-1703-20081700-d9392568d25\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5498-3454aa6\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1598609551002016",
"status": "COMPLETED",
"status_changed_ts": "1598613049581182",
"tags": [
"build_address:luci.chromium.ci/win-archive-rel/17031",
"builder:win-archive-rel",
"buildset:commit/git/4bf6d3988344437b58061622f985da6ce825be53",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4bf6d3988344437b58061622f985da6ce825be53",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9036160479178448352",
"scheduler_job_id:chromium/win-archive-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8870730417238548032/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4e4d5af7cae9dc10",
"user_agent:luci-scheduler"
],
"updated_ts": "1598613049589106",
"url": "https://ci.chromium.org/b/8870730417238548032",
"utcnow_ts": "1598613050285887"
}
}
[11:10:50.303] Invocation finished in 1h3m16.012684428s with status SUCCEEDED