[17:04:48.803] New invocation is queued and will start shortly
[17:04:50.180] Starting the invocation (attempt 1)
[17:04:50.268] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:04:50.268] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:04:50.268] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020279759488878976",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5325013294aaf9de5bc6330b737437d590cd331c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020279759488878976",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/5325013294aaf9de5bc6330b737437d590cd331c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5325013294aaf9de5bc6330b737437d590cd331c",
"gitiles_ref:refs/heads/master"
]
}
[17:04:51.254] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613754290465559",
"id": "8854849697079988096",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5325013294aaf9de5bc6330b737437d590cd331c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613754290952415",
"tags": [
"build_address:luci.chromium.ci/win-asan/17430",
"builder:win-asan",
"buildset:commit/git/5325013294aaf9de5bc6330b737437d590cd331c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5325013294aaf9de5bc6330b737437d590cd331c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020279759488878976",
"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/8854849697079988096/+/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": "1613754290952563",
"url": "https://ci.chromium.org/b/8854849697079988096",
"utcnow_ts": "1613754291243211"
}
}
[17:04:51.254] Task URL: https://ci.chromium.org/b/8854849697079988096
[17:04:51.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:2:0) after 1m0s
[17:05:55.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:2:0)
[17:05:55.255] Timer tick, asking Buildbucket for the build status
[17:05:55.396] Build 8854849697079988096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:05:55.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:3:0) after 1m0s
[17:06:06.464] Received PubSub notification, asking Buildbucket for the build status
[17:06:06.548] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:55.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:3:0)
[17:06:55.457] Timer tick, asking Buildbucket for the build status
[17:06:55.547] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:55.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:5:0) after 1m0s
[17:07:55.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:5:0)
[17:07:55.601] Timer tick, asking Buildbucket for the build status
[17:07:55.712] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:55.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:6:0) after 1m0s
[17:08:55.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:6:0)
[17:08:55.814] Timer tick, asking Buildbucket for the build status
[17:08:55.902] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:55.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:7:0) after 1m0s
[17:09:56.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:7:0)
[17:09:56.274] Timer tick, asking Buildbucket for the build status
[17:09:56.431] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:56.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:8:0) after 1m0s
[17:10:56.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:8:0)
[17:10:56.480] Timer tick, asking Buildbucket for the build status
[17:10:56.561] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:56.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:9:0) after 1m0s
[17:11:56.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:9:0)
[17:11:56.674] Timer tick, asking Buildbucket for the build status
[17:11:56.753] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:56.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:10:0) after 1m0s
[17:12:56.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:10:0)
[17:12:56.925] Timer tick, asking Buildbucket for the build status
[17:12:57.103] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:57.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:11:0) after 1m0s
[17:13:57.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:11:0)
[17:13:57.119] Timer tick, asking Buildbucket for the build status
[17:13:57.236] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:57.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:12:0) after 1m0s
[17:14:57.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:12:0)
[17:14:57.235] Timer tick, asking Buildbucket for the build status
[17:14:57.315] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:57.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:13:0) after 1m0s
[17:15:57.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:13:0)
[17:15:57.404] Timer tick, asking Buildbucket for the build status
[17:15:57.489] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:57.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:14:0) after 1m0s
[17:16:57.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:14:0)
[17:16:57.612] Timer tick, asking Buildbucket for the build status
[17:16:57.704] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:57.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:15:0) after 1m0s
[17:17:57.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:15:0)
[17:17:57.742] Timer tick, asking Buildbucket for the build status
[17:17:57.810] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:57.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:16:0) after 1m0s
[17:18:57.834] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:16:0)
[17:18:57.834] Timer tick, asking Buildbucket for the build status
[17:18:58.093] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:58.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:17:0) after 1m0s
[17:19:58.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:17:0)
[17:19:58.296] Timer tick, asking Buildbucket for the build status
[17:19:58.364] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:58.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:18:0) after 1m0s
[17:20:58.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:18:0)
[17:20:58.447] Timer tick, asking Buildbucket for the build status
[17:20:58.548] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:58.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:19:0) after 1m0s
[17:21:58.567] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:19:0)
[17:21:58.567] Timer tick, asking Buildbucket for the build status
[17:21:58.631] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:58.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:20:0) after 1m0s
[17:22:58.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:20:0)
[17:22:58.681] Timer tick, asking Buildbucket for the build status
[17:22:58.777] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:58.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:21:0) after 1m0s
[17:23:58.961] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:21:0)
[17:23:58.961] Timer tick, asking Buildbucket for the build status
[17:24:00.553] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:00.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:22:0) after 1m0s
[17:25:00.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:22:0)
[17:25:00.548] Timer tick, asking Buildbucket for the build status
[17:25:00.627] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:00.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:23:0) after 1m0s
[17:26:00.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:23:0)
[17:26:00.678] Timer tick, asking Buildbucket for the build status
[17:26:00.787] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:00.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:24:0) after 1m0s
[17:27:00.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:24:0)
[17:27:00.922] Timer tick, asking Buildbucket for the build status
[17:27:01.025] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:01.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:25:0) after 1m0s
[17:28:01.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:25:0)
[17:28:01.118] Timer tick, asking Buildbucket for the build status
[17:28:01.211] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:01.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:26:0) after 1m0s
[17:29:01.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:26:0)
[17:29:01.304] Timer tick, asking Buildbucket for the build status
[17:29:01.406] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:01.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:27:0) after 1m0s
[17:30:01.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:27:0)
[17:30:01.595] Timer tick, asking Buildbucket for the build status
[17:30:01.723] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:01.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:28:0) after 1m0s
[17:31:01.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:28:0)
[17:31:01.808] Timer tick, asking Buildbucket for the build status
[17:31:01.892] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:01.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:29:0) after 1m0s
[17:32:01.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:29:0)
[17:32:01.951] Timer tick, asking Buildbucket for the build status
[17:32:02.090] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:02.090] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:30:0) after 1m0s
[17:33:02.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:30:0)
[17:33:02.064] Timer tick, asking Buildbucket for the build status
[17:33:02.153] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:02.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:31:0) after 1m0s
[17:34:02.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:31:0)
[17:34:02.206] Timer tick, asking Buildbucket for the build status
[17:34:02.310] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:02.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:32:0) after 1m0s
[17:35:02.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:32:0)
[17:35:02.372] Timer tick, asking Buildbucket for the build status
[17:35:02.451] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:02.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:33:0) after 1m0s
[17:36:02.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:33:0)
[17:36:02.505] Timer tick, asking Buildbucket for the build status
[17:36:02.626] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:02.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:34:0) after 1m0s
[17:37:02.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:34:0)
[17:37:02.706] Timer tick, asking Buildbucket for the build status
[17:37:03.126] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:03.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:35:0) after 1m0s
[17:38:03.141] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:35:0)
[17:38:03.141] Timer tick, asking Buildbucket for the build status
[17:38:03.221] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:03.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:36:0) after 1m0s
[17:39:03.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:36:0)
[17:39:03.216] Timer tick, asking Buildbucket for the build status
[17:39:03.499] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:03.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:37:0) after 1m0s
[17:40:03.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:37:0)
[17:40:03.518] Timer tick, asking Buildbucket for the build status
[17:40:03.624] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:03.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:38:0) after 1m0s
[17:41:03.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:38:0)
[17:41:03.631] Timer tick, asking Buildbucket for the build status
[17:41:03.727] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:03.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:39:0) after 1m0s
[17:42:03.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:39:0)
[17:42:03.781] Timer tick, asking Buildbucket for the build status
[17:42:03.900] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:03.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:40:0) after 1m0s
[17:43:03.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:40:0)
[17:43:03.944] Timer tick, asking Buildbucket for the build status
[17:43:04.052] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:04.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:41:0) after 1m0s
[17:44:04.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:41:0)
[17:44:04.106] Timer tick, asking Buildbucket for the build status
[17:44:04.195] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:04.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:42:0) after 1m0s
[17:45:04.278] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:42:0)
[17:45:04.278] Timer tick, asking Buildbucket for the build status
[17:45:04.365] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:04.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:43:0) after 1m0s
[17:46:04.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:43:0)
[17:46:04.435] Timer tick, asking Buildbucket for the build status
[17:46:04.543] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:04.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:44:0) after 1m0s
[17:47:04.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:44:0)
[17:47:04.534] Timer tick, asking Buildbucket for the build status
[17:47:04.618] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:04.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:45:0) after 1m0s
[17:48:04.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:45:0)
[17:48:04.633] Timer tick, asking Buildbucket for the build status
[17:48:04.716] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:04.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:46:0) after 1m0s
[17:49:04.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:46:0)
[17:49:04.797] Timer tick, asking Buildbucket for the build status
[17:49:04.873] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:04.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:47:0) after 1m0s
[17:50:04.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:47:0)
[17:50:04.929] Timer tick, asking Buildbucket for the build status
[17:50:05.038] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:05.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:48:0) after 1m0s
[17:51:05.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:48:0)
[17:51:05.106] Timer tick, asking Buildbucket for the build status
[17:51:05.298] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:05.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:49:0) after 1m0s
[17:52:05.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:49:0)
[17:52:05.302] Timer tick, asking Buildbucket for the build status
[17:52:05.387] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:05.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:50:0) after 1m0s
[17:53:05.470] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:50:0)
[17:53:05.470] Timer tick, asking Buildbucket for the build status
[17:53:05.536] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:05.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:51:0) after 1m0s
[17:54:05.537] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:51:0)
[17:54:05.537] Timer tick, asking Buildbucket for the build status
[17:54:05.658] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:05.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:52:0) after 1m0s
[17:55:05.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:52:0)
[17:55:05.788] Timer tick, asking Buildbucket for the build status
[17:55:05.964] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:05.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:53:0) after 1m0s
[17:56:06.000] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:53:0)
[17:56:06.000] Timer tick, asking Buildbucket for the build status
[17:56:06.178] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:06.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:54:0) after 1m0s
[17:57:06.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:54:0)
[17:57:06.252] Timer tick, asking Buildbucket for the build status
[17:57:06.908] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:06.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:55:0) after 1m0s
[17:58:07.060] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:55:0)
[17:58:07.060] Timer tick, asking Buildbucket for the build status
[17:58:07.147] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:07.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:56:0) after 1m0s
[17:59:07.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:56:0)
[17:59:07.207] Timer tick, asking Buildbucket for the build status
[17:59:07.282] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:07.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:57:0) after 1m0s
[18:00:07.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:57:0)
[18:00:07.324] Timer tick, asking Buildbucket for the build status
[18:00:07.486] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:07.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:58:0) after 1m0s
[18:01:08.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:58:0)
[18:01:08.292] Timer tick, asking Buildbucket for the build status
[18:01:08.403] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:08.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:59:0) after 1m0s
[18:02:08.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:59:0)
[18:02:08.492] Timer tick, asking Buildbucket for the build status
[18:02:08.570] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:08.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:60:0) after 1m0s
[18:03:08.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:60:0)
[18:03:08.704] Timer tick, asking Buildbucket for the build status
[18:03:08.789] Build 8854849697079988096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:08.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020279759488878976:61:0) after 1m0s
[18:03:31.416] Received PubSub notification, asking Buildbucket for the build status
[18:03:31.492] Build 8854849697079988096: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:03:31.492] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613757809931021",
"created_by": "project:chromium",
"created_ts": "1613754290465559",
"id": "8854849697079988096",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5325013294aaf9de5bc6330b737437d590cd331c\"}}",
"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\": \"5325013294aaf9de5bc6330b737437d590cd331c\", \"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-wxwh\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17430, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"fd132e4e7a9c84333b4fb4730f79367fe75f53e7\", \"got_dawn_revision\": \"0b17eb8e19c886680b12b73ba28de8d30adccda9\", \"got_nacl_revision\": \"4ad2ff292145e8613ec371302875f0eaf8b4fa14\", \"got_revision\": \"5325013294aaf9de5bc6330b737437d590cd331c\", \"got_revision_cp\": \"refs/heads/master@{#855701}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"8603b126b3d599f0ab8781163729fd226a93cf17\", \"got_v8_revision\": \"aaf128851c4dd86978eaf9d41ce297c5af31597f\", \"got_v8_revision_cp\": \"refs/heads/9.0.217@{#1}\", \"got_webrtc_revision\": \"735e33fae0a06ae05a9c0be6f5d8b06a6ad820a4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33297}\", \"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\": \"5325013294aaf9de5bc6330b737437d590cd331c\", \"swarm_hashes_refs/heads/master(at){#855701}_without_patch\": {\"absl_hardening_tests\": \"b843e22b7f6adf371415a8a92ab833b4c3bd873e\", \"accessibility_unittests\": \"6a9d6c2399df664d6a401cdc50ab62e6abf69206\", \"angle_unittests\": \"a84663ec42e2c3a9d3a47703dcc9e7ad43c88270\", \"app_shell_unittests\": \"565dec19215a2a79fadd60a5673369d08b48af3c\", \"aura_unittests\": \"d18ea88c579470417726f4e087fd7d3ec8f3a8c8\", \"base_unittests\": \"3ab13d909fdc0ae6d52300469f4266934c6104de\", \"base_util_unittests\": \"56cf9c59b805757633e44031a9c2628d268bc8e2\", \"blink_common_unittests\": \"207f938333751498e948692e59e2b25d64eea79e\", \"blink_fuzzer_unittests\": \"f53ad42f7757fcc9d81eb95d64299137af3b806b\", \"blink_heap_unittests\": \"e0bbc7ac300eaf8d91df99654f0e3f79fba6d17f\", \"blink_platform_unittests\": \"0f633755adfd3b0082d488aa1b01f8835d5c5928\", \"blink_unittests\": \"ee8b044f8100281676331639a6633610262a6cd3\", \"boringssl_crypto_tests\": \"c8cdf0646ebcef1bbfee1ce5469a265108b9bf38\", \"boringssl_ssl_tests\": \"d79b72e571c684043a23c5610f367ac90792c73f\", \"browser_switcher_bho_unittests\": \"4a610c119e684aef8d68a0dce8974e49e2b8e544\", \"browser_tests\": \"0235ebda3ce893ce138363404eec120b9928c02c\", \"capture_unittests\": \"a6839945216c8481388cb2081a38f6b4c562ca59\", \"cast_unittests\": \"5ab3cffa6494b6c302635c4a303c83bf96237ca1\", \"cc_unittests\": \"d91e87457670d8a8373bf32021ff328dc9e8093c\", \"chrome_app_unittests\": \"2192a4d634e04280701579331beb3210ef40ba7d\", \"chrome_cleaner_unittests\": \"839e33303b4a697693c01468f0fe9a533603602c\", \"chrome_elf_unittests\": \"1fa8fbc20a54c2fe9e84db5a2e92bf6f0015e230\", \"chromedriver_unittests\": \"f4a12f93b3edcc2e3d7f6ed60810bc68c681133f\", \"components_browsertests\": \"dbdd6416d01ff830ff7cdc8f95430f34aade213b\", \"components_unittests\": \"9aff16f61ea8a672415fe572e60cad832fec7f9f\", \"compositor_unittests\": \"98e629d51ed4c18a474c2f389e1f232cd6c85c72\", \"content_browsertests\": \"0bc5563a2bd1f67a8fa3593695afa14aab396259\", \"content_unittests\": \"5bbd672803a56b712646adfca079a5873f24e2e5\", \"courgette_unittests\": \"8fd13e422187813e6484a75c65f9afcd08389a8a\", \"crashpad_tests\": \"bf9ea77d1c24a952059a9dc8c4b0657495df3e2e\", \"cronet_tests\": \"9b8250ce6192caf99bd7820d7efe5cce8bd20b30\", \"cronet_unittests\": \"51d1ce6288c4daa3e3f8f7de35ecd79abb7b45b7\", \"crypto_unittests\": \"eeedb6bb5572f0a080eccdf56f3f1bcec2c0ed66\", \"delayloads_unittests\": \"786afcc02e46de0e9f911c69f33f8bc658d87547\", \"device_unittests\": \"b1e545f38f8fb476a7d6afbdd911a431501efafb\", \"display_unittests\": \"85996e7f59f10769e144755962926adb1d339cec\", \"elevation_service_unittests\": \"fca0270ab1164d501ef2abd467807802da919c72\", \"events_unittests\": \"014783c81a45fa68b5530be4bc4c46d90f1967a7\", \"extensions_browsertests\": \"1ae298a4f28154a3ff22c59599c63d46ae42c77d\", \"extensions_unittests\": \"1785f81e6e9194f6811f72ff23103e61e0972182\", \"filesystem_service_unittests\": \"79830ca0f6aa1addd2f348a73b2ce3fac353e0a9\", \"gcm_unit_tests\": \"d166d226b0ab08baa7c6ef4d658257253b2bf285\", \"gcp_unittests\": \"9baca8e560430d5d059c19e868d93c6936ae448b\", \"gfx_unittests\": \"f13b56fb8a2706008d9676a1c8517e9f8f6d7de5\", \"gin_unittests\": \"5f2d2b2da9c02d9b48f30161e7a44a039f1cb748\", \"google_apis_unittests\": \"d0d05b4b309b9496aa8a4c97055eb05d154dacdb\", \"gpu_unittests\": \"6630fbd254a57a5923fecde6b9f3bb91e8b50d0e\", \"gwp_asan_unittests\": \"012dd857af2a1c6dccc90e23d127252743273b01\", \"headless_browsertests\": \"c04746d17aaacfeebdc81ca4b1cda13ed8bc8f1c\", \"headless_unittests\": \"3015e79c4678b78ac216bfa1f07356cf02b1780b\", \"install_static_unittests\": \"6a49d4f55727ac089a8d16c76ef7ea6fcbdad8c3\", \"installer_util_unittests\": \"200c73c9df481cff523f506d5f7baf4589a00fe9\", \"interactive_ui_tests\": \"f2fc9391058d433f8e31db81e54c19c15f9b70ad\", \"ipc_tests\": \"6639c5f9e25f98e6c1982fc907a72554003de455\", \"jingle_unittests\": \"dad7bbd9f629f3d51c7ee9d6f96b8b25a19b2f1b\", \"latency_unittests\": \"86ab683807f3e4360d01bba2680d28e2f30a1bfc\", \"libjingle_xmpp_unittests\": \"ed292da22ee59f1e1c8bd143def594810b0382d8\", \"liburlpattern_unittests\": \"c5aacd2aa1a6590ab8e188a98b9c929af7ce4cf8\", \"media_blink_unittests\": \"e4c830881faccb712d6d41e204108eceab37849b\", \"media_unittests\": \"5b1160b46c14266acc60d82130edb121cbd8e565\", \"message_center_unittests\": \"94fd210d7ea40aa65ef209e62c10e35f0b0d2955\", \"midi_unittests\": \"247bf45a7cafe10be4c61f78b950f29fc53b284b\", \"mojo_core_unittests\": \"de8c7bc4f163f1a1a01a461cbcd91606130f5172\", \"mojo_unittests\": \"0139d5feac2bd2394ca90a6a25f77f8b5c01b7db\", \"nacl_loader_unittests\": \"9849293ce5cbf1018726395043cb83675c328e9c\", \"native_theme_unittests\": \"007afb452c6fac47ff7b433bab09c5b37513c205\", \"net_unittests\": \"668aaa62169f173e2bf9f4a2987898bd0884c808\", \"notification_helper_unittests\": \"bf6e77367611e7d754fda9bda739d85d4fe1c474\", \"pdf_unittests\": \"258c8865e7e48f3b8129069c29cb2cb591d4dbaf\", \"perfetto_unittests\": \"b19370a3447ae7fa5dd44b271b5b41a8d03a238b\", \"ppapi_unittests\": \"26d4206acd2992cfa952e08bf9d752fa51977d3e\", \"printing_unittests\": \"53c25d3e86c35332e38b9fc261d8140b5d9c39b4\", \"remoting_unittests\": \"607eb4b23afa12e55583bc6d30c52af071d2b277\", \"sbox_integration_tests\": \"09ed02eb5abf3c62642384dbbdc8f2b4711109c3\", \"sbox_unittests\": \"650f46fb4674b5566108f3d1a41445ca0732f4ff\", \"sbox_validation_tests\": \"0d4c6ca253ebe59cc3f7b7abfb4b3842abb99c9f\", \"service_manager_unittests\": \"8a08d3c0b67de0d1a2f0aa5fddd0e58ba6ab03ed\", \"services_unittests\": \"a05b39f109adaddfbe8f0f21c98db3f98e9cb5ce\", \"setup_unittests\": \"253210d7e5affe7549207ec28f68e6eed23c31ea\", \"shell_dialogs_unittests\": \"628d779f8f9e291a4e236a4b79b54d4f34b7ac63\", \"skia_unittests\": \"d609e3c2175306c716755faeaef32871012dbbad\", \"snapshot_unittests\": \"f8b957d8397158322508b2974a486b32ff557d40\", \"sql_unittests\": \"d1c7690cdd927cce7c5f75add007f5946956e73e\", \"storage_unittests\": \"575c9c73c303cd56cd458d527923f9ec624d45c4\", \"sync_integration_tests\": \"843eb4dd65ca847967c4a7612d3079da55a5c36f\", \"traffic_annotation_auditor_unittests\": \"acb11df6f748190fc34121870290270f56f651a1\", \"ui_base_unittests\": \"62943911fd223223d55e1a95b09650a64aa73a11\", \"ui_touch_selection_unittests\": \"1a227ed556d2b2f5f379b4005178797e9e507ddf\", \"unit_tests\": \"84a4fccfd2b772efd06cf069cbd64ad7c085a141\", \"updater_tests\": \"ad1784d17bc81b690daedf8a5a761a43a887935f\", \"url_unittests\": \"c0a3301f063cdb2d069d836675d67eab52e9fdb1\", \"views_unittests\": \"f1005a8d0c3643f1cb643f1ccf4edccbbe146193\", \"viz_unittests\": \"465d6eda48afb6aad8af9f1b28e1bebf06bcb8be\", \"vr_common_unittests\": \"dfa41316e5bd0ccdc886b28568803a6d4c70c921\", \"vr_pixeltests\": \"3946ff0a1613876717b3a98689146340954ba7cb\", \"weblayer_browsertests\": \"9584e4416d9e43458014b2394b4f41f75a60489d\", \"weblayer_unittests\": \"caa4ffe176abd3187b4862ffc3343bbd7a91f026\", \"wm_unittests\": \"be9ee42814494d064377d351a010e2f5062c0cfb\", \"wtf_unittests\": \"7d8db3c4453c1f36c553ef1f24d0f7878be5610d\", \"zlib_unittests\": \"25e1cf74409a8e3829433d256c13b68d8db69faa\", \"zucchini_unittests\": \"b847a9c062c6dc1e4ff2d1d1d5da3ad8e3981b89\"}}, \"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-7-wxwh\"], \"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\": [\"5706-397711c\"], \"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": "1613754365691617",
"status": "COMPLETED",
"status_changed_ts": "1613757810665686",
"tags": [
"build_address:luci.chromium.ci/win-asan/17430",
"builder:win-asan",
"buildset:commit/git/5325013294aaf9de5bc6330b737437d590cd331c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5325013294aaf9de5bc6330b737437d590cd331c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020279759488878976",
"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/8854849697079988096/+/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:51d4122d6215e510",
"user_agent:luci-scheduler"
],
"updated_ts": "1613757810669618",
"url": "https://ci.chromium.org/b/8854849697079988096",
"utcnow_ts": "1613757811482823"
}
}
[18:03:31.492] Invocation finished in 58m42.707613062s with status SUCCEEDED