[05:28:04.889] New invocation is queued and will start shortly
[05:28:05.936] Starting the invocation (attempt 1)
[05:28:05.984] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:28:05.984] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:28:05.984] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019780012269783424",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"716d046b3f8fea322e8da9743960c335236c53f1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019780012269783424",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/716d046b3f8fea322e8da9743960c335236c53f1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/716d046b3f8fea322e8da9743960c335236c53f1",
"gitiles_ref:refs/heads/master"
]
}
[05:28:07.091] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614230886151156",
"id": "8854349950281061696",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"716d046b3f8fea322e8da9743960c335236c53f1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614230886948918",
"tags": [
"build_address:luci.chromium.ci/win-asan/17520",
"builder:win-asan",
"buildset:commit/git/716d046b3f8fea322e8da9743960c335236c53f1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/716d046b3f8fea322e8da9743960c335236c53f1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019780012269783424",
"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/8854349950281061696/+/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": "1614230886949075",
"url": "https://ci.chromium.org/b/8854349950281061696",
"utcnow_ts": "1614230887081795"
}
}
[05:28:07.091] Task URL: https://ci.chromium.org/b/8854349950281061696
[05:28:07.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:2:0) after 1m0s
[05:28:10.077] Received PubSub notification, asking Buildbucket for the build status
[05:28:10.185] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:07.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:2:0)
[05:29:07.347] Timer tick, asking Buildbucket for the build status
[05:29:07.410] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:07.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:4:0) after 1m0s
[05:30:07.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:4:0)
[05:30:07.550] Timer tick, asking Buildbucket for the build status
[05:30:07.624] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:07.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:5:0) after 1m0s
[05:31:07.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:5:0)
[05:31:07.813] Timer tick, asking Buildbucket for the build status
[05:31:08.061] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:08.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:6:0) after 1m0s
[05:32:08.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:6:0)
[05:32:08.186] Timer tick, asking Buildbucket for the build status
[05:32:08.302] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:08.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:7:0) after 1m0s
[05:33:08.443] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:7:0)
[05:33:08.443] Timer tick, asking Buildbucket for the build status
[05:33:08.670] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:08.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:8:0) after 1m0s
[05:34:08.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:8:0)
[05:34:08.757] Timer tick, asking Buildbucket for the build status
[05:34:08.843] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:08.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:9:0) after 1m0s
[05:35:08.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:9:0)
[05:35:08.915] Timer tick, asking Buildbucket for the build status
[05:35:09.029] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:09.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:10:0) after 1m0s
[05:36:09.041] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:10:0)
[05:36:09.041] Timer tick, asking Buildbucket for the build status
[05:36:09.127] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:09.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:11:0) after 1m0s
[05:37:09.141] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:11:0)
[05:37:09.141] Timer tick, asking Buildbucket for the build status
[05:37:09.222] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:09.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:12:0) after 1m0s
[05:38:09.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:12:0)
[05:38:09.328] Timer tick, asking Buildbucket for the build status
[05:38:09.412] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:09.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:13:0) after 1m0s
[05:39:09.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:13:0)
[05:39:09.420] Timer tick, asking Buildbucket for the build status
[05:39:09.503] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:09.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:14:0) after 1m0s
[05:40:09.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:14:0)
[05:40:09.487] Timer tick, asking Buildbucket for the build status
[05:40:09.572] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:09.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:15:0) after 1m0s
[05:41:09.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:15:0)
[05:41:09.633] Timer tick, asking Buildbucket for the build status
[05:41:09.843] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:09.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:16:0) after 1m0s
[05:42:09.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:16:0)
[05:42:09.960] Timer tick, asking Buildbucket for the build status
[05:42:10.254] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:10.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:17:0) after 1m0s
[05:43:10.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:17:0)
[05:43:10.369] Timer tick, asking Buildbucket for the build status
[05:43:10.464] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:10.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:18:0) after 1m0s
[05:44:10.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:18:0)
[05:44:10.509] Timer tick, asking Buildbucket for the build status
[05:44:10.893] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:10.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:19:0) after 1m0s
[05:45:10.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:19:0)
[05:45:10.911] Timer tick, asking Buildbucket for the build status
[05:45:11.100] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:11.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:20:0) after 1m0s
[05:46:11.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:20:0)
[05:46:11.150] Timer tick, asking Buildbucket for the build status
[05:46:11.211] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:11.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:21:0) after 1m0s
[05:47:11.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:21:0)
[05:47:11.304] Timer tick, asking Buildbucket for the build status
[05:47:11.386] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:11.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:22:0) after 1m0s
[05:48:11.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:22:0)
[05:48:11.508] Timer tick, asking Buildbucket for the build status
[05:48:11.624] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:11.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:23:0) after 1m0s
[05:49:11.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:23:0)
[05:49:11.661] Timer tick, asking Buildbucket for the build status
[05:49:11.732] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:11.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:24:0) after 1m0s
[05:50:11.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:24:0)
[05:50:11.905] Timer tick, asking Buildbucket for the build status
[05:50:12.171] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:12.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:25:0) after 1m0s
[05:51:12.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:25:0)
[05:51:12.268] Timer tick, asking Buildbucket for the build status
[05:51:12.540] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:12.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:26:0) after 1m0s
[05:52:13.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:26:0)
[05:52:13.619] Timer tick, asking Buildbucket for the build status
[05:52:13.708] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:13.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:27:0) after 1m0s
[05:53:13.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:27:0)
[05:53:13.832] Timer tick, asking Buildbucket for the build status
[05:53:14.204] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:14.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:28:0) after 1m0s
[05:54:14.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:28:0)
[05:54:14.419] Timer tick, asking Buildbucket for the build status
[05:54:14.635] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:14.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:29:0) after 1m0s
[05:55:14.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:29:0)
[05:55:14.886] Timer tick, asking Buildbucket for the build status
[05:55:15.022] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:15.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:30:0) after 1m0s
[05:56:15.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:30:0)
[05:56:15.105] Timer tick, asking Buildbucket for the build status
[05:56:15.573] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:15.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:31:0) after 1m0s
[05:57:15.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:31:0)
[05:57:15.591] Timer tick, asking Buildbucket for the build status
[05:57:15.906] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:15.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:32:0) after 1m0s
[05:58:15.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:32:0)
[05:58:15.998] Timer tick, asking Buildbucket for the build status
[05:58:16.111] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:16.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:33:0) after 1m0s
[05:59:16.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:33:0)
[05:59:16.154] Timer tick, asking Buildbucket for the build status
[05:59:16.242] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:16.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:34:0) after 1m0s
[06:00:16.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:34:0)
[06:00:16.446] Timer tick, asking Buildbucket for the build status
[06:00:16.563] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:16.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:35:0) after 1m0s
[06:01:28.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:35:0)
[06:01:28.584] Timer tick, asking Buildbucket for the build status
[06:01:28.684] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:28.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:36:0) after 1m0s
[06:02:28.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:36:0)
[06:02:28.700] Timer tick, asking Buildbucket for the build status
[06:02:28.780] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:28.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:37:0) after 1m0s
[06:03:28.799] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:37:0)
[06:03:28.799] Timer tick, asking Buildbucket for the build status
[06:03:28.908] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:28.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:38:0) after 1m0s
[06:04:28.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:38:0)
[06:04:28.974] Timer tick, asking Buildbucket for the build status
[06:04:29.088] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:29.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:39:0) after 1m0s
[06:05:29.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:39:0)
[06:05:29.084] Timer tick, asking Buildbucket for the build status
[06:05:29.164] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:29.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:40:0) after 1m0s
[06:06:29.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:40:0)
[06:06:29.212] Timer tick, asking Buildbucket for the build status
[06:06:29.291] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:29.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:41:0) after 1m0s
[06:07:29.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:41:0)
[06:07:29.338] Timer tick, asking Buildbucket for the build status
[06:07:29.831] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:29.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:42:0) after 1m0s
[06:08:29.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:42:0)
[06:08:29.890] Timer tick, asking Buildbucket for the build status
[06:08:30.027] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:30.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:43:0) after 1m0s
[06:09:30.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:43:0)
[06:09:30.057] Timer tick, asking Buildbucket for the build status
[06:09:30.158] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:30.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:44:0) after 1m0s
[06:10:30.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:44:0)
[06:10:30.180] Timer tick, asking Buildbucket for the build status
[06:10:30.293] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:30.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:45:0) after 1m0s
[06:11:30.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:45:0)
[06:11:30.268] Timer tick, asking Buildbucket for the build status
[06:11:30.370] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:30.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:46:0) after 1m0s
[06:12:30.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:46:0)
[06:12:30.390] Timer tick, asking Buildbucket for the build status
[06:12:30.535] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:30.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:47:0) after 1m0s
[06:13:30.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:47:0)
[06:13:30.562] Timer tick, asking Buildbucket for the build status
[06:13:31.112] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:31.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:48:0) after 1m0s
[06:14:31.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:48:0)
[06:14:31.128] Timer tick, asking Buildbucket for the build status
[06:14:32.116] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:32.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:49:0) after 1m0s
[06:15:32.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:49:0)
[06:15:32.188] Timer tick, asking Buildbucket for the build status
[06:15:32.469] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:32.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:50:0) after 1m0s
[06:16:32.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:50:0)
[06:16:32.598] Timer tick, asking Buildbucket for the build status
[06:16:32.688] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:32.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:51:0) after 1m0s
[06:17:32.690] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:51:0)
[06:17:32.690] Timer tick, asking Buildbucket for the build status
[06:17:32.786] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:32.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:52:0) after 1m0s
[06:18:32.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:52:0)
[06:18:32.860] Timer tick, asking Buildbucket for the build status
[06:18:32.946] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:32.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:53:0) after 1m0s
[06:19:32.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:53:0)
[06:19:32.990] Timer tick, asking Buildbucket for the build status
[06:19:33.094] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:33.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:54:0) after 1m0s
[06:20:33.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:54:0)
[06:20:33.211] Timer tick, asking Buildbucket for the build status
[06:20:33.291] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:33.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:55:0) after 1m0s
[06:21:33.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:55:0)
[06:21:33.329] Timer tick, asking Buildbucket for the build status
[06:21:33.418] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:33.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:56:0) after 1m0s
[06:22:33.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:56:0)
[06:22:33.497] Timer tick, asking Buildbucket for the build status
[06:22:34.207] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:34.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:57:0) after 1m0s
[06:23:34.270] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:57:0)
[06:23:34.270] Timer tick, asking Buildbucket for the build status
[06:23:34.414] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:34.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:58:0) after 1m0s
[06:24:34.423] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:58:0)
[06:24:34.423] Timer tick, asking Buildbucket for the build status
[06:24:34.517] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:34.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:59:0) after 1m0s
[06:25:34.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:59:0)
[06:25:34.634] Timer tick, asking Buildbucket for the build status
[06:25:34.748] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:34.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:60:0) after 1m0s
[06:26:34.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:60:0)
[06:26:34.745] Timer tick, asking Buildbucket for the build status
[06:26:34.818] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:34.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:61:0) after 1m0s
[06:27:34.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:61:0)
[06:27:34.892] Timer tick, asking Buildbucket for the build status
[06:27:35.212] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:35.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:62:0) after 1m0s
[06:28:35.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:62:0)
[06:28:35.398] Timer tick, asking Buildbucket for the build status
[06:28:35.574] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:35.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:63:0) after 1m0s
[06:29:35.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:63:0)
[06:29:35.660] Timer tick, asking Buildbucket for the build status
[06:29:35.811] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:35.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:64:0) after 1m0s
[06:30:35.819] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:64:0)
[06:30:35.819] Timer tick, asking Buildbucket for the build status
[06:30:35.902] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:35.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:65:0) after 1m0s
[06:31:35.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:65:0)
[06:31:35.997] Timer tick, asking Buildbucket for the build status
[06:31:36.253] Build 8854349950281061696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:36.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019780012269783424:66:0) after 1m0s
[06:32:16.935] Received PubSub notification, asking Buildbucket for the build status
[06:32:17.049] Build 8854349950281061696: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:32:17.050] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614234735191638",
"created_by": "project:chromium",
"created_ts": "1614230886151156",
"id": "8854349950281061696",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"716d046b3f8fea322e8da9743960c335236c53f1\"}}",
"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\": \"716d046b3f8fea322e8da9743960c335236c53f1\", \"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-3-xpf4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17520, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"9b1c569b14e90765cdd7c07e449400e88f1d6c45\", \"got_dawn_revision\": \"5eb496b863da5b40dc5c9a713c8ca1e413811f35\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"716d046b3f8fea322e8da9743960c335236c53f1\", \"got_revision_cp\": \"refs/heads/master@{#857546}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"349abccec8f7ebd1259de11965d58790a76b0ba4\", \"got_v8_revision\": \"bd0108b4c88e0d6f2350cb79b5f363fbd02f3eb7\", \"got_v8_revision_cp\": \"refs/heads/9.0.257@{#1}\", \"got_webrtc_revision\": \"cd0373f013b5c9672c05e79a48bb1e9fb5f50fa4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33333}\", \"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\": \"716d046b3f8fea322e8da9743960c335236c53f1\", \"swarm_hashes_refs/heads/master(at){#857546}_without_patch\": {\"absl_hardening_tests\": \"822ffd0fd4570cc72fb2db8074e4d7df73513f13\", \"accessibility_unittests\": \"87ccf417d7d3812f37b2a4c66772f5238d3e5de2\", \"angle_unittests\": \"33f1cdd4a60e9b8585ad6785e1fa435c1c71331d\", \"app_shell_unittests\": \"d510fd45f84b8e3001e8a3103ef8a8777af692d6\", \"aura_unittests\": \"53056234539d7f9aa7d15f023883293673ecdd5d\", \"base_unittests\": \"0c7205cfb54de20fe7982d0722f24bb46146f980\", \"base_util_unittests\": \"9be17bdd3869df4309f424ab7bd1489bcfd03a33\", \"blink_common_unittests\": \"5297550e5976f188e2e244c58a2c78c6983abb53\", \"blink_fuzzer_unittests\": \"5550b0e97598ab420f9b45fe60a0e23fa82afaa0\", \"blink_heap_unittests\": \"4678beefd31f4c5c7d0ea9d352ea7bacb76e0b8d\", \"blink_platform_unittests\": \"457e22ceb53d1d6e353725d60f1d6cd0e982d992\", \"blink_unittests\": \"a6e4e653ed016cd099d6bf9903914ae50c7479e5\", \"boringssl_crypto_tests\": \"d55b80a8ac10d92f08f1330ec85069ca7c864f0a\", \"boringssl_ssl_tests\": \"20d5ebd30a97c53de9b58cc48d82bbf11aa5f7c8\", \"browser_switcher_bho_unittests\": \"97cf1b68d73c2877d945d2d10d4abdec6b2951b6\", \"browser_tests\": \"97a38b54fc2e2299bb3010cfae7ae638c1333eea\", \"capture_unittests\": \"60e9859d40d36af741d6690ad8fa5424235c9bd9\", \"cast_unittests\": \"f04145e8b134b334dba57a98d1f78f037b9ce1d9\", \"cc_unittests\": \"831e768626157bd56b38df0f84b481284819bc6c\", \"chrome_app_unittests\": \"4bd10a7b5840e3c6ae9974159a810374672df07c\", \"chrome_cleaner_unittests\": \"02577c4dcd080bded39a5c6903111a1cb76e85e9\", \"chrome_elf_unittests\": \"56698080940ade9b83dd25f29fbdbb32cecbcd10\", \"chromedriver_unittests\": \"d2e0393a93647cf88e2947a70bcc916f4bff5274\", \"color_unittests\": \"cb7e95c966ba287c29576f2c0ba5d80671be05bb\", \"components_browsertests\": \"51d9717868671e77583424eb39a7f1219d7e22fc\", \"components_unittests\": \"d45d200b6fbb1bfec4f024a5b94479407abf41ad\", \"compositor_unittests\": \"2a5005ff61d8d4fe0d7123938faa3cf55f8c80ec\", \"content_browsertests\": \"2670422b7d2391f470595a46f1af45b67b3826ab\", \"content_unittests\": \"43501d29080ac0444a7e0aae6e05afadb59bb1af\", \"courgette_unittests\": \"aa9f01fdce3f4a936417e1ca5fae6da6ee05d01c\", \"crashpad_tests\": \"5680865d6772ce1dbb0a92ae1b0386d8592e1b4c\", \"cronet_tests\": \"110a7f347fe8c977708b0cccff98eaf97974dc66\", \"cronet_unittests\": \"5c5d9d8e842f7cbb5bf608515cb51b05d0afaea0\", \"crypto_unittests\": \"f8eb77ae4c6e018d729a9671d885beae340f97b1\", \"delayloads_unittests\": \"0799b0215e182a1fee8b2784cc1ab7ec5afeb3b4\", \"device_unittests\": \"38ddb39c96680a66cd0edb3a04b8e1f1451edcd3\", \"display_unittests\": \"51b49a3fee075563e85588e4f4e40efeafd960a5\", \"elevation_service_unittests\": \"17a7c33302d83404781d959f1d73c4aae5466fb7\", \"events_unittests\": \"75997a0064cb6c3f365a0c5a1aeba24a51f7036e\", \"extensions_browsertests\": \"b219274207f0ebaf440337a0c94725e7808b063f\", \"extensions_unittests\": \"b36f82fb085382f1922ed65e1485463885a774cc\", \"filesystem_service_unittests\": \"eb25f38b78ce3687ad987ed3e29b247c92f99c1f\", \"gcm_unit_tests\": \"864c0ff4d9377cb8ad60ce46540b59e14b6a87f0\", \"gcp_unittests\": \"cf323f754e5f7b988aee7a314f18795badf416f5\", \"gfx_unittests\": \"02e60a568b5b239c60c02e5d8e667ffe0c5d1d65\", \"gin_unittests\": \"72707c2dc81be2b46201bec8944d69124b43c5f4\", \"google_apis_unittests\": \"2530b8547a05c6b7e0f0ceec9e848e8dbb773676\", \"gpu_unittests\": \"73efb56d7fe2d9ad6f7888c03d84bdb3c8812897\", \"gwp_asan_unittests\": \"55490402740cce17ad96b3444a9e5105a3932e11\", \"headless_browsertests\": \"d2f1d94ce7158ec0228a1e4a64ad45682c968634\", \"headless_unittests\": \"4e42dabfa6c3c328f465a6498c03fd8d92b91be2\", \"install_static_unittests\": \"87606610449d57020f3d116d54644faacb79c3a9\", \"installer_util_unittests\": \"fdc43e4cb49965c3da2a6ae1e2596a5669a1d438\", \"interactive_ui_tests\": \"66e8dc42ec78afeb20b7879f3989f381bf9bd33c\", \"ipc_tests\": \"87bf2b93ffdc84713c33a9b1cb161d295ddc98d9\", \"jingle_unittests\": \"4e5270efde7f1570d51d2c498af73d6c1511cd2b\", \"latency_unittests\": \"ab1c7da7b3bbe3e3a484ecc13b9878a710cfa508\", \"libjingle_xmpp_unittests\": \"f35787a0291dbec550ebf760bd9a9eb56ef539e1\", \"liburlpattern_unittests\": \"d3e90302f3f03ef6150de4e6a5f2031c97c6c6bb\", \"media_blink_unittests\": \"28a9d83fd5300af9a9aa12d67219645d151aa859\", \"media_unittests\": \"6b3d171d16efd5e0521568bb9ad0ca66e90992a6\", \"message_center_unittests\": \"eab1ab7169848d76ef0f178ce4fcc78d098f2912\", \"midi_unittests\": \"0361efd014b820bbcd239b85845e1a1a8cad29a6\", \"mojo_core_unittests\": \"7627e2a448eff11daf719b03c30d122fa47568de\", \"mojo_unittests\": \"9ea726e40ef71a33736675aa95faef9ba48264e4\", \"nacl_loader_unittests\": \"390514bf488eb96964213a39ca99c8072d471992\", \"native_theme_unittests\": \"221c5da8a23f74611e012c723e8723d271d3c5bc\", \"net_unittests\": \"798c3b201e534103056989186a1320d8fff71ff7\", \"notification_helper_unittests\": \"f0cdb6f31750ee47c41a548175e9ff9709584e52\", \"pdf_unittests\": \"1df149cde8f54343f83fe76ffd805b031d1e8b2a\", \"perfetto_unittests\": \"ea1d5f8a41c7c18bd9b25f9ad1125562c9fd2d1e\", \"ppapi_unittests\": \"92c88e9311b6505c2984eb504374663c7e2eaf0f\", \"printing_unittests\": \"d88d6ce208695b19e00f43589a0768f10266a981\", \"remoting_unittests\": \"233adb162a6c072e0fb2cb8de828465d78ad5c7d\", \"sbox_integration_tests\": \"7f4dff5df5fd2a3beeb762274bc20dc1526ed78c\", \"sbox_unittests\": \"2d0db33a2e05694a75959abc41cf5a8192ba6720\", \"sbox_validation_tests\": \"07a54b1aa558634d2abb79549a21c30347d29d9b\", \"service_manager_unittests\": \"c71d5d6b6d51f269e36aa4a79a1cda43d49c1853\", \"services_unittests\": \"3e8e3212c6765c7bb997be81a61f5a3f6aee306f\", \"setup_unittests\": \"1805e4e823175f01f4d872763f63808f756feae3\", \"shell_dialogs_unittests\": \"a1293f2706e0d6c2ac03d659a578e5456693a628\", \"skia_unittests\": \"aaa23eb5d290da6118e463ee522d3e9d0d456eee\", \"snapshot_unittests\": \"f63cb6e2fea8c03ab4d9b2f2da79dfda94c95bc7\", \"sql_unittests\": \"134badf2b7c922d1f93db7460e5743c5df136d8d\", \"storage_unittests\": \"e7080cca9a41197da1dce48339e1dab045851c45\", \"sync_integration_tests\": \"2c55da44428a022d1e54442437d666804caaa7df\", \"traffic_annotation_auditor_unittests\": \"0232a603d3592712c473942021ad57e1569ca423\", \"ui_base_unittests\": \"84d6eade42753d2200bbfcc6f03bdf313452dd5d\", \"ui_touch_selection_unittests\": \"95d94206380b5d0ee2732f6213c508773daf624a\", \"unit_tests\": \"b33f8cf05d2dad2b9fba712957aff1d68872c101\", \"updater_tests\": \"05780c1f27cc2ef0763f08c2031a839719347e0a\", \"url_unittests\": \"2933963dadf73b044761d42ae1c698433d55e08f\", \"views_unittests\": \"6d1fb33a8000dd6fec7019b5ad386857c0707fac\", \"viz_unittests\": \"1062fcc42231e9b3f40ca745623b5ab93ee7fcbc\", \"vr_common_unittests\": \"7b518b5445ea681ce7250fdb058f88147e3ae30e\", \"vr_pixeltests\": \"e385e43c2adc0c4183974d2a95a85e92772bff1d\", \"weblayer_browsertests\": \"5212d56eed07bb1dbd799f96e2efe3663dec36aa\", \"weblayer_unittests\": \"250a21c159a137e4df5b60d7624e306ff985b881\", \"wm_unittests\": \"2b3b1405091a1bc7a841eb8ebc33b2d777fb9170\", \"wtf_unittests\": \"78b6b2a1b9d29af6008f6b183cfffd51aa6a0a53\", \"zlib_unittests\": \"b7a2dcfe31281b43d3399d2f2c8fd21583145a9b\", \"zucchini_unittests\": \"2eef2c902302327f39115bd2eeebf865deb115d1\"}}, \"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-3-xpf4\"], \"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\": [\"5712-339372b\"], \"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": "1614230889238634",
"status": "COMPLETED",
"status_changed_ts": "1614234735487110",
"tags": [
"build_address:luci.chromium.ci/win-asan/17520",
"builder:win-asan",
"buildset:commit/git/716d046b3f8fea322e8da9743960c335236c53f1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/716d046b3f8fea322e8da9743960c335236c53f1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019780012269783424",
"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/8854349950281061696/+/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:51f07a238e5db510",
"user_agent:luci-scheduler"
],
"updated_ts": "1614234735498219",
"url": "https://ci.chromium.org/b/8854349950281061696",
"utcnow_ts": "1614234737037998"
}
}
[06:32:17.050] Invocation finished in 1h4m12.179440552s with status SUCCEEDED