[03:24:56.249] New invocation is queued and will start shortly
[03:24:57.338] Starting the invocation (attempt 1)
[03:24:57.374] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:24:57.374] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:24:57.374] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022686862742465632",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d3e68f05580dbc303d6de07755f251a029a09262\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022686862742465632",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/d3e68f05580dbc303d6de07755f251a029a09262",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d3e68f05580dbc303d6de07755f251a029a09262",
"gitiles_ref:refs/heads/master"
]
}
[03:24:58.154] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611458697470097",
"id": "8857256800799671472",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d3e68f05580dbc303d6de07755f251a029a09262\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611458697971691",
"tags": [
"build_address:luci.chromium.ci/win-asan/16979",
"builder:win-asan",
"buildset:commit/git/d3e68f05580dbc303d6de07755f251a029a09262",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d3e68f05580dbc303d6de07755f251a029a09262",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022686862742465632",
"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/8857256800799671472/+/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": "1611458697971962",
"url": "https://ci.chromium.org/b/8857256800799671472",
"utcnow_ts": "1611458698147911"
}
}
[03:24:58.154] Task URL: https://ci.chromium.org/b/8857256800799671472
[03:24:58.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:2:0) after 1m0s
[03:25:03.267] Received PubSub notification, asking Buildbucket for the build status
[03:25:03.331] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:58.170] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:2:0)
[03:25:58.170] Timer tick, asking Buildbucket for the build status
[03:25:58.261] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:58.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:4:0) after 1m0s
[03:26:58.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:4:0)
[03:26:58.372] Timer tick, asking Buildbucket for the build status
[03:26:58.487] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:58.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:5:0) after 1m0s
[03:27:58.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:5:0)
[03:27:58.606] Timer tick, asking Buildbucket for the build status
[03:27:58.690] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:58.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:6:0) after 1m0s
[03:28:58.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:6:0)
[03:28:58.707] Timer tick, asking Buildbucket for the build status
[03:28:59.115] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:59.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:7:0) after 1m0s
[03:29:59.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:7:0)
[03:29:59.258] Timer tick, asking Buildbucket for the build status
[03:29:59.319] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:59.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:8:0) after 1m0s
[03:30:59.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:8:0)
[03:30:59.386] Timer tick, asking Buildbucket for the build status
[03:30:59.508] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:59.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:9:0) after 1m0s
[03:31:59.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:9:0)
[03:31:59.593] Timer tick, asking Buildbucket for the build status
[03:31:59.649] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:59.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:10:0) after 1m0s
[03:32:59.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:10:0)
[03:32:59.661] Timer tick, asking Buildbucket for the build status
[03:32:59.756] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:59.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:11:0) after 1m0s
[03:33:59.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:11:0)
[03:33:59.804] Timer tick, asking Buildbucket for the build status
[03:33:59.906] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:59.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:12:0) after 1m0s
[03:34:59.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:12:0)
[03:34:59.933] Timer tick, asking Buildbucket for the build status
[03:35:00.074] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:00.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:13:0) after 1m0s
[03:36:00.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:13:0)
[03:36:00.761] Timer tick, asking Buildbucket for the build status
[03:36:00.847] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:00.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:14:0) after 1m0s
[03:37:01.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:14:0)
[03:37:01.104] Timer tick, asking Buildbucket for the build status
[03:37:01.387] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:01.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:15:0) after 1m0s
[03:38:01.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:15:0)
[03:38:01.414] Timer tick, asking Buildbucket for the build status
[03:38:01.472] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:01.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:16:0) after 1m0s
[03:39:01.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:16:0)
[03:39:01.515] Timer tick, asking Buildbucket for the build status
[03:39:01.613] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:01.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:17:0) after 1m0s
[03:40:01.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:17:0)
[03:40:01.657] Timer tick, asking Buildbucket for the build status
[03:40:01.750] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:01.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:18:0) after 1m0s
[03:41:01.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:18:0)
[03:41:01.867] Timer tick, asking Buildbucket for the build status
[03:41:01.975] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:01.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:19:0) after 1m0s
[03:42:02.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:19:0)
[03:42:02.023] Timer tick, asking Buildbucket for the build status
[03:42:02.147] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:02.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:20:0) after 1m0s
[03:43:02.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:20:0)
[03:43:02.196] Timer tick, asking Buildbucket for the build status
[03:43:02.354] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:02.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:21:0) after 1m0s
[03:44:02.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:21:0)
[03:44:02.375] Timer tick, asking Buildbucket for the build status
[03:44:02.864] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:02.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:22:0) after 1m0s
[03:45:03.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:22:0)
[03:45:03.010] Timer tick, asking Buildbucket for the build status
[03:45:03.122] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:03.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:23:0) after 1m0s
[03:46:03.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:23:0)
[03:46:03.221] Timer tick, asking Buildbucket for the build status
[03:46:03.425] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:03.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:24:0) after 1m0s
[03:47:03.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:24:0)
[03:47:03.459] Timer tick, asking Buildbucket for the build status
[03:47:03.586] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:03.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:25:0) after 1m0s
[03:48:03.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:25:0)
[03:48:03.626] Timer tick, asking Buildbucket for the build status
[03:48:03.736] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:03.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:26:0) after 1m0s
[03:49:03.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:26:0)
[03:49:03.851] Timer tick, asking Buildbucket for the build status
[03:49:04.029] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:04.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:27:0) after 1m0s
[03:50:04.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:27:0)
[03:50:04.056] Timer tick, asking Buildbucket for the build status
[03:50:04.188] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:04.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:28:0) after 1m0s
[03:51:04.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:28:0)
[03:51:04.208] Timer tick, asking Buildbucket for the build status
[03:51:04.902] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:04.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:29:0) after 1m0s
[03:52:05.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:29:0)
[03:52:05.038] Timer tick, asking Buildbucket for the build status
[03:52:05.217] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:05.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:30:0) after 1m0s
[03:53:05.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:30:0)
[03:53:05.287] Timer tick, asking Buildbucket for the build status
[03:53:05.418] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:05.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:31:0) after 1m0s
[03:54:05.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:31:0)
[03:54:05.479] Timer tick, asking Buildbucket for the build status
[03:54:05.577] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:05.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:32:0) after 1m0s
[03:55:05.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:32:0)
[03:55:05.688] Timer tick, asking Buildbucket for the build status
[03:55:05.901] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:05.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:33:0) after 1m0s
[03:56:05.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:33:0)
[03:56:05.955] Timer tick, asking Buildbucket for the build status
[03:56:06.025] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:06.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:34:0) after 1m0s
[03:57:06.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:34:0)
[03:57:06.048] Timer tick, asking Buildbucket for the build status
[03:57:06.122] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:06.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:35:0) after 1m0s
[03:58:06.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:35:0)
[03:58:06.176] Timer tick, asking Buildbucket for the build status
[03:58:06.330] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:06.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:36:0) after 1m0s
[03:59:06.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:36:0)
[03:59:06.400] Timer tick, asking Buildbucket for the build status
[03:59:06.748] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:06.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:37:0) after 1m0s
[04:00:06.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:37:0)
[04:00:06.774] Timer tick, asking Buildbucket for the build status
[04:00:06.880] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:06.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:38:0) after 1m0s
[04:01:06.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:38:0)
[04:01:06.958] Timer tick, asking Buildbucket for the build status
[04:01:07.035] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:07.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:39:0) after 1m0s
[04:02:07.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:39:0)
[04:02:07.112] Timer tick, asking Buildbucket for the build status
[04:02:07.235] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:07.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:40:0) after 1m0s
[04:03:07.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:40:0)
[04:03:07.354] Timer tick, asking Buildbucket for the build status
[04:03:07.432] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:07.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:41:0) after 1m0s
[04:04:08.173] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:41:0)
[04:04:08.173] Timer tick, asking Buildbucket for the build status
[04:04:08.232] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:08.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:42:0) after 1m0s
[04:05:08.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:42:0)
[04:05:08.351] Timer tick, asking Buildbucket for the build status
[04:05:09.217] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:09.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:43:0) after 1m0s
[04:06:09.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:43:0)
[04:06:09.320] Timer tick, asking Buildbucket for the build status
[04:06:10.123] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:10.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:44:0) after 1m0s
[04:07:10.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:44:0)
[04:07:10.268] Timer tick, asking Buildbucket for the build status
[04:07:10.555] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:10.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:45:0) after 1m0s
[04:08:10.677] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:45:0)
[04:08:10.677] Timer tick, asking Buildbucket for the build status
[04:08:10.761] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:10.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:46:0) after 1m0s
[04:09:10.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:46:0)
[04:09:10.829] Timer tick, asking Buildbucket for the build status
[04:09:10.923] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:10.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:47:0) after 1m0s
[04:10:11.041] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:47:0)
[04:10:11.041] Timer tick, asking Buildbucket for the build status
[04:10:11.187] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:11.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:48:0) after 1m0s
[04:11:11.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:48:0)
[04:11:11.214] Timer tick, asking Buildbucket for the build status
[04:11:11.311] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:11.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:49:0) after 1m0s
[04:12:11.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:49:0)
[04:12:11.337] Timer tick, asking Buildbucket for the build status
[04:12:11.579] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:11.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:50:0) after 1m0s
[04:13:11.596] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:50:0)
[04:13:11.596] Timer tick, asking Buildbucket for the build status
[04:13:11.654] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:11.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:51:0) after 1m0s
[04:14:11.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:51:0)
[04:14:11.671] Timer tick, asking Buildbucket for the build status
[04:14:11.731] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:11.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:52:0) after 1m0s
[04:15:11.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:52:0)
[04:15:11.783] Timer tick, asking Buildbucket for the build status
[04:15:11.880] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:11.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:53:0) after 1m0s
[04:16:11.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:53:0)
[04:16:11.886] Timer tick, asking Buildbucket for the build status
[04:16:11.995] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:11.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:54:0) after 1m0s
[04:17:12.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:54:0)
[04:17:12.046] Timer tick, asking Buildbucket for the build status
[04:17:12.426] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:12.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:55:0) after 1m0s
[04:18:12.547] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:55:0)
[04:18:12.547] Timer tick, asking Buildbucket for the build status
[04:18:12.771] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:12.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:56:0) after 1m0s
[04:19:12.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:56:0)
[04:19:12.912] Timer tick, asking Buildbucket for the build status
[04:19:13.055] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:13.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:57:0) after 1m0s
[04:20:13.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:57:0)
[04:20:13.106] Timer tick, asking Buildbucket for the build status
[04:20:13.203] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:13.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:58:0) after 1m0s
[04:21:13.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:58:0)
[04:21:13.229] Timer tick, asking Buildbucket for the build status
[04:21:13.332] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:13.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:59:0) after 1m0s
[04:22:13.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:59:0)
[04:22:13.414] Timer tick, asking Buildbucket for the build status
[04:22:14.637] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:14.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:60:0) after 1m0s
[04:23:14.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:60:0)
[04:23:14.649] Timer tick, asking Buildbucket for the build status
[04:23:14.806] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:14.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:61:0) after 1m0s
[04:24:14.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:61:0)
[04:24:14.919] Timer tick, asking Buildbucket for the build status
[04:24:15.047] Build 8857256800799671472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:15.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022686862742465632:62:0) after 1m0s
[04:24:19.062] Received PubSub notification, asking Buildbucket for the build status
[04:24:19.123] Build 8857256800799671472: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:24:19.123] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611462257596095",
"created_by": "project:chromium",
"created_ts": "1611458697470097",
"id": "8857256800799671472",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d3e68f05580dbc303d6de07755f251a029a09262\"}}",
"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\": \"d3e68f05580dbc303d6de07755f251a029a09262\", \"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-10-3ote\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16979, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"3c3b82abcf0a5c18b4d97ae49726045ce6d3b832\", \"got_dawn_revision\": \"f1f8394de04ea9532de763dfb2f968fc9610683b\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"d3e68f05580dbc303d6de07755f251a029a09262\", \"got_revision_cp\": \"refs/heads/master@{#846555}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"413f7cb17c77c95e4cc3ba141f3c0f049ac2ecb2\", \"got_v8_revision\": \"f2b2f28304b332e59226ee6c0688307ed9982884\", \"got_v8_revision_cp\": \"refs/heads/9.0.57@{#1}\", \"got_webrtc_revision\": \"90776cb0ef72d437983c8d29e5a7f2ac264c107d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33064}\", \"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\": \"d3e68f05580dbc303d6de07755f251a029a09262\", \"swarm_hashes_refs/heads/master(at){#846555}_without_patch\": {\"absl_hardening_tests\": \"1f2a710a685ac73b35047b1818ef70ed124bf33c\", \"accessibility_unittests\": \"160052452919f3128229f27877aefc35aac77d7f\", \"angle_unittests\": \"069c1c9c80c861d5f0030d422cdff94d07317eba\", \"app_shell_unittests\": \"ae9b07ae1d4db680ae3dae743a7bb48d5b08d738\", \"aura_unittests\": \"5959f7d3734deafcfe9878a0b09870029505e092\", \"base_unittests\": \"6f58242170690808b8ceade3fd1b9cdcb87054d2\", \"base_util_unittests\": \"9bf07a1a7a634ed272988db3c91c890b2ec5e7a8\", \"blink_common_unittests\": \"2b15a6510075ea65ac836f60c8a2850afab96d00\", \"blink_fuzzer_unittests\": \"db14a27295a81150b7a7ae5c275ff97af6f36e9a\", \"blink_heap_unittests\": \"9e646323137c2400b8c2bc18332991a708c76b5d\", \"blink_platform_unittests\": \"7364567c5e94b853e291b39e6591f6477303e54b\", \"blink_unittests\": \"d6f3558739961547f066021a40c7eeb4ab0b522c\", \"boringssl_crypto_tests\": \"3d92088223316e0de4cd3a7dc50aaf695ac5258f\", \"boringssl_ssl_tests\": \"fce259e3006c34c2b95622e11032abf419efc721\", \"browser_switcher_bho_unittests\": \"5c3794f88ec637239bd1bc0ad82f29c5fb18f1ce\", \"browser_tests\": \"38be9bb8967ff58d55f65ee229b0a623e7b7d270\", \"capture_unittests\": \"42bbaf3987257fb87a7b187a15323dbad38730a2\", \"cast_unittests\": \"07eaad4296f21f39782231d7b63df62e49ce3a68\", \"cc_unittests\": \"8c41ba5887174084341f3ffcb45b737f305197df\", \"chrome_app_unittests\": \"be405200e1cb36b16cd6b653112c831405529747\", \"chrome_cleaner_unittests\": \"63cdb41237e214cc54c352bc6956246fd353913d\", \"chrome_elf_unittests\": \"6f5426dc55eb2fab8ce9a22071e4c742966157d8\", \"chromedriver_unittests\": \"4e9a1c7223c38bca86ede2e1492677b223c7f794\", \"components_browsertests\": \"903cd68507a3ac7f54fac2c199b394d49124e35e\", \"components_unittests\": \"67145a5d554b14815767786b5b5cf769f7a2d094\", \"compositor_unittests\": \"987352bffdfd7a3fc933da50c2f4b5c10a00b878\", \"content_browsertests\": \"fe4d437c659aed0d9a3a2fcd723164fffbfbfed4\", \"content_unittests\": \"961fcbbd7ca2f7cc41d818a382ac891c5aa022d9\", \"courgette_unittests\": \"a2d2e2239b7d22e3b0ba41f472e09f5b23561dde\", \"crashpad_tests\": \"dc7f13cd1fd56d2cd76af1088da017ceafc7ba51\", \"cronet_tests\": \"b27c4d32b472e4edb4dfece3095bdb0c992bbee7\", \"cronet_unittests\": \"33137cf522b4017b01c4ed644abd2e89de009bb9\", \"crypto_unittests\": \"0dc89e8571a84296c9811a6bd97927b6cf192e8f\", \"delayloads_unittests\": \"6bc8713ef3f0f5bb9d8074ed3c3a7bd9f7a7b651\", \"device_unittests\": \"7da5e222e1ae40f5a568192e829b47203446e6d1\", \"display_unittests\": \"3e221b069f4d1bb9f04bb52c85b12e56187b215f\", \"elevation_service_unittests\": \"57cd58ce4442a7ee86b483aea7901e9cda7be2fe\", \"events_unittests\": \"51262948bf04205cdf16ab9747df0733f1187fef\", \"extensions_browsertests\": \"90747b02972bdec4733da0320a13658aa1c7dfcf\", \"extensions_unittests\": \"366e9da63379c54b72ab9214940081669f0097a9\", \"filesystem_service_unittests\": \"6b0fee073fe49d30dace2a57b6533af3e76bb9cf\", \"gcm_unit_tests\": \"7ff82c02b7f2ac3e8d2bb9419716d1b87b30ae2c\", \"gcp_unittests\": \"e7b4b1cbdb5ae74d6b18344d7f491472dc359001\", \"gfx_unittests\": \"43e06fbd140a6b3b3fc3ec091f11d75653bf5cf1\", \"gin_unittests\": \"9e84284807c24a651eb12d0e2c44c13d500f712b\", \"google_apis_unittests\": \"fc738665b4fa9281b8f2e6505d52b455299209ef\", \"gpu_unittests\": \"1efd376538e5c9b4e053acf32244825cd52ef4f1\", \"gwp_asan_unittests\": \"30f8313b9ab2f78b9f4cdd226a780db923ced183\", \"headless_browsertests\": \"e72c7dd23f1f6f866a6a5f2e0f865dd648513928\", \"headless_unittests\": \"d783238e4578c7535c75ea734ca7eb266c77846c\", \"install_static_unittests\": \"720301fa29c8b461869a249d0686e7abfe977338\", \"installer_util_unittests\": \"3f042d020e34c35f47b3f1874e16e1a0afda0d58\", \"interactive_ui_tests\": \"93210cce8cf2cb60f9876ffb070733d9b1b6ba7c\", \"ipc_tests\": \"0b4c726b026ce1d874009c97a231abb4242d429f\", \"jingle_unittests\": \"53c973e0ab014315b7dd83ba10016701a46ddb23\", \"latency_unittests\": \"a2cca5e840ef976fa5386116d55f7cd40cdb7c8c\", \"libjingle_xmpp_unittests\": \"1367ca97893cee47c5d71299dd2687e252a9c502\", \"liburlpattern_unittests\": \"9d811e5b616542cbe0210fdff76c31068fdfe2a2\", \"media_blink_unittests\": \"8ca1b77e4bbb867a4a04522a184ffddd4207071a\", \"media_unittests\": \"7c49bb1e43b0f0a2816baac7323542d7fb89a5c4\", \"message_center_unittests\": \"744e674252396003bd22204f9480f7ce761d887a\", \"midi_unittests\": \"da598f12e90bdcb1fc6b92202861c4a8721a52f6\", \"mojo_core_unittests\": \"df8edf8260ced679732c2ee056270feaeaab2edf\", \"mojo_unittests\": \"53001eb65301f675ed981aec74f3f58aba42971c\", \"nacl_loader_unittests\": \"039443bdb2334c54b4c0840e6986aae27e300b80\", \"native_theme_unittests\": \"735ad8a1b5845341cd5dd8a16bcb1840afa134ec\", \"net_unittests\": \"328cdbfdc9aa9a9f766562745b5415921536e2fb\", \"notification_helper_unittests\": \"d3e54011495aee10d7887a4a03f5b726813527f1\", \"pdf_unittests\": \"539831ed85e97c4961a1d8586f7e7fedd71c6e52\", \"perfetto_unittests\": \"e26f45f7cfb9f7c86a6f3927dfdb2c22e707497d\", \"ppapi_unittests\": \"3f3b4890fe5602414c58d0b9dcf1ada93511bb6d\", \"printing_unittests\": \"cf33bab6769bd469445c528d6581e1385dfe63e5\", \"remoting_unittests\": \"63d8d6205857777e1bbdf5db5295ac68adc13938\", \"sbox_integration_tests\": \"362400fca18f6eb3dc8b0aaaa7acae27bb72f8fa\", \"sbox_unittests\": \"346139aa123aabada021834405b492381ac48b30\", \"sbox_validation_tests\": \"2c47a7279953de0af7cbb7eb52f3771ae8b9b70f\", \"service_manager_unittests\": \"ea37406d382f5bb9b2d1c195aca290a8e57f956b\", \"services_unittests\": \"fb18166c6c93f4ab178a586d9589d6b7c3b8cb5a\", \"setup_unittests\": \"494c04aaeb26d656f741beb51136cecc3e284933\", \"shell_dialogs_unittests\": \"1653946bb04e72a798e3765ceaa4485de3f4c1c4\", \"skia_unittests\": \"c0380e954c2918596009a555078fbd4645a68106\", \"snapshot_unittests\": \"b27ec20c6b19bafd879368b16b831685d5a2c3b4\", \"sql_unittests\": \"4bdb684ccd1bd6fa2acaa4e9c4df7c254ae2d8e5\", \"storage_unittests\": \"d6147ac01445a593a5889f97e9d1e838f45f727b\", \"sync_integration_tests\": \"572a204851d2a7323423c4c0436ee616156966c7\", \"traffic_annotation_auditor_unittests\": \"32484a5915f7d7058613067f6f0722a923b6b4e4\", \"ui_base_unittests\": \"4dc45fe67347f9a9882a7974e5d9695eebf81b3c\", \"ui_touch_selection_unittests\": \"c9a87152d4dd42461af526def67faeec89dcb7ed\", \"unit_tests\": \"3c1b8d9f694983e0726ed1c7909b73137544a95c\", \"updater_tests\": \"65286b748b6264541ccc8c607124bd30c458d1f4\", \"url_unittests\": \"2bf424868e78912f4d98e444fed6854a8da0d800\", \"views_unittests\": \"b461cd6fcdf0adccbc81ec2c43af7002f4ad4f86\", \"viz_unittests\": \"4a747194da598a6987afe06df464ad6127f867e3\", \"vr_common_unittests\": \"b87a8e17fc7bba6a1fa873ebb8049dc2b2809d76\", \"vr_pixeltests\": \"950daddd9e3402c2c2f19143a8268a90e575aaec\", \"weblayer_browsertests\": \"84edd06bead9152230a99996d443fd6a14a3bb87\", \"weblayer_unittests\": \"ef5be10339fd7bc34fea7b00d40ced949c3ca793\", \"wm_unittests\": \"27be50aec3f1ffef4a17f7a57fd4c606d4a97b29\", \"wtf_unittests\": \"e3282f5a39ad7d0135554ac46122ce296568d68f\", \"zlib_unittests\": \"a00dc59ab6d5709883183d97c12c7376b66062a8\", \"zucchini_unittests\": \"38d2844117cfb1def6325f047d5b35fd6b3096ee\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_91c730c3e6ead475130e2ae78bc3f03e09cb9dcb91c3469d4450b29020110401_v2\", \"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-10-3ote\"], \"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\": [\"5671-1d9dea8\"], \"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": "1611458701722048",
"status": "COMPLETED",
"status_changed_ts": "1611462258088759",
"tags": [
"build_address:luci.chromium.ci/win-asan/16979",
"builder:win-asan",
"buildset:commit/git/d3e68f05580dbc303d6de07755f251a029a09262",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d3e68f05580dbc303d6de07755f251a029a09262",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022686862742465632",
"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/8857256800799671472/+/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:514b3de582047110",
"user_agent:luci-scheduler"
],
"updated_ts": "1611462258099712",
"url": "https://ci.chromium.org/b/8857256800799671472",
"utcnow_ts": "1611462259115703"
}
}
[04:24:19.123] Invocation finished in 59m22.888905888s with status SUCCEEDED