[21:20:50.485] New invocation is queued and will start shortly
[21:20:51.605] Starting the invocation (attempt 1)
[21:20:51.640] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:20:51.641] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:20:51.641] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022166187889972288",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d685493326d1326bc692356d281e6f5afd8b49c9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022166187889972288",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/d685493326d1326bc692356d281e6f5afd8b49c9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d685493326d1326bc692356d281e6f5afd8b49c9",
"gitiles_ref:refs/heads/master"
]
}
[21:20:53.300] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611955251809703",
"id": "8856736125837670464",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d685493326d1326bc692356d281e6f5afd8b49c9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611955252919347",
"tags": [
"build_address:luci.chromium.ci/win-asan/17074",
"builder:win-asan",
"buildset:commit/git/d685493326d1326bc692356d281e6f5afd8b49c9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d685493326d1326bc692356d281e6f5afd8b49c9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022166187889972288",
"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/8856736125837670464/+/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": "1611955252919499",
"url": "https://ci.chromium.org/b/8856736125837670464",
"utcnow_ts": "1611955253281911"
}
}
[21:20:53.300] Task URL: https://ci.chromium.org/b/8856736125837670464
[21:20:53.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:2:0) after 1m0s
[21:20:56.453] Received PubSub notification, asking Buildbucket for the build status
[21:20:56.643] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:53.349] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:2:0)
[21:21:53.349] Timer tick, asking Buildbucket for the build status
[21:21:53.438] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:53.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:4:0) after 1m0s
[21:22:53.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:4:0)
[21:22:53.456] Timer tick, asking Buildbucket for the build status
[21:22:53.552] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:53.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:5:0) after 1m0s
[21:23:53.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:5:0)
[21:23:53.546] Timer tick, asking Buildbucket for the build status
[21:23:53.630] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:53.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:6:0) after 1m0s
[21:24:53.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:6:0)
[21:24:53.684] Timer tick, asking Buildbucket for the build status
[21:24:53.949] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:53.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:7:0) after 1m0s
[21:25:53.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:7:0)
[21:25:53.952] Timer tick, asking Buildbucket for the build status
[21:25:54.302] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:54.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:8:0) after 1m0s
[21:26:54.325] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:8:0)
[21:26:54.325] Timer tick, asking Buildbucket for the build status
[21:26:54.526] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:54.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:9:0) after 1m0s
[21:27:54.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:9:0)
[21:27:54.551] Timer tick, asking Buildbucket for the build status
[21:27:54.608] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:54.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:10:0) after 1m0s
[21:28:54.698] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:10:0)
[21:28:54.698] Timer tick, asking Buildbucket for the build status
[21:28:54.767] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:54.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:11:0) after 1m0s
[21:29:54.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:11:0)
[21:29:54.801] Timer tick, asking Buildbucket for the build status
[21:29:54.871] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:54.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:12:0) after 1m0s
[21:30:55.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:12:0)
[21:30:55.042] Timer tick, asking Buildbucket for the build status
[21:30:55.214] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:55.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:13:0) after 1m0s
[21:31:55.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:13:0)
[21:31:55.292] Timer tick, asking Buildbucket for the build status
[21:31:55.475] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:55.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:14:0) after 1m0s
[21:32:55.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:14:0)
[21:32:55.566] Timer tick, asking Buildbucket for the build status
[21:32:55.845] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:55.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:15:0) after 1m0s
[21:33:55.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:15:0)
[21:33:55.902] Timer tick, asking Buildbucket for the build status
[21:33:56.003] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:56.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:16:0) after 1m0s
[21:34:56.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:16:0)
[21:34:56.037] Timer tick, asking Buildbucket for the build status
[21:34:57.851] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:57.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:17:0) after 1m0s
[21:35:57.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:17:0)
[21:35:57.875] Timer tick, asking Buildbucket for the build status
[21:35:57.974] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:57.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:18:0) after 1m0s
[21:36:58.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:18:0)
[21:36:58.015] Timer tick, asking Buildbucket for the build status
[21:36:58.079] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:58.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:19:0) after 1m0s
[21:37:58.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:19:0)
[21:37:58.231] Timer tick, asking Buildbucket for the build status
[21:37:58.348] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:58.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:20:0) after 1m0s
[21:38:58.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:20:0)
[21:38:58.386] Timer tick, asking Buildbucket for the build status
[21:38:58.514] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:58.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:21:0) after 1m0s
[21:39:58.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:21:0)
[21:39:58.604] Timer tick, asking Buildbucket for the build status
[21:39:58.909] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:58.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:22:0) after 1m0s
[21:40:59.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:22:0)
[21:40:59.130] Timer tick, asking Buildbucket for the build status
[21:40:59.444] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:59.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:23:0) after 1m0s
[21:41:59.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:23:0)
[21:41:59.527] Timer tick, asking Buildbucket for the build status
[21:41:59.688] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:59.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:24:0) after 1m0s
[21:42:59.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:24:0)
[21:42:59.709] Timer tick, asking Buildbucket for the build status
[21:43:00.307] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:00.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:25:0) after 1m0s
[21:44:00.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:25:0)
[21:44:00.796] Timer tick, asking Buildbucket for the build status
[21:44:00.875] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:00.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:26:0) after 1m0s
[21:45:01.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:26:0)
[21:45:01.178] Timer tick, asking Buildbucket for the build status
[21:45:01.411] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:01.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:27:0) after 1m0s
[21:46:01.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:27:0)
[21:46:01.459] Timer tick, asking Buildbucket for the build status
[21:46:01.573] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:01.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:28:0) after 1m0s
[21:47:01.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:28:0)
[21:47:01.552] Timer tick, asking Buildbucket for the build status
[21:47:01.663] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:01.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:29:0) after 1m0s
[21:48:01.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:29:0)
[21:48:01.723] Timer tick, asking Buildbucket for the build status
[21:48:03.471] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:03.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:30:0) after 1m0s
[21:49:03.547] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:30:0)
[21:49:03.547] Timer tick, asking Buildbucket for the build status
[21:49:03.684] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:03.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:31:0) after 1m0s
[21:50:03.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:31:0)
[21:50:03.713] Timer tick, asking Buildbucket for the build status
[21:50:03.892] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:03.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:32:0) after 1m0s
[21:51:03.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:32:0)
[21:51:03.942] Timer tick, asking Buildbucket for the build status
[21:51:04.035] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:04.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:33:0) after 1m0s
[21:52:04.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:33:0)
[21:52:04.076] Timer tick, asking Buildbucket for the build status
[21:52:04.204] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:04.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:34:0) after 1m0s
[21:53:04.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:34:0)
[21:53:04.327] Timer tick, asking Buildbucket for the build status
[21:53:04.446] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:04.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:35:0) after 1m0s
[21:54:04.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:35:0)
[21:54:04.572] Timer tick, asking Buildbucket for the build status
[21:54:04.930] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:04.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:36:0) after 1m0s
[21:55:05.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:36:0)
[21:55:05.098] Timer tick, asking Buildbucket for the build status
[21:55:05.855] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:05.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:37:0) after 1m0s
[21:56:05.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:37:0)
[21:56:05.909] Timer tick, asking Buildbucket for the build status
[21:56:06.416] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:06.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:38:0) after 1m0s
[21:57:06.441] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:38:0)
[21:57:06.441] Timer tick, asking Buildbucket for the build status
[21:57:06.592] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:06.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:39:0) after 1m0s
[21:58:06.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:39:0)
[21:58:06.646] Timer tick, asking Buildbucket for the build status
[21:58:07.003] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:07.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:40:0) after 1m0s
[21:59:06.996] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:40:0)
[21:59:06.996] Timer tick, asking Buildbucket for the build status
[21:59:07.173] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:07.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:41:0) after 1m0s
[22:00:07.270] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:41:0)
[22:00:07.270] Timer tick, asking Buildbucket for the build status
[22:00:07.342] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:07.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:42:0) after 1m0s
[22:01:07.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:42:0)
[22:01:07.462] Timer tick, asking Buildbucket for the build status
[22:01:07.677] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:07.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:43:0) after 1m0s
[22:02:07.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:43:0)
[22:02:07.727] Timer tick, asking Buildbucket for the build status
[22:02:08.390] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:08.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:44:0) after 1m0s
[22:03:08.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:44:0)
[22:03:08.493] Timer tick, asking Buildbucket for the build status
[22:03:08.979] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:08.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:45:0) after 1m0s
[22:04:09.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:45:0)
[22:04:09.193] Timer tick, asking Buildbucket for the build status
[22:04:10.825] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:10.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:46:0) after 1m0s
[22:05:10.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:46:0)
[22:05:10.997] Timer tick, asking Buildbucket for the build status
[22:05:11.133] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:11.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:47:0) after 1m0s
[22:06:11.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:47:0)
[22:06:11.180] Timer tick, asking Buildbucket for the build status
[22:06:11.623] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:11.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:48:0) after 1m0s
[22:07:11.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:48:0)
[22:07:11.657] Timer tick, asking Buildbucket for the build status
[22:07:11.762] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:11.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:49:0) after 1m0s
[22:08:11.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:49:0)
[22:08:11.828] Timer tick, asking Buildbucket for the build status
[22:08:11.993] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:11.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:50:0) after 1m0s
[22:09:12.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:50:0)
[22:09:12.044] Timer tick, asking Buildbucket for the build status
[22:09:12.106] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:12.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:51:0) after 1m0s
[22:10:12.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:51:0)
[22:10:12.257] Timer tick, asking Buildbucket for the build status
[22:10:12.365] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:12.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:52:0) after 1m0s
[22:11:12.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:52:0)
[22:11:12.459] Timer tick, asking Buildbucket for the build status
[22:11:16.885] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:16.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:53:0) after 1m0s
[22:12:16.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:53:0)
[22:12:16.956] Timer tick, asking Buildbucket for the build status
[22:12:17.036] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:17.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:54:0) after 1m0s
[22:13:17.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:54:0)
[22:13:17.145] Timer tick, asking Buildbucket for the build status
[22:13:17.515] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:17.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:55:0) after 1m0s
[22:14:17.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:55:0)
[22:14:17.521] Timer tick, asking Buildbucket for the build status
[22:14:17.659] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:17.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:56:0) after 1m0s
[22:15:17.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:56:0)
[22:15:17.715] Timer tick, asking Buildbucket for the build status
[22:15:18.079] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:18.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:57:0) after 1m0s
[22:16:18.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:57:0)
[22:16:18.231] Timer tick, asking Buildbucket for the build status
[22:16:18.369] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:18.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:58:0) after 1m0s
[22:17:18.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:58:0)
[22:17:18.509] Timer tick, asking Buildbucket for the build status
[22:17:18.587] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:18.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:59:0) after 1m0s
[22:18:18.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:59:0)
[22:18:18.759] Timer tick, asking Buildbucket for the build status
[22:18:18.999] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:18.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:60:0) after 1m0s
[22:19:19.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:60:0)
[22:19:19.254] Timer tick, asking Buildbucket for the build status
[22:19:19.347] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:19.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:61:0) after 1m0s
[22:20:19.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:61:0)
[22:20:19.491] Timer tick, asking Buildbucket for the build status
[22:20:19.710] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:19.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:62:0) after 1m0s
[22:21:19.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:62:0)
[22:21:19.721] Timer tick, asking Buildbucket for the build status
[22:21:19.869] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:19.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:63:0) after 1m0s
[22:22:19.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:63:0)
[22:22:19.977] Timer tick, asking Buildbucket for the build status
[22:22:20.066] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:20.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:64:0) after 1m0s
[22:23:20.221] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:64:0)
[22:23:20.221] Timer tick, asking Buildbucket for the build status
[22:23:20.310] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:20.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:65:0) after 1m0s
[22:24:20.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:65:0)
[22:24:20.328] Timer tick, asking Buildbucket for the build status
[22:24:20.435] Build 8856736125837670464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:20.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022166187889972288:66:0) after 1m0s
[22:25:15.167] Received PubSub notification, asking Buildbucket for the build status
[22:25:15.631] Build 8856736125837670464: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:25:15.631] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611959114314516",
"created_by": "project:chromium",
"created_ts": "1611955251809703",
"id": "8856736125837670464",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d685493326d1326bc692356d281e6f5afd8b49c9\"}}",
"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\": \"d685493326d1326bc692356d281e6f5afd8b49c9\", \"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-2fjn\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17074, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"74f7be6d1fd020a432dee63222fa090e4d8df651\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"d685493326d1326bc692356d281e6f5afd8b49c9\", \"got_revision_cp\": \"refs/heads/master@{#848715}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"89b433d7c3defd04809fba9deb6f8885160044a4\", \"got_v8_revision_cp\": \"refs/heads/9.0.100@{#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\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d685493326d1326bc692356d281e6f5afd8b49c9\", \"swarm_hashes_refs/heads/master(at){#848715}_without_patch\": {\"absl_hardening_tests\": \"6c533ba35005b3337b20ca71654ffa1369bed92f\", \"accessibility_unittests\": \"a7143a55301bc42a5fc5389c73578d4db698ffd0\", \"angle_unittests\": \"91b19370b620af36b8af939c7f62b7d769d3c11e\", \"app_shell_unittests\": \"2b09e399ad0022cdd6d04baf3a8a62e888226a72\", \"aura_unittests\": \"6d2eb4c1f60a74b67797273a6fd26119a7833579\", \"base_unittests\": \"f376e8cda30e3ec870b5c13aa64b2bef130b23ec\", \"base_util_unittests\": \"ccbf57b5232b08e17519ac552f0a9baee02c78c4\", \"blink_common_unittests\": \"fc87c7c5b0393aa4ecf778c665d1a9d4eb9b5345\", \"blink_fuzzer_unittests\": \"e6d215c7f759b30011f423c4c4bf0c04be879645\", \"blink_heap_unittests\": \"4d4338cf0fa3f3f549540f7d0ccfa6ea3bfd4e22\", \"blink_platform_unittests\": \"6e7a87fa474336e7e2cab74b903e16327198da1d\", \"blink_unittests\": \"d539377380e5bfee5affdb1f3bbe096c9c1c2892\", \"boringssl_crypto_tests\": \"a5c3aef699f93a5889da64eb5db6ad427f607807\", \"boringssl_ssl_tests\": \"36b9e9a7aec5b0ac4735484a48eff6ab3176712b\", \"browser_switcher_bho_unittests\": \"fc7eef071e102361d6cec93895901a9b6a16a9ad\", \"browser_tests\": \"213be51a50526e84a438b62965d1f31cb97644ea\", \"capture_unittests\": \"a1d3de6dedf53d3baad8cf655455ce579c889fac\", \"cast_unittests\": \"225b0988792d201a4c1692305c6252ec1a807f00\", \"cc_unittests\": \"6636d0518cadde89bf55721d678a36f580309648\", \"chrome_app_unittests\": \"e3cb1376626d7bba8cb2810330b63597b9da00a6\", \"chrome_cleaner_unittests\": \"851e7e3952b9baf24bda278bc3e0ac530a0e1245\", \"chrome_elf_unittests\": \"ebb6ffa3ed5d437cd3c428f44be4ac45585cdf0b\", \"chromedriver_unittests\": \"6f160237252c55195e064bf28b2a9e04f1e02515\", \"components_browsertests\": \"e53223dbe82f0ec4d754b363389def21de08b0b0\", \"components_unittests\": \"c586fb76d752a7427e048d3fa0cf175f18a3c63d\", \"compositor_unittests\": \"563c3450385074414cafa3bb3f7f2ab57d5e8452\", \"content_browsertests\": \"79223e3eb3b7b3ce54c85579a6454bcae08dd67c\", \"content_unittests\": \"d97aead30ce32f6f2d6884b06165b49d92cc8590\", \"courgette_unittests\": \"996af8504b53234da19864755ce9f542e59af54e\", \"crashpad_tests\": \"08d588b57a6b26af540aad2c986a334acd873788\", \"cronet_tests\": \"6290103b84473777de6e8ecc8ec1e8966df8cce0\", \"cronet_unittests\": \"686686eab6b5d5f39f6786b7737a453e26f2984e\", \"crypto_unittests\": \"e2385e741058ada1cc80f6c201f8c358cedfbf4d\", \"delayloads_unittests\": \"8d046589f16d3fc8d470a4f54561550b5b49ddb7\", \"device_unittests\": \"23f18a98f94245c697f917de8f632e5ba7467355\", \"display_unittests\": \"dec5b2848cf3622d3864beae64d4f59fdaf7bfb2\", \"elevation_service_unittests\": \"7f6de8f49ab888ea9b2957ab75edb31e0208397d\", \"events_unittests\": \"c297cfa863c39f0bf667168546020ffb68abb7cc\", \"extensions_browsertests\": \"7c38bc7f2c2516ed490549a3cbbc05d9dd324ce0\", \"extensions_unittests\": \"8aaa771e3245c33b8f0a4e9465f5ff1ea806c541\", \"filesystem_service_unittests\": \"7f940af0df8fe44a5e48256756ce7a1538a89340\", \"gcm_unit_tests\": \"feced4ad2eb64a34bb7c2ab30008d1f09ab7ebc9\", \"gcp_unittests\": \"7e1e31956fb4d9e0c1314c6aa982ca91bcc89d4e\", \"gfx_unittests\": \"f1fefb263361ebbfb27b69918b76c4e7f23bd9c4\", \"gin_unittests\": \"7705edaecd4b8f2fbe9e5756cd36f0342cf1bc87\", \"google_apis_unittests\": \"e9dc7695a726c0100cabeb7c05b073f93f653556\", \"gpu_unittests\": \"ab7f39295bb1b2008a0d0f41d7a33dc9af5ff752\", \"gwp_asan_unittests\": \"890ede2c1a0f335fa073453f5ae4f8f93d70781a\", \"headless_browsertests\": \"e9a536438c1612fd6b044b79ce1784964fdf9cc5\", \"headless_unittests\": \"3b7bed7d219bd9560148c98f3945197c7bccee46\", \"install_static_unittests\": \"262e60fe7d97e088ca2652ec5add71109fd4dcf0\", \"installer_util_unittests\": \"226aae6eb330cce74113fb95e6ec1ba6600f143d\", \"interactive_ui_tests\": \"00b1345ddd4ccc7df86d4688df21b4cd90a9948c\", \"ipc_tests\": \"df977ba8a006ac57149249b940f6c36580c84258\", \"jingle_unittests\": \"4029bfee1cf1331e1b7df726e931bc138b3dd719\", \"latency_unittests\": \"e753553c07bd6ffb9a646416d3b568a759140907\", \"libjingle_xmpp_unittests\": \"27913d88a118638319999d2d4872af9b6dcf16f6\", \"liburlpattern_unittests\": \"bec103d3c683b69ce5f8d43e5f1782ac1ba93505\", \"media_blink_unittests\": \"2ed5a1fc5077d10126482e628466d302090aa161\", \"media_unittests\": \"9cd01ec25b1bee680bfd9373722f0a83f43e5a44\", \"message_center_unittests\": \"2d3a6e775f324017453ac9b28fa0454b112db0e8\", \"midi_unittests\": \"c355c495e87bd1858281347568e42b5d07928f92\", \"mojo_core_unittests\": \"71659d0f1dd4b6b78caf5af31519bd7fb0b3d106\", \"mojo_unittests\": \"06e4122f001ded79a8278b467dea0095829e7e62\", \"nacl_loader_unittests\": \"c103d4ee055eec5266753d5c9cdc73f2fb22b76f\", \"native_theme_unittests\": \"f7537d75413c255517f3ffa12356adcd1708bb3d\", \"net_unittests\": \"399edaaca76c94d44515cc1e4e44f74a91825985\", \"notification_helper_unittests\": \"292f45b6fe8c2ca73d903d9b04e075aeb15f7438\", \"pdf_unittests\": \"ccbe1b24d93b06177737db41f2e9c8cb3dba85bc\", \"perfetto_unittests\": \"837d06abe7979bb269756f6744025502f0952ce5\", \"ppapi_unittests\": \"d2c71063127aef7d69bc6c6081896788f5e56452\", \"printing_unittests\": \"5a9821e3dbea4eee48af782485c665a6dc5aad22\", \"remoting_unittests\": \"cb37bb9a851718308d642ed3c5c67a7f8a2133b3\", \"sbox_integration_tests\": \"594b2df5ef27938e99e316820b877ec2fc906182\", \"sbox_unittests\": \"1528ca5308514242ce3424f23129c0d7a68362b1\", \"sbox_validation_tests\": \"ffe48aafdb66a4de59c4315e2f535e8c74b4c37f\", \"service_manager_unittests\": \"a33bbf519495e68496d266afb742286fec3fda9a\", \"services_unittests\": \"549cd6b3bb203937357fb7a06a1572cfe2bf4631\", \"setup_unittests\": \"641c350f741348da2cfd5af1b5bf91a9c7acf4b4\", \"shell_dialogs_unittests\": \"8aa53a666f712cbf59025c03fc7d9322d2d1f1c9\", \"skia_unittests\": \"a06b97d768968b1079b756e0dc1e4ed889fd5a80\", \"snapshot_unittests\": \"a39c5859b633f889a9ba5065c2bbea57f53a4712\", \"sql_unittests\": \"b1f52b0277d2fc56e2a22447820382e48b8a3ce5\", \"storage_unittests\": \"a0155d826647c1b5a664d3fa4bd6ba36b2feca8b\", \"sync_integration_tests\": \"25d85a512c8003b44a71e7f9e39cc7ff5560147d\", \"traffic_annotation_auditor_unittests\": \"9cd6f5e5f7344c4720ba43b56f2d0adf75fc5836\", \"ui_base_unittests\": \"27f79039dfc71ca5c260210545393463685669a3\", \"ui_touch_selection_unittests\": \"4d1fb7abeaa27b5836b09db82457c25dc3410033\", \"unit_tests\": \"b85b36d4920b7aeffa3a6ee3674af9157a67ec19\", \"updater_tests\": \"42e6ab733346da70fcabb07e6cf2d0602a2ca356\", \"url_unittests\": \"a6870580c6dc61a8591af7724d4fd30923b006e7\", \"views_unittests\": \"2d98d205d679ed8f3b13e19ab9eff18d384c0d2f\", \"viz_unittests\": \"df605c26db2cd4c63a96e7ae7a58f9a3db2bbbe8\", \"vr_common_unittests\": \"678149921c4ddca6a0c4bbe98cfe0fd8482a2762\", \"vr_pixeltests\": \"186e111b8ac4b318bdc1cc5a6fdf46d14bf8a860\", \"weblayer_browsertests\": \"16778d198e4ebe1a0161c53c36134df421788404\", \"weblayer_unittests\": \"b255112602f51c2cf01c9f02897b75c77e7b8208\", \"wm_unittests\": \"9a001d8bec5ed9ce3acdd408cc630fd7cb2eae3e\", \"wtf_unittests\": \"4b3445b4191e4a1f68b6ba49f563767a50fb7f76\", \"zlib_unittests\": \"ec596367e56fc4a6b3fcfee86c7a7c23c5f4846d\", \"zucchini_unittests\": \"805eeb8f0649de4541fd6c70b353a041c9bcf3fd\"}}, \"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-2fjn\"], \"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-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611955255290219",
"status": "COMPLETED",
"status_changed_ts": "1611959114740203",
"tags": [
"build_address:luci.chromium.ci/win-asan/17074",
"builder:win-asan",
"buildset:commit/git/d685493326d1326bc692356d281e6f5afd8b49c9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d685493326d1326bc692356d281e6f5afd8b49c9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022166187889972288",
"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/8856736125837670464/+/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:5168d6b82bb36310",
"user_agent:luci-scheduler"
],
"updated_ts": "1611959114744119",
"url": "https://ci.chromium.org/b/8856736125837670464",
"utcnow_ts": "1611959115610512"
}
}
[22:25:15.631] Invocation finished in 1h4m25.163960849s with status SUCCEEDED