[17:21:08.374] New invocation is queued and will start shortly
[17:21:10.748] Starting the invocation (attempt 1)
[17:21:10.790] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:21:10.790] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:21:10.790] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023721417064005392",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"928f56dad8461adb1ce2c4485dc0bb0a2cd54394\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023721417064005392",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/928f56dad8461adb1ce2c4485dc0bb0a2cd54394",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/928f56dad8461adb1ce2c4485dc0bb0a2cd54394",
"gitiles_ref:refs/heads/master"
]
}
[17:21:12.304] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610472070917627",
"id": "8858291353724711872",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"928f56dad8461adb1ce2c4485dc0bb0a2cd54394\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610472071955106",
"tags": [
"build_address:luci.chromium.ci/win-asan/16779",
"builder:win-asan",
"buildset:commit/git/928f56dad8461adb1ce2c4485dc0bb0a2cd54394",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/928f56dad8461adb1ce2c4485dc0bb0a2cd54394",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023721417064005392",
"scheduler_job_id:chromium/win-asan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8858291353724711872/+/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": "1610472071955215",
"url": "https://ci.chromium.org/b/8858291353724711872",
"utcnow_ts": "1610472072248613"
}
}
[17:21:12.304] Task URL: https://ci.chromium.org/b/8858291353724711872
[17:21:12.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:2:0) after 1m0s
[17:22:12.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:2:0)
[17:22:12.505] Timer tick, asking Buildbucket for the build status
[17:22:12.635] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:12.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:3:0) after 1m0s
[17:23:12.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:3:0)
[17:23:12.671] Timer tick, asking Buildbucket for the build status
[17:23:12.796] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:12.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:4:0) after 1m0s
[17:24:09.197] Received PubSub notification, asking Buildbucket for the build status
[17:24:09.323] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:12.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:4:0)
[17:24:12.853] Timer tick, asking Buildbucket for the build status
[17:24:13.046] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:13.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:6:0) after 1m0s
[17:25:13.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:6:0)
[17:25:13.110] Timer tick, asking Buildbucket for the build status
[17:25:13.396] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:13.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:7:0) after 1m0s
[17:26:13.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:7:0)
[17:26:13.557] Timer tick, asking Buildbucket for the build status
[17:26:13.943] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:13.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:8:0) after 1m0s
[17:27:14.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:8:0)
[17:27:14.014] Timer tick, asking Buildbucket for the build status
[17:27:14.120] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:14.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:9:0) after 1m0s
[17:28:14.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:9:0)
[17:28:14.459] Timer tick, asking Buildbucket for the build status
[17:28:14.627] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:14.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:10:0) after 1m0s
[17:29:14.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:10:0)
[17:29:14.750] Timer tick, asking Buildbucket for the build status
[17:29:14.833] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:14.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:11:0) after 1m0s
[17:30:14.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:11:0)
[17:30:14.869] Timer tick, asking Buildbucket for the build status
[17:30:14.961] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:14.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:12:0) after 1m0s
[17:31:14.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:12:0)
[17:31:14.991] Timer tick, asking Buildbucket for the build status
[17:31:15.118] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:15.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:13:0) after 1m0s
[17:32:15.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:13:0)
[17:32:15.180] Timer tick, asking Buildbucket for the build status
[17:32:15.247] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:15.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:14:0) after 1m0s
[17:33:15.349] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:14:0)
[17:33:15.349] Timer tick, asking Buildbucket for the build status
[17:33:15.599] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:15.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:15:0) after 1m0s
[17:34:15.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:15:0)
[17:34:15.660] Timer tick, asking Buildbucket for the build status
[17:34:16.134] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:16.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:16:0) after 1m0s
[17:35:16.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:16:0)
[17:35:16.208] Timer tick, asking Buildbucket for the build status
[17:35:16.326] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:16.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:17:0) after 1m0s
[17:36:16.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:17:0)
[17:36:16.684] Timer tick, asking Buildbucket for the build status
[17:36:18.083] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:18.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:18:0) after 1m0s
[17:37:18.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:18:0)
[17:37:18.129] Timer tick, asking Buildbucket for the build status
[17:37:18.227] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:18.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:19:0) after 1m0s
[17:38:18.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:19:0)
[17:38:18.284] Timer tick, asking Buildbucket for the build status
[17:38:18.401] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:18.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:20:0) after 1m0s
[17:39:18.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:20:0)
[17:39:18.458] Timer tick, asking Buildbucket for the build status
[17:39:18.537] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:18.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:21:0) after 1m0s
[17:40:18.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:21:0)
[17:40:18.587] Timer tick, asking Buildbucket for the build status
[17:40:19.044] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:19.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:22:0) after 1m0s
[17:41:19.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:22:0)
[17:41:19.028] Timer tick, asking Buildbucket for the build status
[17:41:19.170] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:19.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:23:0) after 1m0s
[17:42:19.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:23:0)
[17:42:19.262] Timer tick, asking Buildbucket for the build status
[17:42:19.386] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:19.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:24:0) after 1m0s
[17:43:19.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:24:0)
[17:43:19.465] Timer tick, asking Buildbucket for the build status
[17:43:19.582] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:19.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:25:0) after 1m0s
[17:44:20.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:25:0)
[17:44:20.022] Timer tick, asking Buildbucket for the build status
[17:44:20.162] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:20.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:26:0) after 1m0s
[17:45:20.306] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:26:0)
[17:45:20.306] Timer tick, asking Buildbucket for the build status
[17:45:20.512] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:20.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:27:0) after 1m0s
[17:46:20.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:27:0)
[17:46:20.527] Timer tick, asking Buildbucket for the build status
[17:46:20.672] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:20.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:28:0) after 1m0s
[17:47:20.695] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:28:0)
[17:47:20.695] Timer tick, asking Buildbucket for the build status
[17:47:20.802] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:20.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:29:0) after 1m0s
[17:48:20.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:29:0)
[17:48:20.846] Timer tick, asking Buildbucket for the build status
[17:48:20.926] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:20.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:30:0) after 1m0s
[17:49:21.002] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:30:0)
[17:49:21.002] Timer tick, asking Buildbucket for the build status
[17:49:21.178] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:21.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:31:0) after 1m0s
[17:50:21.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:31:0)
[17:50:21.217] Timer tick, asking Buildbucket for the build status
[17:50:21.361] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:21.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:32:0) after 1m0s
[17:51:21.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:32:0)
[17:51:21.402] Timer tick, asking Buildbucket for the build status
[17:51:21.954] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:21.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:33:0) after 1m0s
[17:52:21.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:33:0)
[17:52:21.979] Timer tick, asking Buildbucket for the build status
[17:52:22.195] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:22.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:34:0) after 1m0s
[17:53:22.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:34:0)
[17:53:22.217] Timer tick, asking Buildbucket for the build status
[17:53:22.379] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:22.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:35:0) after 1m0s
[17:54:22.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:35:0)
[17:54:22.548] Timer tick, asking Buildbucket for the build status
[17:54:22.747] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:22.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:36:0) after 1m0s
[17:55:22.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:36:0)
[17:55:22.788] Timer tick, asking Buildbucket for the build status
[17:55:23.122] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:23.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:37:0) after 1m0s
[17:56:23.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:37:0)
[17:56:23.257] Timer tick, asking Buildbucket for the build status
[17:56:23.403] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:23.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:38:0) after 1m0s
[17:57:23.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:38:0)
[17:57:23.501] Timer tick, asking Buildbucket for the build status
[17:57:23.609] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:23.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:39:0) after 1m0s
[17:58:23.713] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:39:0)
[17:58:23.713] Timer tick, asking Buildbucket for the build status
[17:58:23.873] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:23.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:40:0) after 1m0s
[17:59:23.924] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:40:0)
[17:59:23.924] Timer tick, asking Buildbucket for the build status
[17:59:24.019] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:24.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:41:0) after 1m0s
[18:00:24.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:41:0)
[18:00:24.123] Timer tick, asking Buildbucket for the build status
[18:00:24.235] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:24.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:42:0) after 1m0s
[18:01:24.421] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:42:0)
[18:01:24.421] Timer tick, asking Buildbucket for the build status
[18:01:24.651] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:24.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:43:0) after 1m0s
[18:02:24.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:43:0)
[18:02:24.768] Timer tick, asking Buildbucket for the build status
[18:02:24.926] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:24.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:44:0) after 1m0s
[18:03:24.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:44:0)
[18:03:24.952] Timer tick, asking Buildbucket for the build status
[18:03:25.061] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:25.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:45:0) after 1m0s
[18:04:25.114] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:45:0)
[18:04:25.114] Timer tick, asking Buildbucket for the build status
[18:04:25.207] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:25.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:46:0) after 1m0s
[18:05:25.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:46:0)
[18:05:25.317] Timer tick, asking Buildbucket for the build status
[18:05:25.524] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:25.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:47:0) after 1m0s
[18:06:25.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:47:0)
[18:06:25.555] Timer tick, asking Buildbucket for the build status
[18:06:25.756] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:25.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:48:0) after 1m0s
[18:07:25.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:48:0)
[18:07:25.775] Timer tick, asking Buildbucket for the build status
[18:07:25.852] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:25.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:49:0) after 1m0s
[18:08:25.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:49:0)
[18:08:25.946] Timer tick, asking Buildbucket for the build status
[18:08:26.084] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:26.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:50:0) after 1m0s
[18:09:26.175] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:50:0)
[18:09:26.175] Timer tick, asking Buildbucket for the build status
[18:09:26.266] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:26.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:51:0) after 1m0s
[18:10:26.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:51:0)
[18:10:26.269] Timer tick, asking Buildbucket for the build status
[18:10:26.460] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:26.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:52:0) after 1m0s
[18:11:26.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:52:0)
[18:11:26.569] Timer tick, asking Buildbucket for the build status
[18:11:26.688] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:26.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:53:0) after 1m0s
[18:12:26.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:53:0)
[18:12:26.760] Timer tick, asking Buildbucket for the build status
[18:12:26.909] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:26.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:54:0) after 1m0s
[18:13:26.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:54:0)
[18:13:26.936] Timer tick, asking Buildbucket for the build status
[18:13:27.165] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:27.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:55:0) after 1m0s
[18:14:27.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:55:0)
[18:14:27.265] Timer tick, asking Buildbucket for the build status
[18:14:27.338] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:27.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:56:0) after 1m0s
[18:15:27.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:56:0)
[18:15:27.365] Timer tick, asking Buildbucket for the build status
[18:15:27.498] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:27.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:57:0) after 1m0s
[18:16:27.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:57:0)
[18:16:27.522] Timer tick, asking Buildbucket for the build status
[18:16:27.692] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:27.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:58:0) after 1m0s
[18:17:27.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:58:0)
[18:17:27.780] Timer tick, asking Buildbucket for the build status
[18:17:27.905] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:27.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:59:0) after 1m0s
[18:18:28.230] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:59:0)
[18:18:28.230] Timer tick, asking Buildbucket for the build status
[18:18:28.620] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:28.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:60:0) after 1m0s
[18:19:28.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:60:0)
[18:19:28.745] Timer tick, asking Buildbucket for the build status
[18:19:28.942] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:28.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:61:0) after 1m0s
[18:20:29.081] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:61:0)
[18:20:29.081] Timer tick, asking Buildbucket for the build status
[18:20:29.209] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:29.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:62:0) after 1m0s
[18:21:29.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:62:0)
[18:21:29.320] Timer tick, asking Buildbucket for the build status
[18:21:29.473] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:29.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:63:0) after 1m0s
[18:22:29.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:63:0)
[18:22:29.482] Timer tick, asking Buildbucket for the build status
[18:22:29.793] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:29.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:64:0) after 1m0s
[18:23:29.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:64:0)
[18:23:29.805] Timer tick, asking Buildbucket for the build status
[18:23:29.931] Build 8858291353724711872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:29.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:65:0) after 1m0s
[18:24:29.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023721417064005392:65:0)
[18:24:29.967] Timer tick, asking Buildbucket for the build status
[18:24:30.082] Build 8858291353724711872: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:24:30.083] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610475846655670",
"created_by": "project:chromium",
"created_ts": "1610472070917627",
"id": "8858291353724711872",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"928f56dad8461adb1ce2c4485dc0bb0a2cd54394\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"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\": \"928f56dad8461adb1ce2c4485dc0bb0a2cd54394\", \"project\": \"chromium/src\"}], \"$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\": \"luci-chromium-ci-win10-32-8-wwgq\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16779, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"7f0e7d0d262aa0ee71c862846c72f737cbbfca04\", \"got_dawn_revision\": \"7fae6cbf1d370bce5af457ad07f97f290a99cb0d\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"928f56dad8461adb1ce2c4485dc0bb0a2cd54394\", \"got_revision_cp\": \"refs/heads/master@{#842566}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"3549479dc4ccd92ef3b9e179080dcec1a430b01d\", \"got_v8_revision\": \"81b3d28ad43829f5d3c65e0725a266bb1a8e7340\", \"got_v8_revision_cp\": \"refs/heads/8.9.254@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"928f56dad8461adb1ce2c4485dc0bb0a2cd54394\", \"swarm_hashes_refs/heads/master(at){#842566}_without_patch\": {\"absl_hardening_tests\": \"30c4f8474a90223bf201b787bbbb9a23631ac994\", \"accessibility_unittests\": \"7c5ed2632756972651b8de514df5a23cd22e7d23\", \"angle_unittests\": \"280739b0434b3cffeba6e8c35d3a021105801a8f\", \"app_shell_unittests\": \"b344753abea363879f6923d0f291edb7f4a69c02\", \"aura_unittests\": \"742c89c117ced3d2983c215c56b5e34956651e23\", \"base_unittests\": \"684b406b140fa7192c15972159d2c32f8ce9729e\", \"base_util_unittests\": \"684d083fc01d03da31c2a371d44f5ddd17f22ba3\", \"blink_common_unittests\": \"0fd7c3a2bc4431822f3d6e85a28e7988ff464816\", \"blink_fuzzer_unittests\": \"632ed033ab695b7bd2404a2dd2dc2e19699d5ea7\", \"blink_heap_unittests\": \"19b01ef3fc5bc3b7d6d7a6ecd77b79e739eb65a0\", \"blink_platform_unittests\": \"cc2c698608463bf4c247f82495ef7ae6ed14a14d\", \"blink_unittests\": \"cff915818dfc4cac9d63e38538e3e4efc058bca1\", \"boringssl_crypto_tests\": \"536aa5a7aeba6f588c93978b2a31b4aa4f6322be\", \"boringssl_ssl_tests\": \"b873ef56a952173cab921095a762331e5df38ae6\", \"browser_switcher_bho_unittests\": \"75c7ad51a7af2d395105e44e1244e3330bf90063\", \"browser_tests\": \"5bbd31af35654878e770db5b9d2665bd4e2c13aa\", \"capture_unittests\": \"a102e7b264da31d08b339ce82fcacd3003408547\", \"cast_unittests\": \"927bef91a73bc156f272afd7ede78f43f1ac5c81\", \"cc_unittests\": \"8ecf800fe7c08de279b783c87487b7b811c60b30\", \"chrome_app_unittests\": \"c2c4e625c60ff39ea874e2088e9e501861e84307\", \"chrome_cleaner_unittests\": \"f780c8bb880e05b3fb9ccfaf94c995291cd32d8c\", \"chrome_elf_unittests\": \"151bf1db54a76a14facdf49d841ce11096c680aa\", \"chromedriver_unittests\": \"f4e2e9fe1b3b2d5f64d4a655b6f0bae05fd3cacd\", \"components_browsertests\": \"255fe034d75d63236d162e69f62a5e082497cf7c\", \"components_unittests\": \"bdd9bd6910278136b2deb7f291489bd79c21e384\", \"compositor_unittests\": \"fad328adf63bed5ca87714f2d00bcbc7aa9e7959\", \"content_browsertests\": \"97addc6c21b4729f1178f81a814c441a204dfc8a\", \"content_unittests\": \"0eaf0583c6be94d70279c1062d5fdb49d935fdf6\", \"courgette_unittests\": \"3308838429125b41d4d76bc1ba0f04e5699b098d\", \"crashpad_tests\": \"852e52849b317843c499bbab2ffee656e6472214\", \"cronet_tests\": \"22e6eddd15c49bd067c1ce37df162f4fb480ab86\", \"cronet_unittests\": \"4a79a7a990733c0e21f5d7593ae5586b3fd9d844\", \"crypto_unittests\": \"81d6b8125dd1ed1f9d5c561efbe6e77f25939016\", \"delayloads_unittests\": \"e93ddc28795b6cc0cd527efe0df5448b83f9aaad\", \"device_unittests\": \"6aa8fe170305fc366beeb710438915ca1bf37bcb\", \"display_unittests\": \"0f57026728d83fb18031549c45710f393603df78\", \"elevation_service_unittests\": \"9a067a8cc6f512f2b7caa148e7458cbde89b7a97\", \"events_unittests\": \"fe7a5a07596bbbe16f038116a45e5d949747188b\", \"extensions_browsertests\": \"1bb13ea52b353b7e7c49c556a6eb3b2f8de2c18e\", \"extensions_unittests\": \"77edd62739fb12056ed246237723398b3d052e2c\", \"filesystem_service_unittests\": \"78bebcde4bcf2a86da15b76ca228adebb7f41589\", \"gcm_unit_tests\": \"9e50ed3b645f0118f8bbd3e764ba4ee599832c40\", \"gcp_unittests\": \"f748c51fc8a000f318803c8b634aa1fcdce84a10\", \"gfx_unittests\": \"8e4193ff5581952d85e998a49f12028e30de1e13\", \"gin_unittests\": \"05e2772b3139a64b6b74452abd386e9f98e25f03\", \"google_apis_unittests\": \"1bde86cea45c4193e92c8ac1fd506c840344a96c\", \"gpu_unittests\": \"d7fdffa764ccb1d4bc156aa9e3236136717230b6\", \"gwp_asan_unittests\": \"21693f2191f2cac7f56eff09a046da803fc2b98c\", \"headless_browsertests\": \"da1f0cf58f92e4e2a6cf0d5cc9f1ef6712bb7c81\", \"headless_unittests\": \"aa058d195fd8c20b145514122944ca5516539dce\", \"install_static_unittests\": \"72dd897b51e1181c00f786300c4477f91cd92c69\", \"installer_util_unittests\": \"69a4d3c2a721073702720c4734eefa4cdb6d57cf\", \"interactive_ui_tests\": \"18ff5a6b3866d018e2c078c1e1269ad3b39702ee\", \"ipc_tests\": \"2c9783db368a084e510da5eba7c97d5400a9afaf\", \"jingle_unittests\": \"3e4f33eb6c2e9ecd0d8bec5b4e89dc2178fd401a\", \"latency_unittests\": \"9c3e9c0df3ed85ba86c57e171a1c93230992b0b5\", \"libjingle_xmpp_unittests\": \"403284a625cebecae2a0bc50313d79f367ea667f\", \"liburlpattern_unittests\": \"43da22e3fba289f743cbd2c87d9c98162cd8b8de\", \"media_blink_unittests\": \"2fc9829b46ea911e4ad7a33fead4824b72e31bc2\", \"media_unittests\": \"fa514f6a75fd162b58da7d2ea19833a07aa3b947\", \"message_center_unittests\": \"dad6435448f62b2358c59ebf23db641cd4e7e7aa\", \"midi_unittests\": \"492f90d225c8fbf711900f5aa398e37fc0970dea\", \"mojo_core_unittests\": \"958967835613198d7de7c7464a25436cf3331676\", \"mojo_unittests\": \"fe3a1379690f245df802be8a9fb6c70732086067\", \"nacl_loader_unittests\": \"e55d14e9e5afcc4366f14a205967c775b21b1594\", \"native_theme_unittests\": \"b86ca07c159846aa4ff589ce96c2f512f24c0e3e\", \"net_unittests\": \"628cdb941b1737cfe8c47044bbecfba6db0ac494\", \"notification_helper_unittests\": \"699a27a342672566512284579ac6ab57282f9802\", \"pdf_unittests\": \"0acbd9d7245fde05bb9c5ec603c3a6843b489e97\", \"perfetto_unittests\": \"b243729f7d8d17bcc8c533f3770f2cda3e7a382d\", \"ppapi_unittests\": \"2847de07c08d6c0b9c6c3e0bc9a4d730fd5dea2f\", \"printing_unittests\": \"393b9c9e7087b59337abd7af6e6c663cf564afcf\", \"remoting_unittests\": \"b86b21e8077b9501af28aa998db42ed558d871d3\", \"sbox_integration_tests\": \"616ef4fd1086d5dbba93e789b3185c8f4681f3b3\", \"sbox_unittests\": \"d38e53a5bbe9ab1b069421258ed2fd583bab18ce\", \"sbox_validation_tests\": \"81710c89f01936e503d8e2982d7b23432dc7aee1\", \"service_manager_unittests\": \"7fb406cf7535dc763d2563173116c710a66fdbf4\", \"services_unittests\": \"cbe52c8265c96e88bb7ed9beecdf56b2fec03155\", \"setup_unittests\": \"276e848a158b398ffd28b10795e71b9d73970954\", \"shell_dialogs_unittests\": \"aa951721915cdded541e1954b646d403df2a0b45\", \"skia_unittests\": \"b85782c557463c156f1a6b7f11b9f30b4f8117b9\", \"snapshot_unittests\": \"f370a1bbfa63866838e46a76f7eaa7a0f2fa5844\", \"sql_unittests\": \"114034085236591fe106f90cdf6206ad038573b9\", \"storage_unittests\": \"30280dcc15dd112f07ec02285bae72d71cdc96f3\", \"sync_integration_tests\": \"a2244106dd86f60f4b5b3b1099485b20cdfda3ae\", \"traffic_annotation_auditor_unittests\": \"380695b430e6c9946d41aaf1d2beadc51b19ca17\", \"ui_base_unittests\": \"3bce465c2bd0d4639aab447c40fcb5e8259284af\", \"ui_touch_selection_unittests\": \"b6cb1a26c12f07fa876d54572e3b0ec65f00a107\", \"unit_tests\": \"3c8d436e178ec50541be0ece35c9a6cb77b1cfea\", \"updater_tests\": \"001d67572f3f191721fd3375863270976a5c2dfd\", \"url_unittests\": \"39ef01a08bd7c97651fda9bc00064210fae54117\", \"views_unittests\": \"54f3793e5edf0e3917f5b0d820ce726ec9ba7d7a\", \"viz_unittests\": \"236b5b43d7a95c9830c79b32828c9e43d9d281a7\", \"vr_common_unittests\": \"026a9428d9c28e2ceccf6a3a98c1768fb16ccc99\", \"vr_pixeltests\": \"7c66129be3ab1d090ae9d0b6539598ab2629f799\", \"weblayer_browsertests\": \"e4fa981e8b36af4d8871e5c82088d93a07b1884a\", \"weblayer_unittests\": \"82e7462cd07a2a008685eea2d91c99fc7b39fca1\", \"wm_unittests\": \"2789b7e618d864ec27d81d27fc6b0b7fe75726cb\", \"wtf_unittests\": \"7ab9368e2e293cc03ec5a2cebf9e809417dfab60\", \"zlib_unittests\": \"e4f3ac883eb98bfcd4b9c751637d504c6fa1c31f\", \"zucchini_unittests\": \"7f7cbcabcdf88191497946d79fab8498a14e1a62\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-8-wwgq\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"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\": [\"5661-c6f6250\"], \"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": "1610472077300291",
"status": "COMPLETED",
"status_changed_ts": "1610475847199442",
"tags": [
"build_address:luci.chromium.ci/win-asan/16779",
"builder:win-asan",
"buildset:commit/git/928f56dad8461adb1ce2c4485dc0bb0a2cd54394",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/928f56dad8461adb1ce2c4485dc0bb0a2cd54394",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023721417064005392",
"scheduler_job_id:chromium/win-asan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8858291353724711872/+/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:51106f2d7a3d1e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1610475847218387",
"url": "https://ci.chromium.org/b/8858291353724711872",
"utcnow_ts": "1610475870070873"
}
}
[18:24:30.083] Invocation finished in 1h3m21.733575527s with status SUCCEEDED