[04:25:46.169] New invocation is queued and will start shortly
[04:25:47.472] Starting the invocation (attempt 1)
[04:25:47.510] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:25:47.510] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:25:47.510] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026216317215647264",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"989b00ac8d3e201d5981260495bfbebcd84d0941\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026216317215647264",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/989b00ac8d3e201d5981260495bfbebcd84d0941",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/989b00ac8d3e201d5981260495bfbebcd84d0941",
"gitiles_ref:refs/heads/master"
]
}
[04:25:48.561] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608092747607555",
"id": "8860786255043543728",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"989b00ac8d3e201d5981260495bfbebcd84d0941\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608092748296427",
"tags": [
"build_address:luci.chromium.ci/win-asan/16253",
"builder:win-asan",
"buildset:commit/git/989b00ac8d3e201d5981260495bfbebcd84d0941",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/989b00ac8d3e201d5981260495bfbebcd84d0941",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026216317215647264",
"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/8860786255043543728/+/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": "1608092748296631",
"url": "https://ci.chromium.org/b/8860786255043543728",
"utcnow_ts": "1608092748524564"
}
}
[04:25:48.561] Task URL: https://ci.chromium.org/b/8860786255043543728
[04:25:48.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:2:0) after 1m0s
[04:26:48.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:2:0)
[04:26:48.577] Timer tick, asking Buildbucket for the build status
[04:26:48.700] Build 8860786255043543728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:26:48.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:3:0) after 1m0s
[04:27:48.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:3:0)
[04:27:48.694] Timer tick, asking Buildbucket for the build status
[04:27:48.776] Build 8860786255043543728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:27:48.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:4:0) after 1m0s
[04:28:48.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:4:0)
[04:28:48.760] Timer tick, asking Buildbucket for the build status
[04:28:48.845] Build 8860786255043543728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:28:48.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:5:0) after 1m0s
[04:29:48.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:5:0)
[04:29:48.845] Timer tick, asking Buildbucket for the build status
[04:29:49.068] Build 8860786255043543728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:29:49.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:6:0) after 1m0s
[04:30:47.357] Received PubSub notification, asking Buildbucket for the build status
[04:30:47.480] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:49.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:6:0)
[04:30:49.056] Timer tick, asking Buildbucket for the build status
[04:30:49.435] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:49.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:8:0) after 1m0s
[04:31:49.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:8:0)
[04:31:49.452] Timer tick, asking Buildbucket for the build status
[04:31:49.552] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:49.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:9:0) after 1m0s
[04:32:49.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:9:0)
[04:32:49.568] Timer tick, asking Buildbucket for the build status
[04:32:49.688] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:49.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:10:0) after 1m0s
[04:33:49.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:10:0)
[04:33:49.720] Timer tick, asking Buildbucket for the build status
[04:33:49.826] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:49.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:11:0) after 1m0s
[04:34:49.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:11:0)
[04:34:49.833] Timer tick, asking Buildbucket for the build status
[04:34:49.939] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:49.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:12:0) after 1m0s
[04:35:49.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:12:0)
[04:35:49.936] Timer tick, asking Buildbucket for the build status
[04:35:50.593] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:50.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:13:0) after 1m0s
[04:36:50.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:13:0)
[04:36:50.633] Timer tick, asking Buildbucket for the build status
[04:36:51.101] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:51.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:14:0) after 1m0s
[04:37:51.170] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:14:0)
[04:37:51.170] Timer tick, asking Buildbucket for the build status
[04:37:51.230] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:51.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:15:0) after 1m0s
[04:38:51.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:15:0)
[04:38:51.247] Timer tick, asking Buildbucket for the build status
[04:38:51.404] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:51.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:16:0) after 1m0s
[04:39:51.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:16:0)
[04:39:51.401] Timer tick, asking Buildbucket for the build status
[04:39:51.507] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:51.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:17:0) after 1m0s
[04:40:51.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:17:0)
[04:40:51.529] Timer tick, asking Buildbucket for the build status
[04:40:51.663] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:51.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:18:0) after 1m0s
[04:41:51.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:18:0)
[04:41:51.717] Timer tick, asking Buildbucket for the build status
[04:41:51.788] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:51.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:19:0) after 1m0s
[04:42:51.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:19:0)
[04:42:51.847] Timer tick, asking Buildbucket for the build status
[04:42:51.903] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:51.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:20:0) after 1m0s
[04:43:51.941] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:20:0)
[04:43:51.941] Timer tick, asking Buildbucket for the build status
[04:43:52.053] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:52.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:21:0) after 1m0s
[04:44:52.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:21:0)
[04:44:52.123] Timer tick, asking Buildbucket for the build status
[04:44:52.222] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:52.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:22:0) after 1m0s
[04:45:52.325] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:22:0)
[04:45:52.325] Timer tick, asking Buildbucket for the build status
[04:45:52.443] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:52.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:23:0) after 1m0s
[04:46:52.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:23:0)
[04:46:52.517] Timer tick, asking Buildbucket for the build status
[04:46:53.010] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:53.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:24:0) after 1m0s
[04:47:53.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:24:0)
[04:47:53.058] Timer tick, asking Buildbucket for the build status
[04:47:53.171] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:53.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:25:0) after 1m0s
[04:48:53.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:25:0)
[04:48:53.255] Timer tick, asking Buildbucket for the build status
[04:48:53.432] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:53.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:26:0) after 1m0s
[04:49:53.466] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:26:0)
[04:49:53.466] Timer tick, asking Buildbucket for the build status
[04:49:53.990] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:53.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:27:0) after 1m0s
[04:50:54.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:27:0)
[04:50:54.010] Timer tick, asking Buildbucket for the build status
[04:50:54.138] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:54.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:28:0) after 1m0s
[04:51:54.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:28:0)
[04:51:54.145] Timer tick, asking Buildbucket for the build status
[04:51:54.248] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:54.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:29:0) after 1m0s
[04:52:54.249] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:29:0)
[04:52:54.249] Timer tick, asking Buildbucket for the build status
[04:52:54.343] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:54.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:30:0) after 1m0s
[04:53:54.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:30:0)
[04:53:54.368] Timer tick, asking Buildbucket for the build status
[04:53:54.618] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:54.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:31:0) after 1m0s
[04:54:54.637] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:31:0)
[04:54:54.637] Timer tick, asking Buildbucket for the build status
[04:54:54.750] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:54.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:32:0) after 1m0s
[04:55:54.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:32:0)
[04:55:54.768] Timer tick, asking Buildbucket for the build status
[04:55:54.878] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:54.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:33:0) after 1m0s
[04:56:54.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:33:0)
[04:56:54.955] Timer tick, asking Buildbucket for the build status
[04:56:55.055] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:55.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:34:0) after 1m0s
[04:57:55.102] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:34:0)
[04:57:55.103] Timer tick, asking Buildbucket for the build status
[04:57:55.220] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:55.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:35:0) after 1m0s
[04:58:55.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:35:0)
[04:58:55.235] Timer tick, asking Buildbucket for the build status
[04:58:55.299] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:55.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:36:0) after 1m0s
[04:59:55.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:36:0)
[04:59:55.360] Timer tick, asking Buildbucket for the build status
[04:59:55.437] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:55.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:37:0) after 1m0s
[05:00:55.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:37:0)
[05:00:55.609] Timer tick, asking Buildbucket for the build status
[05:00:55.808] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:55.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:38:0) after 1m0s
[05:01:55.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:38:0)
[05:01:55.911] Timer tick, asking Buildbucket for the build status
[05:01:56.016] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:56.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:39:0) after 1m0s
[05:02:56.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:39:0)
[05:02:56.054] Timer tick, asking Buildbucket for the build status
[05:02:56.167] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:56.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:40:0) after 1m0s
[05:03:56.344] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:40:0)
[05:03:56.344] Timer tick, asking Buildbucket for the build status
[05:03:56.600] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:56.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:41:0) after 1m0s
[05:04:56.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:41:0)
[05:04:56.593] Timer tick, asking Buildbucket for the build status
[05:04:57.962] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:57.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:42:0) after 1m0s
[05:05:57.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:42:0)
[05:05:57.997] Timer tick, asking Buildbucket for the build status
[05:05:58.112] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:58.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:43:0) after 1m0s
[05:06:58.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:43:0)
[05:06:58.235] Timer tick, asking Buildbucket for the build status
[05:06:58.291] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:58.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:44:0) after 1m0s
[05:07:58.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:44:0)
[05:07:58.288] Timer tick, asking Buildbucket for the build status
[05:07:58.420] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:58.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:45:0) after 1m0s
[05:08:58.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:45:0)
[05:08:58.476] Timer tick, asking Buildbucket for the build status
[05:08:58.643] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:58.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:46:0) after 1m0s
[05:09:58.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:46:0)
[05:09:58.661] Timer tick, asking Buildbucket for the build status
[05:09:58.744] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:58.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:47:0) after 1m0s
[05:10:58.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:47:0)
[05:10:58.882] Timer tick, asking Buildbucket for the build status
[05:10:59.003] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:59.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:48:0) after 1m0s
[05:11:59.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:48:0)
[05:11:59.108] Timer tick, asking Buildbucket for the build status
[05:11:59.662] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:59.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:49:0) after 1m0s
[05:12:59.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:49:0)
[05:12:59.674] Timer tick, asking Buildbucket for the build status
[05:13:00.187] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:00.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:50:0) after 1m0s
[05:14:00.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:50:0)
[05:14:00.292] Timer tick, asking Buildbucket for the build status
[05:14:00.399] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:00.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:51:0) after 1m0s
[05:15:00.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:51:0)
[05:15:00.485] Timer tick, asking Buildbucket for the build status
[05:15:00.589] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:00.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:52:0) after 1m0s
[05:16:01.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:52:0)
[05:16:01.353] Timer tick, asking Buildbucket for the build status
[05:16:01.483] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:01.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:53:0) after 1m0s
[05:17:01.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:53:0)
[05:17:01.767] Timer tick, asking Buildbucket for the build status
[05:17:02.132] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:02.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:54:0) after 1m0s
[05:18:02.153] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:54:0)
[05:18:02.153] Timer tick, asking Buildbucket for the build status
[05:18:02.253] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:02.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:55:0) after 1m0s
[05:19:02.278] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:55:0)
[05:19:02.278] Timer tick, asking Buildbucket for the build status
[05:19:02.928] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:02.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:56:0) after 1m0s
[05:20:02.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:56:0)
[05:20:02.972] Timer tick, asking Buildbucket for the build status
[05:20:03.078] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:03.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:57:0) after 1m0s
[05:21:03.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:57:0)
[05:21:03.108] Timer tick, asking Buildbucket for the build status
[05:21:03.322] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:03.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:58:0) after 1m0s
[05:22:03.347] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:58:0)
[05:22:03.347] Timer tick, asking Buildbucket for the build status
[05:22:03.525] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:03.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:59:0) after 1m0s
[05:23:03.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:59:0)
[05:23:03.524] Timer tick, asking Buildbucket for the build status
[05:23:03.589] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:03.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:60:0) after 1m0s
[05:24:03.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:60:0)
[05:24:03.632] Timer tick, asking Buildbucket for the build status
[05:24:04.045] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:04.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:61:0) after 1m0s
[05:25:04.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:61:0)
[05:25:04.136] Timer tick, asking Buildbucket for the build status
[05:25:04.281] Build 8860786255043543728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:04.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026216317215647264:62:0) after 1m0s
[05:25:56.984] Received PubSub notification, asking Buildbucket for the build status
[05:25:57.233] Build 8860786255043543728: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:25:57.233] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608096355625531",
"created_by": "project:chromium",
"created_ts": "1608092747607555",
"id": "8860786255043543728",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"989b00ac8d3e201d5981260495bfbebcd84d0941\"}}",
"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\": \"989b00ac8d3e201d5981260495bfbebcd84d0941\", \"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-qsbv\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16253, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"f32fbb51e8a173e1839e958c2450633a19449b83\", \"got_dawn_revision\": \"b35ae002390ec0ff85d8754e36a9efd9e63d0a79\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"989b00ac8d3e201d5981260495bfbebcd84d0941\", \"got_revision_cp\": \"refs/heads/master@{#837439}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1ca6504eb7bb9971d317cf8c8033b38b914036b7\", \"got_v8_revision\": \"24e738a6bc98c6971154c655e17f656297085c45\", \"got_v8_revision_cp\": \"refs/heads/8.9.157@{#1}\", \"got_webrtc_revision\": \"cf8ec1f6867651204d81b14a8c31a86e6eb97572\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32829}\", \"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\": \"989b00ac8d3e201d5981260495bfbebcd84d0941\", \"swarm_hashes_refs/heads/master(at){#837439}_without_patch\": {\"absl_hardening_tests\": \"e59c7483ede3af39a1f66692a0a55eb4e34a4537\", \"accessibility_unittests\": \"308e19d57fd51e32b9938e75e24ffc51628091c9\", \"angle_unittests\": \"37b600f89d15f198b065e1a5d110d7fa8cecdc42\", \"app_shell_unittests\": \"fc65570ad28f9557a2853052a8e4e21a18f9f9c1\", \"aura_unittests\": \"80b47d1177c16f5845fc2cfb996a86708c100cdb\", \"base_unittests\": \"9c3219ce4e204f44b1bc8c2f6c5547f8df027957\", \"base_util_unittests\": \"c4199c23533d9b9a38693aa6f445f5ebccdd26fa\", \"blink_common_unittests\": \"0ef9635b8b22f6e38e65fdc9375b90f4d02d9373\", \"blink_fuzzer_unittests\": \"fc8e5918bb5e75a799bb5256b2ec4ef1d17ccb10\", \"blink_heap_unittests\": \"e9f385a15a9cdc0c6a02294efe9d7bf42a4ceb44\", \"blink_platform_unittests\": \"88ba3c51e9c2ab8568ed67c29011042e2f80f1ba\", \"blink_unittests\": \"27b34a36e2c4575d40076f021e399f158b3128fe\", \"boringssl_crypto_tests\": \"27b0dcc868ea2be27f39503c3a5c17980668f933\", \"boringssl_ssl_tests\": \"d96446b43a1bb9053cc7a84a1b0176cee663bd5f\", \"browser_switcher_bho_unittests\": \"ae8abe59ef4461e1dc90f7a55e256380d6f437f5\", \"browser_tests\": \"32c76b7ae1921561dfb69dec12753cb88a5d1ab3\", \"capture_unittests\": \"8efba990647461c924af928d333100c55dc71394\", \"cast_unittests\": \"d4f17329407ac75af2c7065c2314db22185022f6\", \"cc_unittests\": \"b8d5d8487d8ca932a4c3455bccf255210ed4d42b\", \"chrome_app_unittests\": \"719e463426eb4fdc8d1de6b7b7e87e1b80991a0f\", \"chrome_cleaner_unittests\": \"dbf690d6040c0d7f306c8d76d748066a58f56158\", \"chrome_elf_unittests\": \"9e1344d33bede3cb56c858b73d52e34b5df77956\", \"chromedriver_unittests\": \"3a27329babf5054862e1490401a1acf313f7284d\", \"components_browsertests\": \"8a50becd333c0f473a301d6681601c420d3671fc\", \"components_unittests\": \"a31729dcc1eb31674e6de88b2be955eb39dbe8ef\", \"compositor_unittests\": \"f917a1c409c2eb54b443ef97c801df2b73e86694\", \"content_browsertests\": \"cc4010c3fe4b4dd44645ed430de2146ef1ad0489\", \"content_unittests\": \"015e27c333c2bef69c13499fc315edcd40c434a1\", \"courgette_unittests\": \"d0e6907564354eff148a39e72fbbec4c7e8e8e6e\", \"crashpad_tests\": \"4347417517d97480161ec709c3e84d6e08dd19c9\", \"cronet_tests\": \"7c527e837bc6acd4e3d3dd024eebb6c7bc636f33\", \"cronet_unittests\": \"83e3aa0856cb14058c6e288ca6e0689d8180737f\", \"crypto_unittests\": \"1c5294ff991a9bb511c493886b90cb04ebfd9966\", \"delayloads_unittests\": \"4bd0889427f45b7f29aa0d11e0e07b11b242a56b\", \"device_unittests\": \"6f2bcd55cb3090ce8a124eacb531d1af320592e9\", \"display_unittests\": \"e6c67e890b7d2ad8bb7b24c8f2eca854ca942a58\", \"elevation_service_unittests\": \"5f4ed5a55375f56d6e299f4cef12a9070b10a08f\", \"events_unittests\": \"cd94a78bd38111b2c5a3fb7292b690c8d7cfe4dc\", \"extensions_browsertests\": \"b5f21a330644aed563672f143c6bed5fbb2806ef\", \"extensions_unittests\": \"362116317cff5aaf5a1d5aad642a69eb80e82139\", \"filesystem_service_unittests\": \"05ba2464d181cf6b5282c3aca2867424ad75c880\", \"gcm_unit_tests\": \"27fe447b4f0a39f6515f12aa40d98b176171f79b\", \"gcp_unittests\": \"49fcc0910966ab97574fc62a789e22731ed6788b\", \"gfx_unittests\": \"7b638206e9273e0833487113a9edadef8c80307c\", \"gin_unittests\": \"797012a24e05d93f0f049712bc12bf88da6bc23a\", \"google_apis_unittests\": \"7a1383b5c7e84b74b47c045300789034ae9d77c6\", \"gpu_unittests\": \"bba1f83b28fffbc57d43e77c98691a2796ada1b6\", \"gwp_asan_unittests\": \"33e7e011a3b1f991f44b156f492ad7f1a615ddf7\", \"headless_browsertests\": \"8c195ba3eafa474e47304ffafa0df32f32ef59c8\", \"headless_unittests\": \"d6b74fb6366df6d66b98d5d196c0d7fdad0296cc\", \"install_static_unittests\": \"56c4f64f2a8a4eb7f34e4afbd35678a1672b72bb\", \"installer_util_unittests\": \"a2dfc4730034c646deb0d5c96e3da375f6e1b787\", \"interactive_ui_tests\": \"b60e3133d7a2415b5d6554445f180bf50d7f1999\", \"ipc_tests\": \"82a61fdf643c06400ae9a0448c4360ece59d7b4b\", \"jingle_unittests\": \"51641d3f0039dc8fff9520a216cb1526b103da57\", \"latency_unittests\": \"00d4c990a9502d3aa845b0d5a35f1f03951b204f\", \"libjingle_xmpp_unittests\": \"f349bc1015493991fa0c3647bacfc5270f2253ad\", \"liburlpattern_unittests\": \"c7c57cdbf859803ef8ec8230343ca6f028a5565a\", \"media_blink_unittests\": \"6e820d53bb3228625f0be0c758993e270e794ff7\", \"media_unittests\": \"9bbcd337197664882d4ed3629f954b75ba108047\", \"message_center_unittests\": \"9f659e94c9930915aa63311b5c5ab4f4bc42c3f8\", \"midi_unittests\": \"d56ea7e1ad6fd7ab68fa71f92f1cc44a9d72f516\", \"mojo_core_unittests\": \"88e3c5bfd9a07c6a1d131fd55b60b7315c2b262d\", \"mojo_unittests\": \"1a9bca54998547d97adc317887c882e745ef71e6\", \"nacl_loader_unittests\": \"f598d52364cd2471df163ef14a06131ca218dae6\", \"native_theme_unittests\": \"25be7fcb239d7de10c989455caee38b54c80527b\", \"net_unittests\": \"31687511c84b921489e2e7b059d798d68909fff2\", \"notification_helper_unittests\": \"4de1cc1294544255e7951057cb6e1db0951af370\", \"pdf_unittests\": \"aa8e6858561c62d30c3b6906f2ff4e810d330dd2\", \"perfetto_unittests\": \"5981ce096516e1c3aa4f79ce30e7cf1f654a6a59\", \"ppapi_unittests\": \"4467ecd622167ef16f9f1fa509b0a6be8265bc5d\", \"printing_unittests\": \"b0051dff2e988322183479816782cd20d4db4583\", \"remoting_unittests\": \"db23e4e7d2d1e6307c70deb81a5b7736d0a092ab\", \"sbox_integration_tests\": \"c062bfa86fb8b19748dd650138b92eacad3776b1\", \"sbox_unittests\": \"811c0cc04113a511d22675066c30650dd058d6a1\", \"sbox_validation_tests\": \"5f6b71e520ba11793a3654ee05e4a540bf11f4d3\", \"service_manager_unittests\": \"92d6269faabd1f025ea800c350794a0e68d6cd30\", \"services_unittests\": \"81e28e683dfb19f8c083b90c9df8ce5ffb336d58\", \"setup_unittests\": \"be67aadea16febdfdb08ea5d7e33e8ee9152f8c2\", \"shell_dialogs_unittests\": \"43ebc4e62b601f07d8dd2c20fea7660c7268ba46\", \"skia_unittests\": \"a49815aadb370b52266c12726f6c515700676c3c\", \"snapshot_unittests\": \"06ff69b8ba36245ed1787559c6431a6421bc77ef\", \"sql_unittests\": \"ae9a7cd04277de1b36e67a5a2b0a2551f04c48d9\", \"storage_unittests\": \"b655b03c979ba753504a6fdc5465f830eb6dae0a\", \"sync_integration_tests\": \"32030dc7af810eb2f9bf3357e9fd34e076f5cf1b\", \"traffic_annotation_auditor_unittests\": \"3dbcabf1af255e549353670534b2868a2a1c3388\", \"ui_base_unittests\": \"569c11dcd4f4f2fdb4abaf2129e6e25b3fee6c7b\", \"ui_touch_selection_unittests\": \"c93c2ad2fb42272a6d3599510f202b2ad49a5c7b\", \"unit_tests\": \"9dc142062d93b20ee343fa1456a680b46ba7da3f\", \"updater_tests\": \"7cfee579f225b74eedf83efea1514eec6f8b091f\", \"url_unittests\": \"cdc133a5227fb0caf886cc6a3330e9a150db3813\", \"views_unittests\": \"83ac9c6082bb697453cdbc4b280f92b2ea3f1fe8\", \"viz_unittests\": \"71d02faa8e901eb470c00025eaee6748a768e25d\", \"vr_common_unittests\": \"2b86b03d0ecc775a7a25cd615cc87a555f03b3ed\", \"vr_pixeltests\": \"05433b8d8eb9eaa9bd9dab8569dd488e9c76be95\", \"weblayer_browsertests\": \"350c87383284906550dac24fb3ac42d812ab4b9f\", \"weblayer_unittests\": \"7a5c4d6b96fc88f5a9c71a70fd7b6980cd13675e\", \"wm_unittests\": \"5eb4401f594ec2548e6641edd8513bce3acf6b89\", \"wtf_unittests\": \"e596ae1e3264aa4054bb74bc7b97ee23f55c3715\", \"zlib_unittests\": \"4ddb142a4d260cda68e9a1fc51f44d7c3f9a69ef\", \"zucchini_unittests\": \"466d8f4c62fafea967a91ed8d91c0b56467264fc\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_91c730c3e6ead475130e2ae78bc3f03e09cb9dcb91c3469d4450b29020110401_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-qsbv\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"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\": [\"5657-0417a3f\"], \"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": "1608093046445195",
"status": "COMPLETED",
"status_changed_ts": "1608096355902394",
"tags": [
"build_address:luci.chromium.ci/win-asan/16253",
"builder:win-asan",
"buildset:commit/git/989b00ac8d3e201d5981260495bfbebcd84d0941",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/989b00ac8d3e201d5981260495bfbebcd84d0941",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026216317215647264",
"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/8860786255043543728/+/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:50829d93cec93f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1608096355906757",
"url": "https://ci.chromium.org/b/8860786255043543728",
"utcnow_ts": "1608096357216852"
}
}
[05:25:57.233] Invocation finished in 1h0m11.081590868s with status SUCCEEDED