[07:44:42.949] New invocation is queued and will start shortly
[07:44:44.071] Starting the invocation (attempt 1)
[07:44:44.121] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:44:44.121] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:44:44.121] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021311564406175568",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d85bd563e2e3adbaa0e5aab7e97392c27d2c4a50\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021311564406175568",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/d85bd563e2e3adbaa0e5aab7e97392c27d2c4a50",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d85bd563e2e3adbaa0e5aab7e97392c27d2c4a50",
"gitiles_ref:refs/heads/master"
]
}
[07:44:44.925] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612770284221282",
"id": "8855881502410718656",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d85bd563e2e3adbaa0e5aab7e97392c27d2c4a50\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612770284656127",
"tags": [
"build_address:luci.chromium.ci/win-asan/17235",
"builder:win-asan",
"buildset:commit/git/d85bd563e2e3adbaa0e5aab7e97392c27d2c4a50",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d85bd563e2e3adbaa0e5aab7e97392c27d2c4a50",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021311564406175568",
"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/8855881502410718656/+/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": "1612770284656239",
"url": "https://ci.chromium.org/b/8855881502410718656",
"utcnow_ts": "1612770284904291"
}
}
[07:44:44.925] Task URL: https://ci.chromium.org/b/8855881502410718656
[07:44:44.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:2:0) after 1m0s
[07:44:52.354] Received PubSub notification, asking Buildbucket for the build status
[07:44:52.418] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:44.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:2:0)
[07:45:44.994] Timer tick, asking Buildbucket for the build status
[07:45:45.096] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:45.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:4:0) after 1m0s
[07:46:45.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:4:0)
[07:46:45.482] Timer tick, asking Buildbucket for the build status
[07:46:45.610] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:45.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:5:0) after 1m0s
[07:47:45.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:5:0)
[07:47:45.867] Timer tick, asking Buildbucket for the build status
[07:47:46.064] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:46.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:6:0) after 1m0s
[07:48:46.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:6:0)
[07:48:46.235] Timer tick, asking Buildbucket for the build status
[07:48:46.343] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:46.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:7:0) after 1m0s
[07:49:46.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:7:0)
[07:49:46.430] Timer tick, asking Buildbucket for the build status
[07:49:46.570] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:46.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:8:0) after 1m0s
[07:50:46.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:8:0)
[07:50:46.634] Timer tick, asking Buildbucket for the build status
[07:50:46.696] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:46.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:9:0) after 1m0s
[07:51:46.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:9:0)
[07:51:46.804] Timer tick, asking Buildbucket for the build status
[07:51:46.863] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:46.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:10:0) after 1m0s
[07:52:46.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:10:0)
[07:52:46.971] Timer tick, asking Buildbucket for the build status
[07:52:47.051] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:47.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:11:0) after 1m0s
[07:53:47.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:11:0)
[07:53:47.154] Timer tick, asking Buildbucket for the build status
[07:53:47.645] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:47.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:12:0) after 1m0s
[07:54:47.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:12:0)
[07:54:47.711] Timer tick, asking Buildbucket for the build status
[07:54:47.804] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:47.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:13:0) after 1m0s
[07:55:47.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:13:0)
[07:55:47.878] Timer tick, asking Buildbucket for the build status
[07:55:47.973] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:47.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:14:0) after 1m0s
[07:56:48.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:14:0)
[07:56:48.157] Timer tick, asking Buildbucket for the build status
[07:56:48.236] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:48.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:15:0) after 1m0s
[07:57:48.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:15:0)
[07:57:48.337] Timer tick, asking Buildbucket for the build status
[07:57:48.524] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:48.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:16:0) after 1m0s
[07:58:48.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:16:0)
[07:58:48.541] Timer tick, asking Buildbucket for the build status
[07:58:48.604] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:48.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:17:0) after 1m0s
[07:59:48.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:17:0)
[07:59:48.845] Timer tick, asking Buildbucket for the build status
[07:59:48.944] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:48.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:18:0) after 1m0s
[08:00:49.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:18:0)
[08:00:49.226] Timer tick, asking Buildbucket for the build status
[08:00:49.352] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:49.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:19:0) after 1m0s
[08:01:49.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:19:0)
[08:01:49.552] Timer tick, asking Buildbucket for the build status
[08:01:49.621] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:49.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:20:0) after 1m0s
[08:02:49.773] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:20:0)
[08:02:49.773] Timer tick, asking Buildbucket for the build status
[08:02:49.881] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:49.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:21:0) after 1m0s
[08:03:49.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:21:0)
[08:03:49.905] Timer tick, asking Buildbucket for the build status
[08:03:49.999] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:49.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:22:0) after 1m0s
[08:04:50.096] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:22:0)
[08:04:50.096] Timer tick, asking Buildbucket for the build status
[08:04:50.251] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:50.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:23:0) after 1m0s
[08:05:50.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:23:0)
[08:05:50.308] Timer tick, asking Buildbucket for the build status
[08:05:50.615] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:50.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:24:0) after 1m0s
[08:06:50.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:24:0)
[08:06:50.761] Timer tick, asking Buildbucket for the build status
[08:06:50.909] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:50.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:25:0) after 1m0s
[08:07:50.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:25:0)
[08:07:50.936] Timer tick, asking Buildbucket for the build status
[08:07:51.041] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:51.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:26:0) after 1m0s
[08:08:51.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:26:0)
[08:08:51.061] Timer tick, asking Buildbucket for the build status
[08:08:51.166] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:51.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:27:0) after 1m0s
[08:09:51.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:27:0)
[08:09:51.265] Timer tick, asking Buildbucket for the build status
[08:09:51.633] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:51.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:28:0) after 1m0s
[08:10:51.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:28:0)
[08:10:51.651] Timer tick, asking Buildbucket for the build status
[08:10:51.767] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:51.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:29:0) after 1m0s
[08:11:51.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:29:0)
[08:11:51.952] Timer tick, asking Buildbucket for the build status
[08:11:52.091] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:52.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:30:0) after 1m0s
[08:12:52.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:30:0)
[08:12:52.105] Timer tick, asking Buildbucket for the build status
[08:12:54.090] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:54.090] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:31:0) after 1m0s
[08:13:54.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:31:0)
[08:13:54.123] Timer tick, asking Buildbucket for the build status
[08:13:55.780] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:55.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:32:0) after 1m0s
[08:14:55.799] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:32:0)
[08:14:55.799] Timer tick, asking Buildbucket for the build status
[08:14:56.295] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:56.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:33:0) after 1m0s
[08:15:56.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:33:0)
[08:15:56.354] Timer tick, asking Buildbucket for the build status
[08:15:57.639] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:57.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:34:0) after 1m0s
[08:16:57.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:34:0)
[08:16:57.736] Timer tick, asking Buildbucket for the build status
[08:16:57.921] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:57.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:35:0) after 1m0s
[08:17:57.996] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:35:0)
[08:17:57.996] Timer tick, asking Buildbucket for the build status
[08:17:58.463] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:58.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:36:0) after 1m0s
[08:18:58.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:36:0)
[08:18:58.639] Timer tick, asking Buildbucket for the build status
[08:18:58.791] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:58.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:37:0) after 1m0s
[08:19:58.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:37:0)
[08:19:58.958] Timer tick, asking Buildbucket for the build status
[08:19:59.474] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:59.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:38:0) after 1m0s
[08:20:59.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:38:0)
[08:20:59.478] Timer tick, asking Buildbucket for the build status
[08:20:59.585] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:59.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:39:0) after 1m0s
[08:21:59.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:39:0)
[08:21:59.616] Timer tick, asking Buildbucket for the build status
[08:21:59.769] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:59.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:40:0) after 1m0s
[08:22:59.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:40:0)
[08:22:59.823] Timer tick, asking Buildbucket for the build status
[08:22:59.949] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:59.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:41:0) after 1m0s
[08:24:00.009] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:41:0)
[08:24:00.009] Timer tick, asking Buildbucket for the build status
[08:24:00.241] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:00.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:42:0) after 1m0s
[08:25:00.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:42:0)
[08:25:00.439] Timer tick, asking Buildbucket for the build status
[08:25:00.946] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:00.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:43:0) after 1m0s
[08:26:00.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:43:0)
[08:26:00.965] Timer tick, asking Buildbucket for the build status
[08:26:01.178] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:01.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:44:0) after 1m0s
[08:27:01.160] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:44:0)
[08:27:01.160] Timer tick, asking Buildbucket for the build status
[08:27:01.257] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:01.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:45:0) after 1m0s
[08:28:01.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:45:0)
[08:28:01.388] Timer tick, asking Buildbucket for the build status
[08:28:01.454] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:01.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:46:0) after 1m0s
[08:29:01.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:46:0)
[08:29:01.518] Timer tick, asking Buildbucket for the build status
[08:29:01.657] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:01.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:47:0) after 1m0s
[08:30:01.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:47:0)
[08:30:01.723] Timer tick, asking Buildbucket for the build status
[08:30:02.259] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:02.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:48:0) after 1m0s
[08:31:02.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:48:0)
[08:31:02.285] Timer tick, asking Buildbucket for the build status
[08:31:02.626] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:02.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:49:0) after 1m0s
[08:32:02.941] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:49:0)
[08:32:02.941] Timer tick, asking Buildbucket for the build status
[08:32:03.071] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:03.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:50:0) after 1m0s
[08:33:03.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:50:0)
[08:33:03.185] Timer tick, asking Buildbucket for the build status
[08:33:03.430] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:03.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:51:0) after 1m0s
[08:34:03.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:51:0)
[08:34:03.544] Timer tick, asking Buildbucket for the build status
[08:34:03.851] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:03.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:52:0) after 1m0s
[08:35:03.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:52:0)
[08:35:03.868] Timer tick, asking Buildbucket for the build status
[08:35:04.000] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:04.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:53:0) after 1m0s
[08:36:04.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:53:0)
[08:36:04.152] Timer tick, asking Buildbucket for the build status
[08:36:04.737] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:04.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:54:0) after 1m0s
[08:37:04.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:54:0)
[08:37:04.940] Timer tick, asking Buildbucket for the build status
[08:37:05.039] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:05.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:55:0) after 1m0s
[08:38:05.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:55:0)
[08:38:05.220] Timer tick, asking Buildbucket for the build status
[08:38:05.601] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:05.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:56:0) after 1m0s
[08:39:05.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:56:0)
[08:39:05.712] Timer tick, asking Buildbucket for the build status
[08:39:06.318] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:06.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:57:0) after 1m0s
[08:40:06.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:57:0)
[08:40:06.485] Timer tick, asking Buildbucket for the build status
[08:40:07.207] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:07.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:58:0) after 1m0s
[08:41:07.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:58:0)
[08:41:07.501] Timer tick, asking Buildbucket for the build status
[08:41:07.799] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:07.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:59:0) after 1m0s
[08:42:07.920] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:59:0)
[08:42:07.920] Timer tick, asking Buildbucket for the build status
[08:42:08.431] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:08.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:60:0) after 1m0s
[08:43:08.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:60:0)
[08:43:08.557] Timer tick, asking Buildbucket for the build status
[08:43:08.703] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:08.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:61:0) after 1m0s
[08:44:08.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:61:0)
[08:44:08.812] Timer tick, asking Buildbucket for the build status
[08:44:08.909] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:08.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:62:0) after 1m0s
[08:45:08.908] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:62:0)
[08:45:08.908] Timer tick, asking Buildbucket for the build status
[08:45:09.497] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:09.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:63:0) after 1m0s
[08:46:09.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:63:0)
[08:46:09.618] Timer tick, asking Buildbucket for the build status
[08:46:10.005] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:10.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:64:0) after 1m0s
[08:47:10.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:64:0)
[08:47:10.049] Timer tick, asking Buildbucket for the build status
[08:47:10.155] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:10.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:65:0) after 1m0s
[08:48:10.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:65:0)
[08:48:10.210] Timer tick, asking Buildbucket for the build status
[08:48:10.550] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:10.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:66:0) after 1m0s
[08:49:10.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:66:0)
[08:49:10.581] Timer tick, asking Buildbucket for the build status
[08:49:10.743] Build 8855881502410718656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:10.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021311564406175568:67:0) after 1m0s
[08:50:07.879] Received PubSub notification, asking Buildbucket for the build status
[08:50:08.529] Build 8855881502410718656: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:50:08.529] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612774207102057",
"created_by": "project:chromium",
"created_ts": "1612770284221282",
"id": "8855881502410718656",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d85bd563e2e3adbaa0e5aab7e97392c27d2c4a50\"}}",
"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\": \"d85bd563e2e3adbaa0e5aab7e97392c27d2c4a50\", \"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-7-1hzs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17235, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"6b20e1c884885eb6a5603e88a561a3ba21cc615b\", \"got_dawn_revision\": \"2831f0584028114a8896059ce00e7bddaf3bba1f\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"d85bd563e2e3adbaa0e5aab7e97392c27d2c4a50\", \"got_revision_cp\": \"refs/heads/master@{#851622}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"266614a36dbd5328a9079ea6bbef9026c7bbe3d7\", \"got_v8_revision\": \"462378addab907ab7452d38baccb27fe2ef7289a\", \"got_v8_revision_cp\": \"refs/heads/9.0.138@{#1}\", \"got_webrtc_revision\": \"c3c63c8ceaf4ac3646b1e17bfe8a97e7102f0913\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33185}\", \"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\": \"d85bd563e2e3adbaa0e5aab7e97392c27d2c4a50\", \"swarm_hashes_refs/heads/master(at){#851622}_without_patch\": {\"absl_hardening_tests\": \"e923a172c088f98e05d18ec38e1f1a09870cd82b\", \"accessibility_unittests\": \"652bebe66f18911222eb898dda026c9109a2b773\", \"angle_unittests\": \"4c56b8d542e88a7950c7c433d6b10b4567bed2ba\", \"app_shell_unittests\": \"4b294a270727874b5b1086dde3341c7351655007\", \"aura_unittests\": \"aa776192b4ccd218f3c5e5769491ca6d6ab73030\", \"base_unittests\": \"81f189f0845d4f4fb7c87d7f00657ce68c32a75e\", \"base_util_unittests\": \"cac3139eb4c7310596545a872b20b3535465153c\", \"blink_common_unittests\": \"56dfbd9eb5dbd5eb9f27f0c2e46fed5d4f17010a\", \"blink_fuzzer_unittests\": \"e961d89ccd6074e3e32e356dfc0cf5230f4917dd\", \"blink_heap_unittests\": \"a68f88d7c27da692cab201096bcf2f98096696bd\", \"blink_platform_unittests\": \"a87d11cd2b1c651336f79c2e1f16fbf39146577c\", \"blink_unittests\": \"ffcb1758d1bc5d23ee68c2d67cef4540e2378be2\", \"boringssl_crypto_tests\": \"2ce249d500b147cb8b3383d194860d56c98980e5\", \"boringssl_ssl_tests\": \"285463a5fbab12512d318753623a9364b24cc624\", \"browser_switcher_bho_unittests\": \"b0e86d55a229bc47f2f00fdc763151c979864031\", \"browser_tests\": \"19574c3d52f8b362bab2fd3d8536f00b3eebdde3\", \"capture_unittests\": \"5effa706d4c924fb06514744b4d63dccb76ddb71\", \"cast_unittests\": \"1b6702212a8bd68f5d71871414b4f0aeb62f887b\", \"cc_unittests\": \"2bb8df30a799ca12cd6060c08a2a1b202cb81729\", \"chrome_app_unittests\": \"97ffb1e83780026632d0740d40ad6f8c74a3ae95\", \"chrome_cleaner_unittests\": \"16d15e1d02d791f385a8fc0d5f7ee8e3bb7fa731\", \"chrome_elf_unittests\": \"1cc48991f88db4143e52ca705313dcfa6f0e3eaa\", \"chromedriver_unittests\": \"1a23cfc73bc3dd609a686b1cb948a224b3e1b52d\", \"components_browsertests\": \"94a18142a2a6baf230155bb3e347787843f4d7eb\", \"components_unittests\": \"1b0d3ef922633723efb0cd394c5fa9b801f3763d\", \"compositor_unittests\": \"67510517f03bdd5d7c57df11552097a3d10adde1\", \"content_browsertests\": \"fe00eed69e3f463b411cc3043cd346454dabb289\", \"content_unittests\": \"9e8b48e41ab2d05423d3c2f96dc329b50d0ee6b3\", \"courgette_unittests\": \"227317c338ac853b0db91a6712b18156ca99beef\", \"crashpad_tests\": \"659ce6a3aa3f7bd13949413748e068bee8f094a7\", \"cronet_tests\": \"e48cdce59179f1c7b4debf2f379ed52de213d5cb\", \"cronet_unittests\": \"7c741bd6691e07cd6495d426cc0c1ad5fa1c04f6\", \"crypto_unittests\": \"019ce5214aeac7b9ac33888c8e1feff043d7a407\", \"delayloads_unittests\": \"5d5b2e43f4ca7cb7bbb987cf6e3c92019d787c78\", \"device_unittests\": \"237fbb85e8e1be9406b378e2f5af0570fe92dce9\", \"display_unittests\": \"c856634c238cf2cb4e6b16f0056489c584a97295\", \"elevation_service_unittests\": \"d626ae6ac9497eb7222e2a8cea555c0b5dc46982\", \"events_unittests\": \"626241691f9405ab3e3f93af3532e7620763273d\", \"extensions_browsertests\": \"848fe1dba4d0323a7326c5fff4f022be215ea273\", \"extensions_unittests\": \"b47b58e4b03f191d3dbdef3cb8d834294c7d8127\", \"filesystem_service_unittests\": \"f5203684e6a5572a42b80d920341efd6bd7cb6fa\", \"gcm_unit_tests\": \"9a2b3cc714aa032a00f8444ac8b3501478df2574\", \"gcp_unittests\": \"26c0d10c32f723f8903a4c19f65d7fedb9c021c2\", \"gfx_unittests\": \"0c5c9caa8f228861b2563c5f69efcd0bf43d02af\", \"gin_unittests\": \"3f1f57455883b9d84012ff738be54a8e942e5050\", \"google_apis_unittests\": \"1c8457e6286f52f02b863d37ec6821cccd0c3761\", \"gpu_unittests\": \"8a636fb8e9ff2bd4dd3993cf169eb65200fc4056\", \"gwp_asan_unittests\": \"c3e390a489c6b38809020c26229665d2f4d1e4b6\", \"headless_browsertests\": \"9984da090cef8274e4b4b0907320381147777934\", \"headless_unittests\": \"381102b6bd0743bced9864f8b7f524d85594aa90\", \"install_static_unittests\": \"fa0be9393cf2b60d06941a4e3147a34b2ae9bc0d\", \"installer_util_unittests\": \"1d37cff1cf668976730412157320dda9bc574002\", \"interactive_ui_tests\": \"afc8ca5c8d54bd6d0721b712bae24afafb663725\", \"ipc_tests\": \"979222984a13ac3caed867431102b59bdc3c4365\", \"jingle_unittests\": \"83f9e9b3177a560e6e4e530b622910e53baa0b4c\", \"latency_unittests\": \"cc6a1249570d04ac4002c23c0f0148295ad37a8b\", \"libjingle_xmpp_unittests\": \"e5a88047c4a5809b812962c6d79bcbc7bb9a0e1b\", \"liburlpattern_unittests\": \"1e38d5b7f267e3ca5b11688cd8ab3152c7d9bee0\", \"media_blink_unittests\": \"219d47d7893a4e499a9f2d945f0e260ec11d2b88\", \"media_unittests\": \"a3095c2333d6e07c7bacd93c4e6205592301841c\", \"message_center_unittests\": \"da0fb37c1305e5a91c8e7a99870193d1ede04c60\", \"midi_unittests\": \"07fc535839e9fcd4a579240f63dc0d446b55836b\", \"mojo_core_unittests\": \"61a9f0b3426be0e1622a2433fa95a2be2b3f5c49\", \"mojo_unittests\": \"02811fb31fd2e8c23e9c94115a4ee0052aac0266\", \"nacl_loader_unittests\": \"5f52d61bebb0fe0b9c63ab669a45aa0ec0fdeca7\", \"native_theme_unittests\": \"12ea67b9ba5b38821435430f23e18167718ef17e\", \"net_unittests\": \"0f82ebd3fd034069e00b0cf8d20127e659dd3c0a\", \"notification_helper_unittests\": \"8a63806a67fc1840aa982b904e9e7c01e40e327a\", \"pdf_unittests\": \"d18b45e7155095f22b4d37949628ebc40a7034d2\", \"perfetto_unittests\": \"a006052ed55b90510625c6629b5c6c6eebf448ff\", \"ppapi_unittests\": \"eabb0a1a40de6220673f42b30923826b3a775158\", \"printing_unittests\": \"3be7801c8c593308ee4489dd9553f5854db100ad\", \"remoting_unittests\": \"deab2668e6985c5618407e452f943d30e5b4ab4d\", \"sbox_integration_tests\": \"7834297f0b2125a54210a55c9f289e85925a8f01\", \"sbox_unittests\": \"c3561d031d353db6e4aba1992cb3d5f48902aa64\", \"sbox_validation_tests\": \"9e1cc4ffee760e39ebd3273281939f4c71336314\", \"service_manager_unittests\": \"760bc4ab86c7e13c7e6f36200524060862767da9\", \"services_unittests\": \"a8a80a9d122dda47b359f2942b0e1a8e3419844a\", \"setup_unittests\": \"7e0d8371fabee2b4a35146006045f676feeaced4\", \"shell_dialogs_unittests\": \"50a4e09483d06adde3bcdb1308f8884ca7d3a315\", \"skia_unittests\": \"59c827e281c639ccac26a6aae1c8062d454a528b\", \"snapshot_unittests\": \"b8e40baab8f5e7fc4c88d49dd6ddb6a33d126e56\", \"sql_unittests\": \"79bf7f461d34af2644e12f3f91df634c6153adc1\", \"storage_unittests\": \"25f431f5d47c7a1ddabaa02a7504122479a652eb\", \"sync_integration_tests\": \"5c6a66500fa50b1ebbf00b5ff174f32178e2dfd5\", \"traffic_annotation_auditor_unittests\": \"9cf4231e23378d54a7c92e87c281b69687f92b1c\", \"ui_base_unittests\": \"0d5e7da6344305b0aaf12a5766571c24b2dd326f\", \"ui_touch_selection_unittests\": \"a9bae58fa2410e351b2870f8afbc85529ee2c6fd\", \"unit_tests\": \"8d9bf07176356c65b1d67712ca6992bc34661996\", \"updater_tests\": \"4ec1bf66deca39a1aab05f550409b3d206c248bc\", \"url_unittests\": \"3d339b31ceceaab22834c71d3a9ff58b6b1096c7\", \"views_unittests\": \"b1e56410123c0d541fe662f6b67cff8e389c9fc6\", \"viz_unittests\": \"7e360e3684653e2afb929958c90edba27b3b7c1e\", \"vr_common_unittests\": \"62d3939ea528d8f531ec7f935c7ad8dfd362bff0\", \"vr_pixeltests\": \"045caf2019230d33109a0a192d496f2d1dabf180\", \"weblayer_browsertests\": \"d6be4b4b0ea9eb1ec52bbb8dff6ddca2b1754edf\", \"weblayer_unittests\": \"7566d96e96235652c74483b3edc92face7ac9bac\", \"wm_unittests\": \"0677d29094d116bb5e472f88c3a6b8ea12536bf4\", \"wtf_unittests\": \"681981bd2f026dc712b0907dc0c139942c12f29f\", \"zlib_unittests\": \"900318e66f2280297884e1cf9aebe09bf35a6c96\", \"zucchini_unittests\": \"d2c53134b45b4fe241590a278f97769626ed653f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"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-7-1hzs\"], \"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\": [\"5681-01e7fd1\"], \"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": "1612770288228769",
"status": "COMPLETED",
"status_changed_ts": "1612774207453384",
"tags": [
"build_address:luci.chromium.ci/win-asan/17235",
"builder:win-asan",
"buildset:commit/git/d85bd563e2e3adbaa0e5aab7e97392c27d2c4a50",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d85bd563e2e3adbaa0e5aab7e97392c27d2c4a50",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021311564406175568",
"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/8855881502410718656/+/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:51996b210f49f710",
"user_agent:luci-scheduler"
],
"updated_ts": "1612774207457655",
"url": "https://ci.chromium.org/b/8855881502410718656",
"utcnow_ts": "1612774208434563"
}
}
[08:50:08.530] Invocation finished in 1h5m25.595049498s with status SUCCEEDED