[22:31:48.252] New invocation is queued and will start shortly
[22:31:49.364] Starting the invocation (attempt 1)
[22:31:49.398] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:31:49.398] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:31:49.398] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022252320265320368",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5273cd642100d473218b28487c6e3fcdb450b008\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022252320265320368",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/5273cd642100d473218b28487c6e3fcdb450b008",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5273cd642100d473218b28487c6e3fcdb450b008",
"gitiles_ref:refs/heads/master"
]
}
[22:31:50.284] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611873109484868",
"id": "8856822258307584304",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5273cd642100d473218b28487c6e3fcdb450b008\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611873110151925",
"tags": [
"build_address:luci.chromium.ci/win-asan/17056",
"builder:win-asan",
"buildset:commit/git/5273cd642100d473218b28487c6e3fcdb450b008",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5273cd642100d473218b28487c6e3fcdb450b008",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022252320265320368",
"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/8856822258307584304/+/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": "1611873110152886",
"url": "https://ci.chromium.org/b/8856822258307584304",
"utcnow_ts": "1611873110277950"
}
}
[22:31:50.284] Task URL: https://ci.chromium.org/b/8856822258307584304
[22:31:50.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:2:0) after 1m0s
[22:31:53.572] Received PubSub notification, asking Buildbucket for the build status
[22:31:53.961] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:50.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:2:0)
[22:32:50.382] Timer tick, asking Buildbucket for the build status
[22:32:50.734] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:50.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:4:0) after 1m0s
[22:33:51.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:4:0)
[22:33:51.121] Timer tick, asking Buildbucket for the build status
[22:33:51.358] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:51.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:5:0) after 1m0s
[22:34:51.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:5:0)
[22:34:51.672] Timer tick, asking Buildbucket for the build status
[22:34:52.341] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:52.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:6:0) after 1m0s
[22:35:52.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:6:0)
[22:35:52.329] Timer tick, asking Buildbucket for the build status
[22:35:53.138] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:53.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:7:0) after 1m0s
[22:36:53.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:7:0)
[22:36:53.256] Timer tick, asking Buildbucket for the build status
[22:36:53.392] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:53.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:8:0) after 1m0s
[22:37:53.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:8:0)
[22:37:53.408] Timer tick, asking Buildbucket for the build status
[22:37:53.549] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:53.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:9:0) after 1m0s
[22:38:53.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:9:0)
[22:38:53.576] Timer tick, asking Buildbucket for the build status
[22:38:54.721] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:54.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:10:0) after 1m0s
[22:39:54.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:10:0)
[22:39:54.810] Timer tick, asking Buildbucket for the build status
[22:39:54.889] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:54.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:11:0) after 1m0s
[22:40:54.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:11:0)
[22:40:54.949] Timer tick, asking Buildbucket for the build status
[22:40:55.057] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:55.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:12:0) after 1m0s
[22:41:55.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:12:0)
[22:41:55.079] Timer tick, asking Buildbucket for the build status
[22:41:55.680] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:55.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:13:0) after 1m0s
[22:42:55.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:13:0)
[22:42:55.824] Timer tick, asking Buildbucket for the build status
[22:42:56.155] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:56.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:14:0) after 1m0s
[22:43:56.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:14:0)
[22:43:56.222] Timer tick, asking Buildbucket for the build status
[22:43:56.284] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:56.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:15:0) after 1m0s
[22:44:56.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:15:0)
[22:44:56.324] Timer tick, asking Buildbucket for the build status
[22:44:57.131] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:57.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:16:0) after 1m0s
[22:45:57.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:16:0)
[22:45:57.188] Timer tick, asking Buildbucket for the build status
[22:45:57.359] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:57.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:17:0) after 1m0s
[22:46:57.367] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:17:0)
[22:46:57.367] Timer tick, asking Buildbucket for the build status
[22:46:57.836] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:57.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:18:0) after 1m0s
[22:47:57.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:18:0)
[22:47:57.902] Timer tick, asking Buildbucket for the build status
[22:47:58.102] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:58.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:19:0) after 1m0s
[22:48:58.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:19:0)
[22:48:58.139] Timer tick, asking Buildbucket for the build status
[22:48:58.358] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:58.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:20:0) after 1m0s
[22:49:58.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:20:0)
[22:49:58.428] Timer tick, asking Buildbucket for the build status
[22:49:58.588] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:58.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:21:0) after 1m0s
[22:50:58.637] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:21:0)
[22:50:58.637] Timer tick, asking Buildbucket for the build status
[22:50:58.725] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:58.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:22:0) after 1m0s
[22:51:58.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:22:0)
[22:51:58.778] Timer tick, asking Buildbucket for the build status
[22:51:59.108] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:59.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:23:0) after 1m0s
[22:52:59.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:23:0)
[22:52:59.111] Timer tick, asking Buildbucket for the build status
[22:52:59.912] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:59.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:24:0) after 1m0s
[22:53:59.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:24:0)
[22:53:59.965] Timer tick, asking Buildbucket for the build status
[22:54:00.144] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:00.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:25:0) after 1m0s
[22:55:00.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:25:0)
[22:55:00.182] Timer tick, asking Buildbucket for the build status
[22:55:00.355] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:00.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:26:0) after 1m0s
[22:56:01.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:26:0)
[22:56:01.108] Timer tick, asking Buildbucket for the build status
[22:56:01.570] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:01.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:27:0) after 1m0s
[22:57:02.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:27:0)
[22:57:02.194] Timer tick, asking Buildbucket for the build status
[22:57:02.323] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:02.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:28:0) after 1m0s
[22:58:02.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:28:0)
[22:58:02.346] Timer tick, asking Buildbucket for the build status
[22:58:02.535] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:02.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:29:0) after 1m0s
[22:59:02.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:29:0)
[22:59:02.629] Timer tick, asking Buildbucket for the build status
[22:59:02.880] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:02.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:30:0) after 1m0s
[23:00:02.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:30:0)
[23:00:02.989] Timer tick, asking Buildbucket for the build status
[23:00:03.503] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:03.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:31:0) after 1m0s
[23:01:03.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:31:0)
[23:01:03.629] Timer tick, asking Buildbucket for the build status
[23:01:04.401] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:04.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:32:0) after 1m0s
[23:02:04.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:32:0)
[23:02:04.462] Timer tick, asking Buildbucket for the build status
[23:02:04.943] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:04.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:33:0) after 1m0s
[23:03:05.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:33:0)
[23:03:05.059] Timer tick, asking Buildbucket for the build status
[23:03:05.154] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:05.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:34:0) after 1m0s
[23:04:05.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:34:0)
[23:04:05.237] Timer tick, asking Buildbucket for the build status
[23:04:05.307] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:05.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:35:0) after 1m0s
[23:05:05.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:35:0)
[23:05:05.413] Timer tick, asking Buildbucket for the build status
[23:05:06.490] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:06.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:36:0) after 1m0s
[23:06:06.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:36:0)
[23:06:06.539] Timer tick, asking Buildbucket for the build status
[23:06:06.699] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:06.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:37:0) after 1m0s
[23:07:06.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:37:0)
[23:07:06.749] Timer tick, asking Buildbucket for the build status
[23:07:06.919] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:06.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:38:0) after 1m0s
[23:08:06.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:38:0)
[23:08:06.997] Timer tick, asking Buildbucket for the build status
[23:08:07.132] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:07.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:39:0) after 1m0s
[23:09:07.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:39:0)
[23:09:07.180] Timer tick, asking Buildbucket for the build status
[23:09:07.282] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:07.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:40:0) after 1m0s
[23:10:07.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:40:0)
[23:10:07.308] Timer tick, asking Buildbucket for the build status
[23:10:07.445] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:07.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:41:0) after 1m0s
[23:11:07.500] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:41:0)
[23:11:07.500] Timer tick, asking Buildbucket for the build status
[23:11:07.660] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:07.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:42:0) after 1m0s
[23:12:07.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:42:0)
[23:12:07.705] Timer tick, asking Buildbucket for the build status
[23:12:07.831] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:07.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:43:0) after 1m0s
[23:13:07.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:43:0)
[23:13:07.918] Timer tick, asking Buildbucket for the build status
[23:13:08.521] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:08.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:44:0) after 1m0s
[23:14:08.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:44:0)
[23:14:08.668] Timer tick, asking Buildbucket for the build status
[23:14:08.832] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:08.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:45:0) after 1m0s
[23:15:08.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:45:0)
[23:15:08.921] Timer tick, asking Buildbucket for the build status
[23:15:09.106] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:09.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:46:0) after 1m0s
[23:16:09.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:46:0)
[23:16:09.238] Timer tick, asking Buildbucket for the build status
[23:16:09.708] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:09.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:47:0) after 1m0s
[23:17:09.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:47:0)
[23:17:09.763] Timer tick, asking Buildbucket for the build status
[23:17:09.928] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:09.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:48:0) after 1m0s
[23:18:10.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:48:0)
[23:18:10.016] Timer tick, asking Buildbucket for the build status
[23:18:10.229] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:10.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:49:0) after 1m0s
[23:19:10.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:49:0)
[23:19:10.521] Timer tick, asking Buildbucket for the build status
[23:19:10.652] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:10.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:50:0) after 1m0s
[23:20:10.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:50:0)
[23:20:10.827] Timer tick, asking Buildbucket for the build status
[23:20:11.032] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:11.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:51:0) after 1m0s
[23:21:11.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:51:0)
[23:21:11.261] Timer tick, asking Buildbucket for the build status
[23:21:11.325] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:11.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:52:0) after 1m0s
[23:22:11.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:52:0)
[23:22:11.408] Timer tick, asking Buildbucket for the build status
[23:22:11.728] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:11.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:53:0) after 1m0s
[23:23:11.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:53:0)
[23:23:11.923] Timer tick, asking Buildbucket for the build status
[23:23:12.137] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:12.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:54:0) after 1m0s
[23:24:12.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:54:0)
[23:24:12.227] Timer tick, asking Buildbucket for the build status
[23:24:12.630] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:12.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:55:0) after 1m0s
[23:25:12.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:55:0)
[23:25:12.728] Timer tick, asking Buildbucket for the build status
[23:25:12.923] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:12.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:56:0) after 1m0s
[23:26:12.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:56:0)
[23:26:12.952] Timer tick, asking Buildbucket for the build status
[23:26:13.075] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:13.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:57:0) after 1m0s
[23:27:13.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:57:0)
[23:27:13.117] Timer tick, asking Buildbucket for the build status
[23:27:14.285] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:14.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:58:0) after 1m0s
[23:28:14.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:58:0)
[23:28:14.382] Timer tick, asking Buildbucket for the build status
[23:28:14.526] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:14.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:59:0) after 1m0s
[23:29:14.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:59:0)
[23:29:14.680] Timer tick, asking Buildbucket for the build status
[23:29:14.791] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:14.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:60:0) after 1m0s
[23:30:15.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:60:0)
[23:30:15.072] Timer tick, asking Buildbucket for the build status
[23:30:15.188] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:15.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:61:0) after 1m0s
[23:31:15.370] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:61:0)
[23:31:15.370] Timer tick, asking Buildbucket for the build status
[23:31:15.469] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:15.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:62:0) after 1m0s
[23:32:15.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:62:0)
[23:32:15.692] Timer tick, asking Buildbucket for the build status
[23:32:15.813] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:15.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:63:0) after 1m0s
[23:33:15.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:63:0)
[23:33:15.900] Timer tick, asking Buildbucket for the build status
[23:33:15.977] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:15.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:64:0) after 1m0s
[23:34:16.069] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:64:0)
[23:34:16.069] Timer tick, asking Buildbucket for the build status
[23:34:16.466] Build 8856822258307584304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:16.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022252320265320368:65:0) after 1m0s
[23:34:21.256] Received PubSub notification, asking Buildbucket for the build status
[23:34:21.340] Build 8856822258307584304: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:34:21.340] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611876859302099",
"created_by": "project:chromium",
"created_ts": "1611873109484868",
"id": "8856822258307584304",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5273cd642100d473218b28487c6e3fcdb450b008\"}}",
"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\": \"5273cd642100d473218b28487c6e3fcdb450b008\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-us-central1-b-32-1-nlke\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17056, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"a408ce8349289c8fe457a5ebccb8c4cbac7b9c3f\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"5273cd642100d473218b28487c6e3fcdb450b008\", \"got_revision_cp\": \"refs/heads/master@{#848289}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"609f77888b667dbb70edc38715437b69f15a4dc4\", \"got_v8_revision_cp\": \"refs/heads/9.0.99@{#1}\", \"got_webrtc_revision\": \"54b925cfc2cd7c2f8f83e8e484f281aa87b92dfc\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33090}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"prev_got_revision\": \"59f6ff500d33ce6f0df48df19348b1ce2445e63e\", \"prev_got_revision_cp\": \"refs/heads/master@{#848261}\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5273cd642100d473218b28487c6e3fcdb450b008\", \"swarm_hashes_refs/heads/master(at){#848289}_without_patch\": {\"absl_hardening_tests\": \"6549deb906c78f5a7f50a46969edf3cfd48b7081\", \"accessibility_unittests\": \"73f69521d4a5759b01bc61482027f367a8111930\", \"angle_unittests\": \"515fd3510be836a80d8ba492ded45bb0aa102b35\", \"app_shell_unittests\": \"f0114d282d7b2bc51660c68b4210ac54aaa414b8\", \"aura_unittests\": \"7786742fdcd19b1298ed9b46f68aab44e63370e0\", \"base_unittests\": \"2085d8b7cca0260fd27afef0b3d5c555820edd3a\", \"base_util_unittests\": \"8597098c2a52538d41b7ad8f9c293165845136b3\", \"blink_common_unittests\": \"3e314033e94b31456ccc71a098a033eaa78a09fb\", \"blink_fuzzer_unittests\": \"68f94a0ee3800cee260966fba2b37cc3af80b497\", \"blink_heap_unittests\": \"d97e2e9854f27ba2960c96918b79e439f58f2a8a\", \"blink_platform_unittests\": \"02678857a4153768a8830579b82d091beb8987ec\", \"blink_unittests\": \"0a023ffb713904d9eb775d2d45f79e4b36d678d5\", \"boringssl_crypto_tests\": \"c0a3fd56076039f369402aea60975dc1c5813365\", \"boringssl_ssl_tests\": \"60bca8ba13f60913a06c3c5210bfcba04e52aff5\", \"browser_switcher_bho_unittests\": \"62bb910a3c2025d59aae6df069f86806697abc39\", \"browser_tests\": \"8972fe51fade16634c8c668771a57a03a2a875a4\", \"capture_unittests\": \"d66a06b17aa90fd6520d1edfb62611e4f25dd21b\", \"cast_unittests\": \"fd8d70ecf0963b6835199fe1943610b1b26e91e5\", \"cc_unittests\": \"fb860f4036cf688268e92b0caa2f05cd7f483198\", \"chrome_app_unittests\": \"bea50f4d7b8b9f366177e05c23e07fd3bd644a8f\", \"chrome_cleaner_unittests\": \"873efc0440bc49b4e79b721037b9ea9750c90ec8\", \"chrome_elf_unittests\": \"e73cd52cdc1547f9635935104f1a9bf4ba3177db\", \"chromedriver_unittests\": \"01aa422a388ec3274240d0266a6fcb4f386a7635\", \"components_browsertests\": \"1cdf66eaaa62f49e87fd54eed0f73096201fb630\", \"components_unittests\": \"177fc3ab49bed4e3410cea70e1bbb55b6307cfa7\", \"compositor_unittests\": \"5a60d6f2090011094941a074d3802d5e1f0e21bb\", \"content_browsertests\": \"6e7c8fef0ac5627f7696cf63e4c3f7c820b8eedc\", \"content_unittests\": \"90a747b25419f4a03af038a9b9bf290bb2f5d5e6\", \"courgette_unittests\": \"f791707c05e0aa84d0e5e216c88f6af9977aad91\", \"crashpad_tests\": \"98a740c4db76aec15a574562252ab0a0c31544ca\", \"cronet_tests\": \"ac543713f26dbac1f409988ce92875dceb390e26\", \"cronet_unittests\": \"abddbef7f39b6dbca4b9a7eb2ae9a36e4a30fcc4\", \"crypto_unittests\": \"35a3b6de716010ce5645bdad1787334e1c91e3c4\", \"delayloads_unittests\": \"bff0c26c054c624af6d5047272a41e0cba479dc9\", \"device_unittests\": \"9ac87716a4f7b2db96bc727ce200c4e9b670d2ff\", \"display_unittests\": \"60d809816b2865afdf8b00472bea1147ec7405b3\", \"elevation_service_unittests\": \"8fb2ad274784b19e5ed968b20604df9047079a48\", \"events_unittests\": \"1558b7029bdd901cc03db081153ef56616431eea\", \"extensions_browsertests\": \"8799e44eb13579186f216318d7f782d44d9d8906\", \"extensions_unittests\": \"5c064ce124c5f267cb3eddf7c1c53e30d04d40d6\", \"filesystem_service_unittests\": \"c932ba6b74262a474cebe3aac06d732b432067e7\", \"gcm_unit_tests\": \"43300be5d3dbe4041e9bfad6625ebb15bf2ccd80\", \"gcp_unittests\": \"0f93a6c7efff99c303bdb378b0d3c4983b32d579\", \"gfx_unittests\": \"7c4469c56f92022a01d67561ca671d9b66580fb8\", \"gin_unittests\": \"1b36dcf674f92a681aca5d0c2f1504a3a1703282\", \"google_apis_unittests\": \"5827e319caede16355aad3169487f9e39e0a6287\", \"gpu_unittests\": \"94bc9ef3abf6fe8b28df6a2715051930e5dbf0f5\", \"gwp_asan_unittests\": \"130390efd7e4dcb906e6faab25c2b2b446bcc102\", \"headless_browsertests\": \"012ddd39df4cffa351c1a0c348740776977813e2\", \"headless_unittests\": \"81cd86f8b3278361db33633ae37d47e250b71dc8\", \"install_static_unittests\": \"1628118f1e0b35085bfe141fbdf1dbad96372f8b\", \"installer_util_unittests\": \"442a317028a147785beb34ef0431742a0c11fa6a\", \"interactive_ui_tests\": \"8ab86f9a3cf5e5ee745058c3867e0d14840f472f\", \"ipc_tests\": \"b6a97846f8da3ba1593ea837185339d8cb6b79b9\", \"jingle_unittests\": \"420a4f95a8ce724514304448c19c8c6310f8fa38\", \"latency_unittests\": \"900bb4acbf70cdb426c396219ef7df9908db87c6\", \"libjingle_xmpp_unittests\": \"5c6288bface3def772d5af8b383d0731f269c265\", \"liburlpattern_unittests\": \"15c26f7ca3e236c7c9837a45a1f477ab4481f8e1\", \"media_blink_unittests\": \"709a62d7447e9fe5ca29f6eeb70cf079a0c4b917\", \"media_unittests\": \"eb817ddc17e7ed8f43488edad9b32705855f9f76\", \"message_center_unittests\": \"28f7632982cbf9cc9e1db4cb7a8154cd8b7a10c6\", \"midi_unittests\": \"bd86a31af1baf92a8df95c2258202cc8f321e8cd\", \"mojo_core_unittests\": \"4459003148fab09e085234dc0327193d28a3b550\", \"mojo_unittests\": \"29b63b8236a2f276912e536dcfb99d5b07cae54b\", \"nacl_loader_unittests\": \"8710a82996f96b13547ab277dd5da90395c64c88\", \"native_theme_unittests\": \"d5af1c5821e385e93c4337eacb8c5db8ddf47dd8\", \"net_unittests\": \"75f692c6cf6eecd84d450deeb56d5fbb3e906802\", \"notification_helper_unittests\": \"fb93fc9202a5c780b2ec595ef5b7ea6517be80cb\", \"pdf_unittests\": \"15dc3c36c01c6118d73d3016c8dcc2bda7d29308\", \"perfetto_unittests\": \"eef974755a84ec8bf18c704d0ca09e2ac2b6491a\", \"ppapi_unittests\": \"9f14807d31a2af03f861efe8f402f2c3fe154353\", \"printing_unittests\": \"9dff08ab9b2069fc0d50685f7f8eba71eca7797e\", \"remoting_unittests\": \"273b5320e4c8dddec07af31975be6954cc3f5305\", \"sbox_integration_tests\": \"646ef0f78c6b1a0666d6c24ebdc05efe39597fe4\", \"sbox_unittests\": \"c9893982777afd68962284c62acdb5ee893d7668\", \"sbox_validation_tests\": \"1d716bf00aa656548bf5ab43eac6b82796f144ef\", \"service_manager_unittests\": \"de7845f6ba4366bb2ee03e1613de543be4b2b4b0\", \"services_unittests\": \"1b8bfe5004678956b46dde91d1e48fc9f355dd23\", \"setup_unittests\": \"fecdba2596058dd1ee7665ce69749f3245b06391\", \"shell_dialogs_unittests\": \"a7910fc1802b2c4654880932771517646b0dafe0\", \"skia_unittests\": \"f2f87d7821c617f627145a7deaf6d8e4cb399b6b\", \"snapshot_unittests\": \"d7bbe7310712a084a56c11b9eb4512709bbbf8a2\", \"sql_unittests\": \"5a2ca4612cab3fac449262d8e1b28f2afa1790fb\", \"storage_unittests\": \"a60da7df1b373c559807f03021a8e912e9d34e29\", \"sync_integration_tests\": \"a45e2429f850a514adcf49a3137c278033160227\", \"traffic_annotation_auditor_unittests\": \"8f210457f060dbc2e978a1fe9191b40a56f271ae\", \"ui_base_unittests\": \"a008cf17bbbd6b83f65f2dbfd269a8598a4cb1e4\", \"ui_touch_selection_unittests\": \"0b0cf1527ab8f364df51117b9986a0693a8228bb\", \"unit_tests\": \"244147265b3b3900733ec29b7c51016d212665f6\", \"updater_tests\": \"d498dd1827f3249ced7321263e1e63df6df21ac9\", \"url_unittests\": \"3c9d4afdc665f3a1a01fc33eab57481a0237e8dd\", \"views_unittests\": \"bf21f6eaa37c14a0f916bb4a90c0acb96d5b9058\", \"viz_unittests\": \"3bac803c124e3efdd5f131b1c5727e30d2fa095f\", \"vr_common_unittests\": \"3987e9d369ddf87a36fc67b132d8411ea415f5a5\", \"vr_pixeltests\": \"2a1877a1901f3d54d8e2a6b36b73c0b730efd27a\", \"weblayer_browsertests\": \"563dc77aef68698afc9c1447c288edd476f58551\", \"weblayer_unittests\": \"5b6078fc814e3ad05500288e384bd1d5c77bef39\", \"wm_unittests\": \"eb25bb6613379b7ec5156eda68782436b82cdf73\", \"wtf_unittests\": \"d8cbd7abc5bb497abae5e7201cfe66fd38d92093\", \"zlib_unittests\": \"6a9dba5a6a68bb58313b1c3a5de55c7037f4e163\", \"zucchini_unittests\": \"aa22796849e61008989c4e4e6f3c515292c3177c\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-nlke\"], \"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\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611873113036574",
"status": "COMPLETED",
"status_changed_ts": "1611876859557508",
"tags": [
"build_address:luci.chromium.ci/win-asan/17056",
"builder:win-asan",
"buildset:commit/git/5273cd642100d473218b28487c6e3fcdb450b008",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5273cd642100d473218b28487c6e3fcdb450b008",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022252320265320368",
"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/8856822258307584304/+/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:5163f156b391e810",
"user_agent:luci-scheduler"
],
"updated_ts": "1611876859561643",
"url": "https://ci.chromium.org/b/8856822258307584304",
"utcnow_ts": "1611876861321840"
}
}
[23:34:21.340] Invocation finished in 1h2m33.105615869s with status SUCCEEDED