[01:42:21.559] New invocation is queued and will start shortly
[01:42:22.644] Starting the invocation (attempt 1)
[01:42:22.682] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:42:22.682] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:42:22.682] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9017982274149468608",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"17e692795b96c05bfdc51e7a9b67e72f09e5defd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9017982274149468608",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/17e692795b96c05bfdc51e7a9b67e72f09e5defd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/17e692795b96c05bfdc51e7a9b67e72f09e5defd",
"gitiles_ref:refs/heads/master"
]
}
[01:42:24.182] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615945342854691",
"id": "8852552212130230480",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"17e692795b96c05bfdc51e7a9b67e72f09e5defd\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615945344047089",
"tags": [
"build_address:luci.chromium.ci/win-asan/17826",
"builder:win-asan",
"buildset:commit/git/17e692795b96c05bfdc51e7a9b67e72f09e5defd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/17e692795b96c05bfdc51e7a9b67e72f09e5defd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017982274149468608",
"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/8852552212130230480/+/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": "1615945344047231",
"url": "https://ci.chromium.org/b/8852552212130230480",
"utcnow_ts": "1615945344174424"
}
}
[01:42:24.182] Task URL: https://ci.chromium.org/b/8852552212130230480
[01:42:24.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:2:0) after 1m0s
[01:42:58.149] Received PubSub notification, asking Buildbucket for the build status
[01:42:58.214] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:24.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:2:0)
[01:43:24.207] Timer tick, asking Buildbucket for the build status
[01:43:24.427] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:24.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:4:0) after 1m0s
[01:44:24.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:4:0)
[01:44:24.485] Timer tick, asking Buildbucket for the build status
[01:44:24.547] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:24.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:5:0) after 1m0s
[01:45:24.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:5:0)
[01:45:24.589] Timer tick, asking Buildbucket for the build status
[01:45:24.711] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:24.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:6:0) after 1m0s
[01:46:24.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:6:0)
[01:46:24.737] Timer tick, asking Buildbucket for the build status
[01:46:24.808] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:24.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:7:0) after 1m0s
[01:47:24.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:7:0)
[01:47:24.900] Timer tick, asking Buildbucket for the build status
[01:47:25.183] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:25.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:8:0) after 1m0s
[01:48:25.318] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:8:0)
[01:48:25.318] Timer tick, asking Buildbucket for the build status
[01:48:25.399] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:25.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:9:0) after 1m0s
[01:49:25.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:9:0)
[01:49:25.499] Timer tick, asking Buildbucket for the build status
[01:49:25.554] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:25.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:10:0) after 1m0s
[01:50:25.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:10:0)
[01:50:25.582] Timer tick, asking Buildbucket for the build status
[01:50:25.689] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:25.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:11:0) after 1m0s
[01:51:25.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:11:0)
[01:51:25.826] Timer tick, asking Buildbucket for the build status
[01:51:25.895] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:25.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:12:0) after 1m0s
[01:52:26.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:12:0)
[01:52:26.068] Timer tick, asking Buildbucket for the build status
[01:52:26.177] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:26.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:13:0) after 1m0s
[01:53:26.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:13:0)
[01:53:26.284] Timer tick, asking Buildbucket for the build status
[01:53:26.431] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:26.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:14:0) after 1m0s
[01:54:26.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:14:0)
[01:54:26.453] Timer tick, asking Buildbucket for the build status
[01:54:26.524] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:26.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:15:0) after 1m0s
[01:55:26.567] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:15:0)
[01:55:26.567] Timer tick, asking Buildbucket for the build status
[01:55:26.638] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:26.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:16:0) after 1m0s
[01:56:26.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:16:0)
[01:56:26.700] Timer tick, asking Buildbucket for the build status
[01:56:26.781] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:26.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:17:0) after 1m0s
[01:57:26.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:17:0)
[01:57:26.859] Timer tick, asking Buildbucket for the build status
[01:57:26.954] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:26.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:18:0) after 1m0s
[01:58:26.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:18:0)
[01:58:26.997] Timer tick, asking Buildbucket for the build status
[01:58:27.071] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:27.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:19:0) after 1m0s
[01:59:27.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:19:0)
[01:59:27.203] Timer tick, asking Buildbucket for the build status
[01:59:27.293] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:27.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:20:0) after 1m0s
[02:00:27.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:20:0)
[02:00:27.446] Timer tick, asking Buildbucket for the build status
[02:00:27.518] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:27.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:21:0) after 1m0s
[02:01:27.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:21:0)
[02:01:27.584] Timer tick, asking Buildbucket for the build status
[02:01:27.675] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:27.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:22:0) after 1m0s
[02:02:27.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:22:0)
[02:02:27.765] Timer tick, asking Buildbucket for the build status
[02:02:28.092] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:28.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:23:0) after 1m0s
[02:03:28.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:23:0)
[02:03:28.157] Timer tick, asking Buildbucket for the build status
[02:03:28.688] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:28.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:24:0) after 1m0s
[02:04:28.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:24:0)
[02:04:28.758] Timer tick, asking Buildbucket for the build status
[02:04:28.862] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:28.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:25:0) after 1m0s
[02:05:28.904] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:25:0)
[02:05:28.904] Timer tick, asking Buildbucket for the build status
[02:05:29.351] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:29.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:26:0) after 1m0s
[02:06:29.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:26:0)
[02:06:29.403] Timer tick, asking Buildbucket for the build status
[02:06:29.497] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:29.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:27:0) after 1m0s
[02:07:29.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:27:0)
[02:07:29.512] Timer tick, asking Buildbucket for the build status
[02:07:29.624] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:29.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:28:0) after 1m0s
[02:08:29.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:28:0)
[02:08:29.683] Timer tick, asking Buildbucket for the build status
[02:08:29.760] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:29.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:29:0) after 1m0s
[02:09:29.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:29:0)
[02:09:29.857] Timer tick, asking Buildbucket for the build status
[02:09:30.290] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:30.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:30:0) after 1m0s
[02:10:30.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:30:0)
[02:10:30.758] Timer tick, asking Buildbucket for the build status
[02:10:30.835] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:30.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:31:0) after 1m0s
[02:11:30.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:31:0)
[02:11:30.977] Timer tick, asking Buildbucket for the build status
[02:11:31.116] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:31.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:32:0) after 1m0s
[02:12:31.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:32:0)
[02:12:31.272] Timer tick, asking Buildbucket for the build status
[02:12:31.360] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:31.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:33:0) after 1m0s
[02:13:31.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:33:0)
[02:13:31.521] Timer tick, asking Buildbucket for the build status
[02:13:31.600] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:31.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:34:0) after 1m0s
[02:14:31.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:34:0)
[02:14:31.749] Timer tick, asking Buildbucket for the build status
[02:14:31.844] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:31.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:35:0) after 1m0s
[02:15:31.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:35:0)
[02:15:31.912] Timer tick, asking Buildbucket for the build status
[02:15:32.000] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:32.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:36:0) after 1m0s
[02:16:32.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:36:0)
[02:16:32.252] Timer tick, asking Buildbucket for the build status
[02:16:32.362] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:32.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:37:0) after 1m0s
[02:17:32.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:37:0)
[02:17:32.551] Timer tick, asking Buildbucket for the build status
[02:17:32.659] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:32.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:38:0) after 1m0s
[02:18:32.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:38:0)
[02:18:32.813] Timer tick, asking Buildbucket for the build status
[02:18:32.917] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:32.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:39:0) after 1m0s
[02:19:33.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:39:0)
[02:19:33.177] Timer tick, asking Buildbucket for the build status
[02:19:33.256] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:33.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:40:0) after 1m0s
[02:20:33.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:40:0)
[02:20:33.369] Timer tick, asking Buildbucket for the build status
[02:20:33.589] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:33.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:41:0) after 1m0s
[02:21:33.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:41:0)
[02:21:33.674] Timer tick, asking Buildbucket for the build status
[02:21:33.814] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:33.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:42:0) after 1m0s
[02:22:33.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:42:0)
[02:22:33.998] Timer tick, asking Buildbucket for the build status
[02:22:34.125] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:34.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:43:0) after 1m0s
[02:23:34.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:43:0)
[02:23:34.195] Timer tick, asking Buildbucket for the build status
[02:23:34.463] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:34.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:44:0) after 1m0s
[02:24:34.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:44:0)
[02:24:34.625] Timer tick, asking Buildbucket for the build status
[02:24:34.741] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:34.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:45:0) after 1m0s
[02:25:34.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:45:0)
[02:25:34.902] Timer tick, asking Buildbucket for the build status
[02:25:35.229] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:35.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:46:0) after 1m0s
[02:26:35.290] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:46:0)
[02:26:35.290] Timer tick, asking Buildbucket for the build status
[02:26:36.180] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:36.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:47:0) after 1m0s
[02:27:36.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:47:0)
[02:27:36.216] Timer tick, asking Buildbucket for the build status
[02:27:36.573] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:36.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:48:0) after 1m0s
[02:28:36.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:48:0)
[02:28:36.593] Timer tick, asking Buildbucket for the build status
[02:28:36.684] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:36.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:49:0) after 1m0s
[02:29:36.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:49:0)
[02:29:36.726] Timer tick, asking Buildbucket for the build status
[02:29:36.808] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:36.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:50:0) after 1m0s
[02:30:36.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:50:0)
[02:30:36.901] Timer tick, asking Buildbucket for the build status
[02:30:36.979] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:36.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:51:0) after 1m0s
[02:31:36.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:51:0)
[02:31:36.981] Timer tick, asking Buildbucket for the build status
[02:31:37.057] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:37.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:52:0) after 1m0s
[02:32:37.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:52:0)
[02:32:37.124] Timer tick, asking Buildbucket for the build status
[02:32:37.457] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:37.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:53:0) after 1m0s
[02:33:37.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:53:0)
[02:33:37.553] Timer tick, asking Buildbucket for the build status
[02:33:37.625] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:37.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:54:0) after 1m0s
[02:34:37.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:54:0)
[02:34:37.689] Timer tick, asking Buildbucket for the build status
[02:34:37.976] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:37.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:55:0) after 1m0s
[02:35:38.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:55:0)
[02:35:38.086] Timer tick, asking Buildbucket for the build status
[02:35:38.401] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:38.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:56:0) after 1m0s
[02:36:38.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:56:0)
[02:36:38.451] Timer tick, asking Buildbucket for the build status
[02:36:38.560] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:38.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:57:0) after 1m0s
[02:37:38.600] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:57:0)
[02:37:38.600] Timer tick, asking Buildbucket for the build status
[02:37:38.876] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:38.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:58:0) after 1m0s
[02:38:38.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:58:0)
[02:38:38.870] Timer tick, asking Buildbucket for the build status
[02:38:38.961] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:38.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:59:0) after 1m0s
[02:39:38.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:59:0)
[02:39:38.964] Timer tick, asking Buildbucket for the build status
[02:39:39.228] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:39.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:60:0) after 1m0s
[02:40:39.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:60:0)
[02:40:39.251] Timer tick, asking Buildbucket for the build status
[02:40:39.322] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:39.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:61:0) after 1m0s
[02:41:39.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:61:0)
[02:41:39.497] Timer tick, asking Buildbucket for the build status
[02:41:39.564] Build 8852552212130230480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:39.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017982274149468608:62:0) after 1m0s
[02:42:31.170] Received PubSub notification, asking Buildbucket for the build status
[02:42:31.269] Build 8852552212130230480: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:42:31.269] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615948948633064",
"created_by": "project:chromium",
"created_ts": "1615945342854691",
"id": "8852552212130230480",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"17e692795b96c05bfdc51e7a9b67e72f09e5defd\"}}",
"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\": \"17e692795b96c05bfdc51e7a9b67e72f09e5defd\", \"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-us-central1-b-32-0-mmh4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17826, \"clang_revision\": \"llvmorg-13-init-4330-g08a5277a-1\", \"got_angle_revision\": \"27e12398b33894bf345dc8c496d0bcde527f9892\", \"got_dawn_revision\": \"cc84ee24fc89229d956a6cd1a8ed0f7fd7528c1b\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"17e692795b96c05bfdc51e7a9b67e72f09e5defd\", \"got_revision_cp\": \"refs/heads/master@{#863587}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a0aeb64e01d75b257495eda180bea9758d072005\", \"got_v8_revision\": \"c47dfc8ab1b8c17a6959f42e069091a38e72c040\", \"got_v8_revision_cp\": \"refs/heads/9.1.140@{#1}\", \"got_webrtc_revision\": \"ab6335041130fdc0365a604f49668eac9194f9d6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33479}\", \"is_cached\": true, \"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\": \"17e692795b96c05bfdc51e7a9b67e72f09e5defd\", \"swarm_hashes_refs/heads/master(at){#863587}_without_patch\": {\"absl_hardening_tests\": \"077e2b391173e57ee321a29fa0f352a6bd46bb79\", \"accessibility_unittests\": \"8771907ea5984ba5e4457ec8e01e91a3e17687b4\", \"angle_unittests\": \"0ea38f8bb19c81e3e42dcae906c059b7147678c0\", \"app_shell_unittests\": \"e111dc0d4fe7c8f545bc22489d91f7cdc5a0c677\", \"aura_unittests\": \"70af2feaf2d910ee8f34c02f358d318aae7356cc\", \"base_unittests\": \"b278be64807057ff13beee15ee042a74701b2ed4\", \"base_util_unittests\": \"3fc0d26c7d96f2f6432f19d7486fa122e4c40c7e\", \"blink_common_unittests\": \"a6bf61fc26d96f3cf02ccc062a03a6eb431abd2c\", \"blink_fuzzer_unittests\": \"e208b99036a3324b27593aed37727286c66fd5e6\", \"blink_heap_unittests\": \"7e3c082387fb0452576eaef8b917be0cd4d90573\", \"blink_platform_unittests\": \"d41e0c675cd235bc96eae3eed542089a4bf27fd1\", \"blink_unittests\": \"f1bee2f8fca0f459f5b8c4c5360192b951c28233\", \"boringssl_crypto_tests\": \"f947378eee4d2d578843965bb77ac69846464919\", \"boringssl_ssl_tests\": \"e236b883fa89656da7ed4664349e169206b89b95\", \"browser_switcher_bho_unittests\": \"adade16fb07baa8e4b24e9d7ef62dc33ffc98a55\", \"browser_tests\": \"eb3a3dfc122677e49cfde9465a792119d2cef41b\", \"capture_unittests\": \"876df77ea22f8f1cea6c4a269554195b831fc966\", \"cast_unittests\": \"886088637afd5498c069da6bee0df18b4933cf5f\", \"cc_unittests\": \"185587bcce5920350367bd137a981f26966ccff6\", \"chrome_app_unittests\": \"e6558bb70811e10851ccb699582bc366118432e3\", \"chrome_cleaner_unittests\": \"c02dd1cd5766ae24f394a8689c4c9289843e2019\", \"chrome_elf_unittests\": \"05769467c0a63eed98dfa3e027e4434506093279\", \"chromedriver_unittests\": \"e947b555296f420dbf3ce5c77becdc7117da4f09\", \"color_unittests\": \"9abcb7d7230cb15e2b10a70b48c20936803ac52d\", \"components_browsertests\": \"5ac362e4223ad4d3e3f8d7ca669fcd6278f91630\", \"components_unittests\": \"f1d390009bdd10f93fda87fd9a3581256045503d\", \"compositor_unittests\": \"a02e91509ed52f653cf747f8fe44819863db4dcb\", \"content_browsertests\": \"3d4f6fa54fef69f39010fbe42806a027ea4bbfbc\", \"content_unittests\": \"88bbe245179c55455a0f2289d3f87524d96b2669\", \"courgette_unittests\": \"bef05f782b53b04042a95cd58a307369148b06eb\", \"crashpad_tests\": \"f2b840200eb3a7ca99f8407e7448488afd0d02c8\", \"cronet_tests\": \"21747d3ca1a7ecb01a08b5cf4039197219b0e362\", \"cronet_unittests\": \"7b05b427f163a7b73fe334862b357d8b4e467e65\", \"crypto_unittests\": \"be85617b18b0b012b3c86d5f19326800200d4b77\", \"delayloads_unittests\": \"78dfd8db127c55ce57009faddb90aff9ed91e9fb\", \"device_unittests\": \"c57ec8454adf21ee7a2019804849df8532787735\", \"display_unittests\": \"29bfd3a17b40e740f6638ecef0439502531146eb\", \"elevation_service_unittests\": \"145c1a603c980b958718513edf744b8dca584c46\", \"events_unittests\": \"cdb864475cc981aafbab740abd5a9b851b69dd91\", \"extensions_browsertests\": \"3d380d2ceece3357542adbf374ace02b2021f24d\", \"extensions_unittests\": \"aac3b6fdf9d75b7b1f5ea1bbdc0d40c2e0e03a1a\", \"filesystem_service_unittests\": \"86b6cf78b9f72ddc94fb42939568367a5f654802\", \"gcm_unit_tests\": \"cda10b7f6bf3893728017ae0d9bf0981017a5585\", \"gcp_unittests\": \"a9f5a58682f5a480744521a8fc369953dfa55847\", \"gfx_unittests\": \"2bd40918576c8d632174537b7a8d93a71b4feae8\", \"gin_unittests\": \"888b09ec0df476d54f20bca86b5ba8e7d1cffd2e\", \"google_apis_unittests\": \"d1d7f7685607b3631aa5129b552c023ff7dc851a\", \"gpu_unittests\": \"bceb1b3857254b59fa4e8775674aeb9744db2e4c\", \"gwp_asan_unittests\": \"7c6e3be289176f8dba812fe211ebcbebe14076ce\", \"headless_browsertests\": \"03d72f3ef185d81714c9dc2b2598ef1c7469cf1b\", \"headless_unittests\": \"80f0782d641fe89a4ae9eb781dfa506178a8bcce\", \"install_static_unittests\": \"6b34af177b590e3aeab615ee6d0e59c04b01e330\", \"installer_util_unittests\": \"d38135dcb84ddb2c328037274e56ae6ab50c77f0\", \"interactive_ui_tests\": \"0bc3de32a96931ded58c4e61d6dd2bf6ddf8ad74\", \"ipc_tests\": \"51f7705dc5f2d0195b7673f45038a70ac9e3527b\", \"jingle_unittests\": \"3991c9da5c64db6dfed5bef77cfdced4fb7dfb88\", \"latency_unittests\": \"0531f1863456a59094fc647fb857d18bee023e7f\", \"libjingle_xmpp_unittests\": \"1915e639bfa8c78a4aa5d562061f0595c127bacb\", \"liburlpattern_unittests\": \"2cb790234cf44ba93de78940005dab2ba536d193\", \"media_blink_unittests\": \"4708196c3a56a52e22c2fae4d64a49c8c7d506fd\", \"media_unittests\": \"c168a39e457d2ec85ffd9f3cfb19a12a129cc999\", \"message_center_unittests\": \"c1903badb48e7e88fb427d670325e07ef36619ec\", \"midi_unittests\": \"28a22f2a38891fc2489127a5d124b9a301ce0475\", \"mojo_core_unittests\": \"843da6c637049f3a4a02d35ae7b136067b71dd5b\", \"mojo_unittests\": \"3c694cdef7812ea92202568a52eec670402e19c1\", \"nacl_loader_unittests\": \"0f5755d00b4fd26691ee9977ba275c90170676a5\", \"native_theme_unittests\": \"466386fc8986734f4b865198b6b05ce43d3d1c6e\", \"net_unittests\": \"0a2fb1f10070fe6e0dfddf7f205ef8da2fb4f074\", \"notification_helper_unittests\": \"02595fd35037fee86b4c710a0d5db63705e8ae32\", \"pdf_unittests\": \"45ced82b5594d02664b6d438eced3736c47ed9ef\", \"perfetto_unittests\": \"4de7406a57744bedcdfe5ddccc2bd00be0a71051\", \"ppapi_unittests\": \"f30f9bd5a3051dabf152fd177398e688b8c95ffb\", \"printing_unittests\": \"9d61e0df46a29a00fd51e877807e7600bf8b5d84\", \"remoting_unittests\": \"8c6c8c250cabe3b568bf0b9c75efd9cb07f3a7d4\", \"sbox_integration_tests\": \"a22ee05cc3c7344201343f7bbf379364ded065f2\", \"sbox_unittests\": \"cca5f7192a218288dfa748c95c60bd87be9214f0\", \"sbox_validation_tests\": \"147fb8bd979fa43d4aa166e7581a831a7c46d568\", \"service_manager_unittests\": \"85ec280aa66924695bed400d59f7d56bc72f8173\", \"services_unittests\": \"824557318d91d7af925d6f2acfb48e4db672b5d3\", \"setup_unittests\": \"1dc5f91954a102873a15da428e3901f2915aef31\", \"shell_dialogs_unittests\": \"aa78e26801507ef459d614dd9f2e82a5729c9540\", \"skia_unittests\": \"0522c3617449d4a23cd5a19b38baa271b725fc39\", \"snapshot_unittests\": \"fb31224c09453bf5f7c80f15f53ca180fce0b7de\", \"sql_unittests\": \"3c9b2efc596f6a351e460bcfe43f63495b6eb002\", \"storage_unittests\": \"1795850970254384d3412198c2dad7aa61bf7df7\", \"sync_integration_tests\": \"eaa3130f42530938c742eea335439cff60b4f599\", \"traffic_annotation_auditor_unittests\": \"b44acaf699df3a7ad67bdb5d177ec6e15ba86d9a\", \"ui_base_unittests\": \"16086024eb1beb2902ee1b1c582c5e5589f506d3\", \"ui_touch_selection_unittests\": \"461a14447499ed62c7d41fc5ea78e34b7c6bffee\", \"unit_tests\": \"c81d64cc6c4058a041e7f6957ba025ac6b569ef2\", \"updater_tests\": \"8577384953c2508d407a3ad9d3860c005d9c02cd\", \"url_unittests\": \"bbf7de651d13d5e7eecbe524bfe1a64fe6cdc856\", \"views_unittests\": \"5ca8e345813eec9412dfa77596120bd27d7e2c46\", \"viz_unittests\": \"2898f46162a4b5786943e268685df42b4d0e50e8\", \"vr_common_unittests\": \"0d13a607615484dbebd0aeb9a0a780c6235de8c6\", \"vr_pixeltests\": \"1ace178e1510435e7d79d114a5ec6af69fb32a1c\", \"weblayer_browsertests\": \"34a151493684b37a25d1d5203e28474fecdc6f36\", \"weblayer_unittests\": \"98b0b809a4f74c5ab081b45eff95188a35eaabb4\", \"wm_unittests\": \"087fc45c1fcad1f28b75a2ace04b80f96bfd8006\", \"wtf_unittests\": \"233b00c5af5735a90782e305ec0b02c7fabc4b77\", \"zlib_unittests\": \"a3415dfe9288f7c5b6e47063123c5c9da48a0b30\", \"zucchini_unittests\": \"238cbb3d1e1bbb3fb494b7df6860f4f93bc2ce1f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-0-mmh4\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615945373598813",
"status": "COMPLETED",
"status_changed_ts": "1615948949414791",
"tags": [
"build_address:luci.chromium.ci/win-asan/17826",
"builder:win-asan",
"buildset:commit/git/17e692795b96c05bfdc51e7a9b67e72f09e5defd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/17e692795b96c05bfdc51e7a9b67e72f09e5defd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017982274149468608",
"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/8852552212130230480/+/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:5256aaad5eadee10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615948949422610",
"url": "https://ci.chromium.org/b/8852552212130230480",
"utcnow_ts": "1615948951261292"
}
}
[02:42:31.270] Invocation finished in 1h0m9.725392713s with status SUCCEEDED