[21:29:59.156] New invocation is queued and will start shortly
[21:30:00.231] Starting the invocation (attempt 1)
[21:30:00.268] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:30:00.268] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:30:00.268] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022256209532249008",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"59f6ff500d33ce6f0df48df19348b1ce2445e63e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022256209532249008",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/59f6ff500d33ce6f0df48df19348b1ce2445e63e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/59f6ff500d33ce6f0df48df19348b1ce2445e63e",
"gitiles_ref:refs/heads/master"
]
}
[21:30:01.460] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611869400372527",
"id": "8856826147594150992",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"59f6ff500d33ce6f0df48df19348b1ce2445e63e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611869401199894",
"tags": [
"build_address:luci.chromium.ci/win-asan/17055",
"builder:win-asan",
"buildset:commit/git/59f6ff500d33ce6f0df48df19348b1ce2445e63e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/59f6ff500d33ce6f0df48df19348b1ce2445e63e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022256209532249008",
"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/8856826147594150992/+/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": "1611869401200211",
"url": "https://ci.chromium.org/b/8856826147594150992",
"utcnow_ts": "1611869401439568"
}
}
[21:30:01.460] Task URL: https://ci.chromium.org/b/8856826147594150992
[21:30:01.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:2:0) after 1m0s
[21:30:03.290] Received PubSub notification, asking Buildbucket for the build status
[21:30:03.484] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:02.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:2:0)
[21:31:02.190] Timer tick, asking Buildbucket for the build status
[21:31:02.382] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:02.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:4:0) after 1m0s
[21:32:02.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:4:0)
[21:32:02.550] Timer tick, asking Buildbucket for the build status
[21:32:03.379] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:03.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:5:0) after 1m0s
[21:33:03.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:5:0)
[21:33:03.727] Timer tick, asking Buildbucket for the build status
[21:33:04.384] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:04.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:6:0) after 1m0s
[21:34:04.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:6:0)
[21:34:04.494] Timer tick, asking Buildbucket for the build status
[21:34:04.700] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:04.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:7:0) after 1m0s
[21:35:05.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:7:0)
[21:35:05.142] Timer tick, asking Buildbucket for the build status
[21:35:06.492] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:06.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:8:0) after 1m0s
[21:36:06.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:8:0)
[21:36:06.788] Timer tick, asking Buildbucket for the build status
[21:36:06.884] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:06.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:9:0) after 1m0s
[21:37:08.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:9:0)
[21:37:08.827] Timer tick, asking Buildbucket for the build status
[21:37:09.067] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:09.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:10:0) after 1m0s
[21:38:09.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:10:0)
[21:38:09.117] Timer tick, asking Buildbucket for the build status
[21:38:09.187] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:09.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:11:0) after 1m0s
[21:39:09.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:11:0)
[21:39:09.258] Timer tick, asking Buildbucket for the build status
[21:39:09.354] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:09.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:12:0) after 1m0s
[21:40:09.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:12:0)
[21:40:09.442] Timer tick, asking Buildbucket for the build status
[21:40:10.115] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:10.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:13:0) after 1m0s
[21:41:10.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:13:0)
[21:41:10.133] Timer tick, asking Buildbucket for the build status
[21:41:10.601] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:10.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:14:0) after 1m0s
[21:42:10.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:14:0)
[21:42:10.692] Timer tick, asking Buildbucket for the build status
[21:42:10.882] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:10.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:15:0) after 1m0s
[21:43:10.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:15:0)
[21:43:10.898] Timer tick, asking Buildbucket for the build status
[21:43:11.407] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:11.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:16:0) after 1m0s
[21:44:11.431] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:16:0)
[21:44:11.431] Timer tick, asking Buildbucket for the build status
[21:44:12.053] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:12.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:17:0) after 1m0s
[21:45:12.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:17:0)
[21:45:12.156] Timer tick, asking Buildbucket for the build status
[21:45:12.388] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:12.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:18:0) after 1m0s
[21:46:12.443] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:18:0)
[21:46:12.443] Timer tick, asking Buildbucket for the build status
[21:46:14.239] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:14.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:19:0) after 1m0s
[21:47:14.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:19:0)
[21:47:14.260] Timer tick, asking Buildbucket for the build status
[21:47:14.489] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:14.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:20:0) after 1m0s
[21:48:14.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:20:0)
[21:48:14.565] Timer tick, asking Buildbucket for the build status
[21:48:14.655] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:14.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:21:0) after 1m0s
[21:49:14.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:21:0)
[21:49:14.781] Timer tick, asking Buildbucket for the build status
[21:49:14.929] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:14.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:22:0) after 1m0s
[21:50:15.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:22:0)
[21:50:15.059] Timer tick, asking Buildbucket for the build status
[21:50:15.523] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:15.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:23:0) after 1m0s
[21:51:15.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:23:0)
[21:51:15.661] Timer tick, asking Buildbucket for the build status
[21:51:16.264] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:16.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:24:0) after 1m0s
[21:52:16.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:24:0)
[21:52:16.266] Timer tick, asking Buildbucket for the build status
[21:52:16.781] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:16.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:25:0) after 1m0s
[21:53:16.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:25:0)
[21:53:16.811] Timer tick, asking Buildbucket for the build status
[21:53:17.192] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:17.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:26:0) after 1m0s
[21:54:17.204] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:26:0)
[21:54:17.204] Timer tick, asking Buildbucket for the build status
[21:54:17.505] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:17.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:27:0) after 1m0s
[21:55:17.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:27:0)
[21:55:17.532] Timer tick, asking Buildbucket for the build status
[21:55:18.074] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:18.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:28:0) after 1m0s
[21:56:18.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:28:0)
[21:56:18.106] Timer tick, asking Buildbucket for the build status
[21:56:18.196] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:18.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:29:0) after 1m0s
[21:57:18.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:29:0)
[21:57:18.233] Timer tick, asking Buildbucket for the build status
[21:57:18.390] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:18.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:30:0) after 1m0s
[21:58:19.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:30:0)
[21:58:19.242] Timer tick, asking Buildbucket for the build status
[21:58:19.383] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:19.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:31:0) after 1m0s
[21:59:19.731] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:31:0)
[21:59:19.731] Timer tick, asking Buildbucket for the build status
[21:59:20.331] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:20.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:32:0) after 1m0s
[22:00:20.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:32:0)
[22:00:20.388] Timer tick, asking Buildbucket for the build status
[22:00:20.503] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:20.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:33:0) after 1m0s
[22:01:20.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:33:0)
[22:01:20.561] Timer tick, asking Buildbucket for the build status
[22:01:21.238] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:21.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:34:0) after 1m0s
[22:02:21.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:34:0)
[22:02:21.217] Timer tick, asking Buildbucket for the build status
[22:02:21.357] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:21.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:35:0) after 1m0s
[22:03:21.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:35:0)
[22:03:21.425] Timer tick, asking Buildbucket for the build status
[22:03:21.715] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:21.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:36:0) after 1m0s
[22:04:21.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:36:0)
[22:04:21.704] Timer tick, asking Buildbucket for the build status
[22:04:21.902] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:21.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:37:0) after 1m0s
[22:05:22.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:37:0)
[22:05:22.022] Timer tick, asking Buildbucket for the build status
[22:05:22.430] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:22.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:38:0) after 1m0s
[22:06:22.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:38:0)
[22:06:22.434] Timer tick, asking Buildbucket for the build status
[22:06:22.549] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:22.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:39:0) after 1m0s
[22:07:22.677] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:39:0)
[22:07:22.677] Timer tick, asking Buildbucket for the build status
[22:07:23.511] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:23.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:40:0) after 1m0s
[22:08:23.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:40:0)
[22:08:23.538] Timer tick, asking Buildbucket for the build status
[22:08:23.860] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:23.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:41:0) after 1m0s
[22:09:23.924] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:41:0)
[22:09:23.924] Timer tick, asking Buildbucket for the build status
[22:09:24.082] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:24.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:42:0) after 1m0s
[22:10:24.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:42:0)
[22:10:24.111] Timer tick, asking Buildbucket for the build status
[22:10:24.284] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:24.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:43:0) after 1m0s
[22:11:24.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:43:0)
[22:11:24.452] Timer tick, asking Buildbucket for the build status
[22:11:25.004] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:25.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:44:0) after 1m0s
[22:12:25.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:44:0)
[22:12:25.142] Timer tick, asking Buildbucket for the build status
[22:12:25.259] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:25.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:45:0) after 1m0s
[22:13:25.306] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:45:0)
[22:13:25.306] Timer tick, asking Buildbucket for the build status
[22:13:26.971] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:26.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:46:0) after 1m0s
[22:14:27.204] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:46:0)
[22:14:27.204] Timer tick, asking Buildbucket for the build status
[22:14:27.394] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:27.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:47:0) after 1m0s
[22:15:27.474] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:47:0)
[22:15:27.474] Timer tick, asking Buildbucket for the build status
[22:15:27.939] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:27.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:48:0) after 1m0s
[22:16:28.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:48:0)
[22:16:28.004] Timer tick, asking Buildbucket for the build status
[22:16:28.182] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:28.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:49:0) after 1m0s
[22:17:28.225] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:49:0)
[22:17:28.225] Timer tick, asking Buildbucket for the build status
[22:17:28.622] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:28.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:50:0) after 1m0s
[22:18:28.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:50:0)
[22:18:28.674] Timer tick, asking Buildbucket for the build status
[22:18:29.162] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:29.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:51:0) after 1m0s
[22:19:29.278] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:51:0)
[22:19:29.278] Timer tick, asking Buildbucket for the build status
[22:19:29.429] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:29.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:52:0) after 1m0s
[22:20:29.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:52:0)
[22:20:29.450] Timer tick, asking Buildbucket for the build status
[22:20:29.641] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:29.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:53:0) after 1m0s
[22:21:30.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:53:0)
[22:21:30.296] Timer tick, asking Buildbucket for the build status
[22:21:31.032] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:31.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:54:0) after 1m0s
[22:22:31.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:54:0)
[22:22:31.034] Timer tick, asking Buildbucket for the build status
[22:22:31.244] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:31.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:55:0) after 1m0s
[22:23:31.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:55:0)
[22:23:31.309] Timer tick, asking Buildbucket for the build status
[22:23:31.409] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:31.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:56:0) after 1m0s
[22:24:31.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:56:0)
[22:24:31.478] Timer tick, asking Buildbucket for the build status
[22:24:31.611] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:31.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:57:0) after 1m0s
[22:25:31.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:57:0)
[22:25:31.651] Timer tick, asking Buildbucket for the build status
[22:25:31.794] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:31.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:58:0) after 1m0s
[22:26:31.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:58:0)
[22:26:31.875] Timer tick, asking Buildbucket for the build status
[22:26:31.983] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:31.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:59:0) after 1m0s
[22:27:32.005] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:59:0)
[22:27:32.005] Timer tick, asking Buildbucket for the build status
[22:27:32.132] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:32.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:60:0) after 1m0s
[22:28:32.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:60:0)
[22:28:32.154] Timer tick, asking Buildbucket for the build status
[22:28:32.467] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:32.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:61:0) after 1m0s
[22:29:32.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:61:0)
[22:29:32.573] Timer tick, asking Buildbucket for the build status
[22:29:33.710] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:33.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:62:0) after 1m0s
[22:30:33.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:62:0)
[22:30:33.778] Timer tick, asking Buildbucket for the build status
[22:30:34.116] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:34.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:63:0) after 1m0s
[22:31:34.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:63:0)
[22:31:34.146] Timer tick, asking Buildbucket for the build status
[22:31:34.580] Build 8856826147594150992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:34.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022256209532249008:64:0) after 1m0s
[22:31:46.212] Received PubSub notification, asking Buildbucket for the build status
[22:31:46.283] Build 8856826147594150992: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:31:46.283] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611873103731615",
"created_by": "project:chromium",
"created_ts": "1611869400372527",
"id": "8856826147594150992",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"59f6ff500d33ce6f0df48df19348b1ce2445e63e\"}}",
"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\": \"59f6ff500d33ce6f0df48df19348b1ce2445e63e\", \"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\": 17055, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"a408ce8349289c8fe457a5ebccb8c4cbac7b9c3f\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"59f6ff500d33ce6f0df48df19348b1ce2445e63e\", \"got_revision_cp\": \"refs/heads/master@{#848261}\", \"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\": \"3aec43e0d5e23052dfb10b46e47309f8db6e0db5\", \"prev_got_revision_cp\": \"refs/heads/master@{#848227}\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"59f6ff500d33ce6f0df48df19348b1ce2445e63e\", \"swarm_hashes_refs/heads/master(at){#848261}_without_patch\": {\"absl_hardening_tests\": \"d666d675b322c48561145c3de62490601f43aff3\", \"accessibility_unittests\": \"4609aa7e38b9bf78d2fe3f26591d0d4253e7dc15\", \"angle_unittests\": \"dda1dd3c8909db4195b448ac534a614a67b7274f\", \"app_shell_unittests\": \"944404b73960aae49de6b36f6383946ee8c5584b\", \"aura_unittests\": \"51ddd884d9320cbc0aeb400a6a47827a8b02df83\", \"base_unittests\": \"d3b8a281339a571d4850b167c8ec4b0ea1a3494a\", \"base_util_unittests\": \"85cb0cbfceb1abb7a27c1ad3c31c01717059cd6f\", \"blink_common_unittests\": \"0b6ccec28c0f84d0b7801de3b6fdfb88060fad34\", \"blink_fuzzer_unittests\": \"584579c90495a8cfd2a13c11db58d456ea2c65d9\", \"blink_heap_unittests\": \"9255acd53b981092f2f299c0529fdedfaed6c877\", \"blink_platform_unittests\": \"7f16f7ea6952782654adef41127582081c3420ff\", \"blink_unittests\": \"ea68471e17d64bbdde96dbc2432a74cec090fa85\", \"boringssl_crypto_tests\": \"b58860eeea1abe403269108c4a475b5db90ae1ac\", \"boringssl_ssl_tests\": \"6e0eb316ffa89d98a102948176b51d029ae2a34a\", \"browser_switcher_bho_unittests\": \"687a9236f11c2ea42c0396e4ba654c5a97257aa2\", \"browser_tests\": \"7cc8ce31b85373678b1362df2ebade700e3eadbf\", \"capture_unittests\": \"cb82e215e5b80aaddd0ed9c2e27e8f4f1391fe19\", \"cast_unittests\": \"cc95176c12e82bc6e2cb429f80676fdfef40af92\", \"cc_unittests\": \"3b81b5e9cb2bd7b625946efbafebdc35d3e5881a\", \"chrome_app_unittests\": \"74f3a1d96c7976137ae4e255b0e5676d1386f469\", \"chrome_cleaner_unittests\": \"2954aa52cac257ddaa260f252008563a7c71bccc\", \"chrome_elf_unittests\": \"24902e02f5d2f06d0506848a344c5b75debe61ac\", \"chromedriver_unittests\": \"fd1a9cb2054692ab81b3a68707652da1a0a52508\", \"components_browsertests\": \"addff0ede21ba069bc9daadfef337c5a7eab18d0\", \"components_unittests\": \"253c37e4b9195d6e6d18ff8965f8d17da201ca6f\", \"compositor_unittests\": \"77108be5545cd3c3c248006bc84de656b050da02\", \"content_browsertests\": \"ae7818bb0a0feb132dd437e613dea1ba6be925de\", \"content_unittests\": \"3ad6196606d9981a7202d22d7e694ede22133a0f\", \"courgette_unittests\": \"34f585690e25df5640894bc0148e0b96837b6784\", \"crashpad_tests\": \"ce203a3a9a07f9d9ac3e06ade84319d2ce0130ee\", \"cronet_tests\": \"a674dfdca70b2fcb333c03a28535da7233ed9066\", \"cronet_unittests\": \"b009283100a22af4b8842313efe2456324558800\", \"crypto_unittests\": \"6429f5508e0ec603d61a38aecf4196f29f5f7209\", \"delayloads_unittests\": \"9b984ce5b4fe0b447ef04056ddd2f380c9c1f64c\", \"device_unittests\": \"307e49ff5d648977f1942057bc6219b3b7d136ab\", \"display_unittests\": \"34ee97eb1dca1535a17d401b31db54fbc84a5ffa\", \"elevation_service_unittests\": \"de9e5c08cf4166431bf4bdac79990a6663a5ad97\", \"events_unittests\": \"628b69a500f4475a685e3424906864f87de0785f\", \"extensions_browsertests\": \"9802b925a8d34d074ff187bbf9dd4247e0dcb651\", \"extensions_unittests\": \"fdb19e3ab6c05678d5119f961d4f7e06d25d7a24\", \"filesystem_service_unittests\": \"84e044efc2c252f5679d8480dda9eb4d68a372f7\", \"gcm_unit_tests\": \"4a93ce5d7a7f61765df2acd794666eff3e39517d\", \"gcp_unittests\": \"71c2051e0e5f42f581a039b3ad50d61afbff1b64\", \"gfx_unittests\": \"b8a59777b8dcd9fa05d8126fd384a0350274c279\", \"gin_unittests\": \"bffcec8e84da170b8462e8eef6770def3c874018\", \"google_apis_unittests\": \"e44161a67695bc25bef179fbc6b24a9c28f62f97\", \"gpu_unittests\": \"51d9011ed7d3f3b85dcc8aac9f9549ee986a47a1\", \"gwp_asan_unittests\": \"e483b5ccaac7b4d37073efb2b4c814524b65f2d8\", \"headless_browsertests\": \"fc4e27d706b8a2ac9a49e36c74bc2d1d0619da33\", \"headless_unittests\": \"0c584fa59f69aedee7d2821b2bbf6b08f011af45\", \"install_static_unittests\": \"89a32c7181dac61b4ccd5fba0908b667612b8241\", \"installer_util_unittests\": \"0e26919cf3164665a12999c65010f66163050328\", \"interactive_ui_tests\": \"d04ed7abd561965e8a78a5fa8660e9519c3f968e\", \"ipc_tests\": \"5f5966aae9fc11cdfff135236e2cf65450e842ef\", \"jingle_unittests\": \"9e6a17309631bcc1d449e6b9e1e5b20e31ec9555\", \"latency_unittests\": \"1e4ec38871d8c3c24185be0e5e4214383ce9191c\", \"libjingle_xmpp_unittests\": \"f327a4736dc960c88c0163234d20f0832cdb58f6\", \"liburlpattern_unittests\": \"4726a0c600f6c410f1e9dfb9526e8287fba3bc46\", \"media_blink_unittests\": \"63534037ee0bca7b61b9736bf3e183739a08fe1d\", \"media_unittests\": \"affc3087130d62461b4841eeefbb7623774d8298\", \"message_center_unittests\": \"b53cfdcf027b5e5fb72530de7eb29953fed5323c\", \"midi_unittests\": \"3b03f0a145f3d6c42a673e593d345481b0c88807\", \"mojo_core_unittests\": \"2ac363f05061ddf2a7bb1f993e51422a4a62b98d\", \"mojo_unittests\": \"f3a906b71902cf2d9c8c6d01140541022675ccfc\", \"nacl_loader_unittests\": \"5d43f435db3fa320cc0f2fcef8b2dd7833b94826\", \"native_theme_unittests\": \"a2f9502883c7bc4a029a30d9894cc4890a327520\", \"net_unittests\": \"1d7e90cccb64adf1ece5df4e99f46a626a5f14de\", \"notification_helper_unittests\": \"a3fb761cb0398416a5fec0d2a334759afcb05885\", \"pdf_unittests\": \"48a7ca5b9354efef22883087d3812a2be89457eb\", \"perfetto_unittests\": \"b64c9872bfad71c11169bb3ec8e584fda98db239\", \"ppapi_unittests\": \"0f1e237d5b5a6596a4dec93ee9ee202277a3ec6f\", \"printing_unittests\": \"e5a902c93f23665df5d17f5582b7635d500d5414\", \"remoting_unittests\": \"a15eeb9192090908db640a33bb4450bbe65a159d\", \"sbox_integration_tests\": \"74aca3f39a3d275111692503e8811d9b54e755d8\", \"sbox_unittests\": \"4c206742d34fbc3f9a5a0a4e09fab03c8aab4bc7\", \"sbox_validation_tests\": \"a080249db784dd03bce607a1531b00b7660909fd\", \"service_manager_unittests\": \"b6fc439a60d5858271892d784f9d5cd78af7ddcb\", \"services_unittests\": \"c1ab105d279b218673cb866053b741c322f150a0\", \"setup_unittests\": \"41dff137becc4cbdc04deb366a161238d2a6f5c4\", \"shell_dialogs_unittests\": \"4ca0a2e4d75380c4f1bcddde72e1320960af5349\", \"skia_unittests\": \"0e8c4969ce5b033e18f0b32fba890e7481b58fd0\", \"snapshot_unittests\": \"750f16a40f0440a4b51fd25b56cc6339cf017c68\", \"sql_unittests\": \"aa4f2d9e3d18310ddd0bff871e2e5b3cf2d1323c\", \"storage_unittests\": \"9b67dfc89fc6998be90acc2fbb829fa3b324ee2c\", \"sync_integration_tests\": \"b0dfd2c1636045c63a93b09126e0d038c5d36361\", \"traffic_annotation_auditor_unittests\": \"bb7de666dfb757977f9636aadc3c40ddef563069\", \"ui_base_unittests\": \"8b1de2a2c0f0f33e2976d966c8cccd84715ba493\", \"ui_touch_selection_unittests\": \"04811a5cde918224b85c5b083b4109e182fcff3d\", \"unit_tests\": \"f9287384c2f00829d5924d916ec39cb9d7ef8bc1\", \"updater_tests\": \"c7478f1ca63ccd844dd3966b59685698bec3bec0\", \"url_unittests\": \"36a5b535b1eb67dd4670e78ab3cfe2c204d8955a\", \"views_unittests\": \"61eaf9438b36cbb695518ee8b63ac2667f6b4b56\", \"viz_unittests\": \"13950aa9ba61f9153a7c9fc81640a67965b244f2\", \"vr_common_unittests\": \"f506c50c711b368ad6b573950702269b9d976eb3\", \"vr_pixeltests\": \"688389c03f381eafb55f281fc0e63bce07f5781b\", \"weblayer_browsertests\": \"519b4a9e04e805d3f4928474e1a033973be17bad\", \"weblayer_unittests\": \"8a3259e785518a9b8d9c91d310687e4674296b9e\", \"wm_unittests\": \"7ac7a08dd4a94e82fda0cacdefd4fdab205bb720\", \"wtf_unittests\": \"c670d8d5858e12ab74271dfac66c3749987e64d2\", \"zlib_unittests\": \"0faf4e5736a8a36ab84ad7fa891a307baabb3123\", \"zucchini_unittests\": \"7d94b2383873af713ffce358d6603b0da6870c61\"}}, \"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": "1611869402637051",
"status": "COMPLETED",
"status_changed_ts": "1611873104396275",
"tags": [
"build_address:luci.chromium.ci/win-asan/17055",
"builder:win-asan",
"buildset:commit/git/59f6ff500d33ce6f0df48df19348b1ce2445e63e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/59f6ff500d33ce6f0df48df19348b1ce2445e63e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022256209532249008",
"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/8856826147594150992/+/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:5163b8b98bc44a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611873104412620",
"url": "https://ci.chromium.org/b/8856826147594150992",
"utcnow_ts": "1611873106274917"
}
}
[22:31:46.283] Invocation finished in 1h1m47.142938807s with status SUCCEEDED