[02:35:12.620] New invocation is queued and will start shortly
[02:35:14.041] Starting the invocation (attempt 1)
[02:35:14.077] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:35:14.079] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:35:14.079] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9043436696611390528",
"parameters_json": "{\"builder_name\":\"win-archive-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3d7bfb5e87082ecc345b49d1718dbdc86c2e6e8d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-archive-rel",
"scheduler_invocation_id:9043436696611390528",
"scheduler_job_id:chromium/win-archive-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/3d7bfb5e87082ecc345b49d1718dbdc86c2e6e8d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3d7bfb5e87082ecc345b49d1718dbdc86c2e6e8d",
"gitiles_ref:refs/heads/master"
]
}
[02:35:18.083] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1591670114376898",
"id": "8878006634106272912",
"parameters_json": "{\"builder_name\": \"win-archive-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3d7bfb5e87082ecc345b49d1718dbdc86c2e6e8d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1591670117681894",
"tags": [
"build_address:luci.chromium.ci/win-archive-rel/14662",
"builder:win-archive-rel",
"buildset:commit/git/3d7bfb5e87082ecc345b49d1718dbdc86c2e6e8d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3d7bfb5e87082ecc345b49d1718dbdc86c2e6e8d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9043436696611390528",
"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/8878006634106272912/+/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": "1591670117682062",
"url": "https://ci.chromium.org/b/8878006634106272912",
"utcnow_ts": "1591670118049939"
}
}
[02:35:18.083] Task URL: https://ci.chromium.org/b/8878006634106272912
[02:35:18.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:2:0) after 1m0s
[02:35:23.965] Received PubSub notification, asking Buildbucket for the build status
[02:35:24.147] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:18.103] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:2:0)
[02:36:18.103] Timer tick, asking Buildbucket for the build status
[02:36:18.433] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:18.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:4:0) after 1m0s
[02:37:18.528] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:4:0)
[02:37:18.528] Timer tick, asking Buildbucket for the build status
[02:37:18.671] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:18.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:5:0) after 1m0s
[02:38:18.702] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:5:0)
[02:38:18.702] Timer tick, asking Buildbucket for the build status
[02:38:19.071] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:19.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:6:0) after 1m0s
[02:39:19.095] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:6:0)
[02:39:19.095] Timer tick, asking Buildbucket for the build status
[02:39:19.244] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:19.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:7:0) after 1m0s
[02:40:19.272] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:7:0)
[02:40:19.272] Timer tick, asking Buildbucket for the build status
[02:40:19.425] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:19.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:8:0) after 1m0s
[02:41:19.445] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:8:0)
[02:41:19.445] Timer tick, asking Buildbucket for the build status
[02:41:19.599] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:19.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:9:0) after 1m0s
[02:42:19.714] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:9:0)
[02:42:19.714] Timer tick, asking Buildbucket for the build status
[02:42:20.020] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:20.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:10:0) after 1m0s
[02:43:20.026] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:10:0)
[02:43:20.026] Timer tick, asking Buildbucket for the build status
[02:43:20.312] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:20.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:11:0) after 1m0s
[02:44:20.406] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:11:0)
[02:44:20.406] Timer tick, asking Buildbucket for the build status
[02:44:20.575] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:20.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:12:0) after 1m0s
[02:45:20.722] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:12:0)
[02:45:20.722] Timer tick, asking Buildbucket for the build status
[02:45:20.938] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:20.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:13:0) after 1m0s
[02:46:21.025] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:13:0)
[02:46:21.025] Timer tick, asking Buildbucket for the build status
[02:46:21.183] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:21.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:14:0) after 1m0s
[02:47:21.284] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:14:0)
[02:47:21.284] Timer tick, asking Buildbucket for the build status
[02:47:21.751] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:21.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:15:0) after 1m0s
[02:48:21.770] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:15:0)
[02:48:21.770] Timer tick, asking Buildbucket for the build status
[02:48:21.937] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:21.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:16:0) after 1m0s
[02:49:22.090] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:16:0)
[02:49:22.090] Timer tick, asking Buildbucket for the build status
[02:49:22.322] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:22.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:17:0) after 1m0s
[02:50:22.342] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:17:0)
[02:50:22.342] Timer tick, asking Buildbucket for the build status
[02:50:22.533] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:22.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:18:0) after 1m0s
[02:51:22.559] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:18:0)
[02:51:22.559] Timer tick, asking Buildbucket for the build status
[02:51:22.939] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:22.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:19:0) after 1m0s
[02:52:22.970] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:19:0)
[02:52:22.970] Timer tick, asking Buildbucket for the build status
[02:52:23.136] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:23.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:20:0) after 1m0s
[02:53:23.500] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:20:0)
[02:53:23.500] Timer tick, asking Buildbucket for the build status
[02:53:23.635] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:23.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:21:0) after 1m0s
[02:54:23.767] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:21:0)
[02:54:23.767] Timer tick, asking Buildbucket for the build status
[02:54:23.923] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:23.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:22:0) after 1m0s
[02:55:24.016] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:22:0)
[02:55:24.016] Timer tick, asking Buildbucket for the build status
[02:55:25.348] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:25.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:23:0) after 1m0s
[02:56:25.329] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:23:0)
[02:56:25.329] Timer tick, asking Buildbucket for the build status
[02:56:25.555] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:25.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:24:0) after 1m0s
[02:57:25.638] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:24:0)
[02:57:25.638] Timer tick, asking Buildbucket for the build status
[02:57:26.083] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:26.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:25:0) after 1m0s
[02:58:26.082] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:25:0)
[02:58:26.082] Timer tick, asking Buildbucket for the build status
[02:58:26.257] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:26.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:26:0) after 1m0s
[02:59:26.236] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:26:0)
[02:59:26.236] Timer tick, asking Buildbucket for the build status
[02:59:26.463] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:26.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:27:0) after 1m0s
[03:00:26.464] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:27:0)
[03:00:26.464] Timer tick, asking Buildbucket for the build status
[03:00:27.529] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:27.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:28:0) after 1m0s
[03:01:27.605] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:28:0)
[03:01:27.605] Timer tick, asking Buildbucket for the build status
[03:01:27.871] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:27.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:29:0) after 1m0s
[03:02:27.992] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:29:0)
[03:02:27.992] Timer tick, asking Buildbucket for the build status
[03:02:28.606] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:28.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:30:0) after 1m0s
[03:03:28.661] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:30:0)
[03:03:28.661] Timer tick, asking Buildbucket for the build status
[03:03:28.777] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:28.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:31:0) after 1m0s
[03:04:28.896] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:31:0)
[03:04:28.896] Timer tick, asking Buildbucket for the build status
[03:04:29.191] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:29.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:32:0) after 1m0s
[03:05:29.356] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:32:0)
[03:05:29.356] Timer tick, asking Buildbucket for the build status
[03:05:29.615] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:29.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:33:0) after 1m0s
[03:06:29.755] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:33:0)
[03:06:29.755] Timer tick, asking Buildbucket for the build status
[03:06:30.882] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:30.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:34:0) after 1m0s
[03:07:31.109] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:34:0)
[03:07:31.109] Timer tick, asking Buildbucket for the build status
[03:07:31.283] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:31.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:35:0) after 1m0s
[03:08:31.316] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:35:0)
[03:08:31.316] Timer tick, asking Buildbucket for the build status
[03:08:31.562] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:31.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:36:0) after 1m0s
[03:09:31.652] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:36:0)
[03:09:31.652] Timer tick, asking Buildbucket for the build status
[03:09:31.794] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:31.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:37:0) after 1m0s
[03:10:31.893] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:37:0)
[03:10:31.893] Timer tick, asking Buildbucket for the build status
[03:10:32.215] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:32.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:38:0) after 1m0s
[03:11:32.267] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:38:0)
[03:11:32.267] Timer tick, asking Buildbucket for the build status
[03:11:32.635] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:32.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:39:0) after 1m0s
[03:12:32.663] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:39:0)
[03:12:32.663] Timer tick, asking Buildbucket for the build status
[03:12:32.844] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:32.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:40:0) after 1m0s
[03:13:32.935] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:40:0)
[03:13:32.935] Timer tick, asking Buildbucket for the build status
[03:13:33.115] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:33.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:41:0) after 1m0s
[03:14:33.185] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:41:0)
[03:14:33.185] Timer tick, asking Buildbucket for the build status
[03:14:33.295] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:33.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:42:0) after 1m0s
[03:15:33.316] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:42:0)
[03:15:33.316] Timer tick, asking Buildbucket for the build status
[03:15:33.717] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:33.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:43:0) after 1m0s
[03:16:33.764] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:43:0)
[03:16:33.764] Timer tick, asking Buildbucket for the build status
[03:16:33.965] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:33.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:44:0) after 1m0s
[03:17:34.023] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:44:0)
[03:17:34.023] Timer tick, asking Buildbucket for the build status
[03:17:34.337] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:34.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:45:0) after 1m0s
[03:18:34.378] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:45:0)
[03:18:34.378] Timer tick, asking Buildbucket for the build status
[03:18:34.515] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:34.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:46:0) after 1m0s
[03:19:34.523] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:46:0)
[03:19:34.523] Timer tick, asking Buildbucket for the build status
[03:19:34.673] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:34.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:47:0) after 1m0s
[03:20:34.753] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:47:0)
[03:20:34.753] Timer tick, asking Buildbucket for the build status
[03:20:35.028] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:35.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:48:0) after 1m0s
[03:21:35.127] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:48:0)
[03:21:35.127] Timer tick, asking Buildbucket for the build status
[03:21:35.349] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:35.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:49:0) after 1m0s
[03:22:35.399] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:49:0)
[03:22:35.399] Timer tick, asking Buildbucket for the build status
[03:22:35.726] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:35.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:50:0) after 1m0s
[03:23:35.791] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:50:0)
[03:23:35.791] Timer tick, asking Buildbucket for the build status
[03:23:36.094] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:36.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:51:0) after 1m0s
[03:24:36.106] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:51:0)
[03:24:36.106] Timer tick, asking Buildbucket for the build status
[03:24:36.219] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:36.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:52:0) after 1m0s
[03:25:36.463] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:52:0)
[03:25:36.463] Timer tick, asking Buildbucket for the build status
[03:25:36.664] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:36.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:53:0) after 1m0s
[03:26:36.715] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:53:0)
[03:26:36.715] Timer tick, asking Buildbucket for the build status
[03:26:37.043] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:37.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:54:0) after 1m0s
[03:27:37.103] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:54:0)
[03:27:37.104] Timer tick, asking Buildbucket for the build status
[03:27:37.258] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:37.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:55:0) after 1m0s
[03:28:37.314] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:55:0)
[03:28:37.314] Timer tick, asking Buildbucket for the build status
[03:28:37.573] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:37.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:56:0) after 1m0s
[03:29:37.613] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:56:0)
[03:29:37.614] Timer tick, asking Buildbucket for the build status
[03:29:37.838] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:37.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:57:0) after 1m0s
[03:30:37.859] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:57:0)
[03:30:37.859] Timer tick, asking Buildbucket for the build status
[03:30:38.653] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:38.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:58:0) after 1m0s
[03:31:38.752] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:58:0)
[03:31:38.753] Timer tick, asking Buildbucket for the build status
[03:31:38.905] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:38.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:59:0) after 1m0s
[03:32:38.960] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:59:0)
[03:32:38.960] Timer tick, asking Buildbucket for the build status
[03:32:39.284] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:39.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:60:0) after 1m0s
[03:33:39.316] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:60:0)
[03:33:39.316] Timer tick, asking Buildbucket for the build status
[03:33:39.719] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:39.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:61:0) after 1m0s
[03:34:39.722] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:61:0)
[03:34:39.722] Timer tick, asking Buildbucket for the build status
[03:34:40.123] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:40.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:62:0) after 1m0s
[03:35:40.190] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:62:0)
[03:35:40.190] Timer tick, asking Buildbucket for the build status
[03:35:41.257] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:41.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:63:0) after 1m0s
[03:36:41.316] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:63:0)
[03:36:41.316] Timer tick, asking Buildbucket for the build status
[03:36:41.507] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:41.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:64:0) after 1m0s
[03:37:41.600] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:64:0)
[03:37:41.600] Timer tick, asking Buildbucket for the build status
[03:37:41.989] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:41.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:65:0) after 1m0s
[03:38:42.016] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:65:0)
[03:38:42.016] Timer tick, asking Buildbucket for the build status
[03:38:42.471] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:42.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:66:0) after 1m0s
[03:39:42.524] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:66:0)
[03:39:42.524] Timer tick, asking Buildbucket for the build status
[03:39:42.681] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:42.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:67:0) after 1m0s
[03:40:42.760] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:67:0)
[03:40:42.760] Timer tick, asking Buildbucket for the build status
[03:40:43.193] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:43.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:68:0) after 1m0s
[03:41:43.355] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:68:0)
[03:41:43.355] Timer tick, asking Buildbucket for the build status
[03:41:43.494] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:43.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:69:0) after 1m0s
[03:42:43.609] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:69:0)
[03:42:43.609] Timer tick, asking Buildbucket for the build status
[03:42:43.892] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:43.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:70:0) after 1m0s
[03:43:43.957] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:70:0)
[03:43:43.957] Timer tick, asking Buildbucket for the build status
[03:43:44.121] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:44.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:71:0) after 1m0s
[03:44:44.156] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:71:0)
[03:44:44.156] Timer tick, asking Buildbucket for the build status
[03:44:44.481] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:44.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:72:0) after 1m0s
[03:45:44.568] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:72:0)
[03:45:44.568] Timer tick, asking Buildbucket for the build status
[03:45:44.883] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:44.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:73:0) after 1m0s
[03:46:44.974] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:73:0)
[03:46:44.974] Timer tick, asking Buildbucket for the build status
[03:46:45.138] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:45.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:74:0) after 1m0s
[03:47:45.258] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:74:0)
[03:47:45.258] Timer tick, asking Buildbucket for the build status
[03:47:45.569] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:45.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:75:0) after 1m0s
[03:48:45.632] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:75:0)
[03:48:45.632] Timer tick, asking Buildbucket for the build status
[03:48:45.867] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:45.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:76:0) after 1m0s
[03:49:45.860] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:76:0)
[03:49:45.860] Timer tick, asking Buildbucket for the build status
[03:49:46.009] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:46.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:77:0) after 1m0s
[03:50:45.990] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:77:0)
[03:50:45.990] Timer tick, asking Buildbucket for the build status
[03:50:46.152] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:46.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:78:0) after 1m0s
[03:51:46.179] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:78:0)
[03:51:46.179] Timer tick, asking Buildbucket for the build status
[03:51:46.991] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:46.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:79:0) after 1m0s
[03:52:47.031] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:79:0)
[03:52:47.031] Timer tick, asking Buildbucket for the build status
[03:52:48.199] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:48.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:80:0) after 1m0s
[03:53:48.272] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:80:0)
[03:53:48.272] Timer tick, asking Buildbucket for the build status
[03:53:48.440] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:48.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:81:0) after 1m0s
[03:54:48.492] Handling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:81:0)
[03:54:48.492] Timer tick, asking Buildbucket for the build status
[03:54:48.643] Build 8878006634106272912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:48.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-archive-rel:9043436696611390528:82:0) after 1m0s
[03:54:51.749] Received PubSub notification, asking Buildbucket for the build status
[03:54:51.908] Build 8878006634106272912: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:54:51.908] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1591674890978412",
"created_by": "project:chromium",
"created_ts": "1591670114376898",
"id": "8878006634106272912",
"parameters_json": "{\"builder_name\": \"win-archive-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3d7bfb5e87082ecc345b49d1718dbdc86c2e6e8d\"}}",
"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-3-c76k\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-archive-rel\", \"buildnumber\": 14662, \"got_angle_revision\": \"e9a0cb87f7a3486cc31e0a8ae4446fdf7d738281\", \"got_dawn_revision\": \"0a79bee135cd916e5d85495c47f4f77746e1c5f6\", \"got_nacl_revision\": \"87616f7ce2ea8383efd3731960c15f4ba5852d93\", \"got_revision\": \"3d7bfb5e87082ecc345b49d1718dbdc86c2e6e8d\", \"got_revision_cp\": \"refs/heads/master@{#776350}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"e3eb327e8c3c724a415fa4d5f4805f2e281f4760\", \"got_v8_revision\": \"9912e35d594bf00cd78697889beff6b238ebe66d\", \"got_v8_revision_cp\": \"refs/heads/8.5.133@{#1}\", \"got_webrtc_revision\": \"09eb6e249d4e1f926dfa586bff9cc0de1c4cdefa\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31465}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3d7bfb5e87082ecc345b49d1718dbdc86c2e6e8d\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-dbg\", \"win-rel\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-dbg\", \"win32-rel\"], \"caches\": [\"builder_a69e6b6949245e742d9a022200655bac5124ee86184973d811510d142a3a53fc_v2\", \"builder_cc946d8c9fe3204026095d74248289703a45d938e0b9766be9cb626e466da04f_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-3-c76k\"], \"image\": [\"chrome-win10-1703-20052900-58250aa4257\"], \"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\": [\"5184-34b1987\"], \"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": "1591670121305088",
"status": "COMPLETED",
"status_changed_ts": "1591674891357233",
"tags": [
"build_address:luci.chromium.ci/win-archive-rel/14662",
"builder:win-archive-rel",
"buildset:commit/git/3d7bfb5e87082ecc345b49d1718dbdc86c2e6e8d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3d7bfb5e87082ecc345b49d1718dbdc86c2e6e8d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9043436696611390528",
"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/8878006634106272912/+/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:4cafc02111cdae10",
"user_agent:luci-scheduler"
],
"updated_ts": "1591674891365948",
"url": "https://ci.chromium.org/b/8878006634106272912",
"utcnow_ts": "1591674891893794"
}
}
[03:54:51.908] Invocation finished in 1h19m39.304891808s with status SUCCEEDED