[05:52:50.449] New invocation is queued and will start shortly
[05:52:51.541] Starting the invocation (attempt 1)
[05:52:51.572] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:52:51.572] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:52:51.572] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018057112182113104",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"75f1108ede38ba6f826b94102306933404042dbf\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9018057112182113104",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/75f1108ede38ba6f826b94102306933404042dbf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/75f1108ede38ba6f826b94102306933404042dbf",
"gitiles_ref:refs/heads/master"
]
}
[05:52:52.352] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615873971771461",
"id": "8852627050134614912",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"75f1108ede38ba6f826b94102306933404042dbf\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615873972188925",
"tags": [
"build_address:luci.chromium.ci/win-asan/17813",
"builder:win-asan",
"buildset:commit/git/75f1108ede38ba6f826b94102306933404042dbf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/75f1108ede38ba6f826b94102306933404042dbf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018057112182113104",
"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/8852627050134614912/+/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": "1615873972189035",
"url": "https://ci.chromium.org/b/8852627050134614912",
"utcnow_ts": "1615873972336956"
}
}
[05:52:52.352] Task URL: https://ci.chromium.org/b/8852627050134614912
[05:52:52.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:2:0) after 1m0s
[05:52:54.749] Received PubSub notification, asking Buildbucket for the build status
[05:52:54.823] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:52.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:2:0)
[05:53:52.393] Timer tick, asking Buildbucket for the build status
[05:53:52.468] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:52.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:4:0) after 1m0s
[05:54:52.463] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:4:0)
[05:54:52.463] Timer tick, asking Buildbucket for the build status
[05:54:52.558] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:52.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:5:0) after 1m0s
[05:55:52.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:5:0)
[05:55:52.552] Timer tick, asking Buildbucket for the build status
[05:55:52.623] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:52.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:6:0) after 1m0s
[05:56:52.695] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:6:0)
[05:56:52.695] Timer tick, asking Buildbucket for the build status
[05:56:52.947] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:52.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:7:0) after 1m0s
[05:57:52.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:7:0)
[05:57:52.964] Timer tick, asking Buildbucket for the build status
[05:57:53.036] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:53.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:8:0) after 1m0s
[05:58:53.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:8:0)
[05:58:53.056] Timer tick, asking Buildbucket for the build status
[05:58:53.144] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:53.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:9:0) after 1m0s
[05:59:53.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:9:0)
[05:59:53.162] Timer tick, asking Buildbucket for the build status
[05:59:53.245] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:53.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:10:0) after 1m0s
[06:00:53.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:10:0)
[06:00:53.257] Timer tick, asking Buildbucket for the build status
[06:00:53.400] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:53.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:11:0) after 1m0s
[06:01:53.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:11:0)
[06:01:53.432] Timer tick, asking Buildbucket for the build status
[06:01:53.884] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:53.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:12:0) after 1m0s
[06:02:53.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:12:0)
[06:02:53.922] Timer tick, asking Buildbucket for the build status
[06:02:54.091] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:54.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:13:0) after 1m0s
[06:03:54.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:13:0)
[06:03:54.118] Timer tick, asking Buildbucket for the build status
[06:03:54.187] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:54.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:14:0) after 1m0s
[06:04:54.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:14:0)
[06:04:54.190] Timer tick, asking Buildbucket for the build status
[06:04:54.319] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:54.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:15:0) after 1m0s
[06:05:54.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:15:0)
[06:05:54.354] Timer tick, asking Buildbucket for the build status
[06:05:54.598] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:54.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:16:0) after 1m0s
[06:06:54.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:16:0)
[06:06:54.609] Timer tick, asking Buildbucket for the build status
[06:06:54.991] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:54.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:17:0) after 1m0s
[06:07:55.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:17:0)
[06:07:55.008] Timer tick, asking Buildbucket for the build status
[06:07:55.109] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:55.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:18:0) after 1m0s
[06:08:55.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:18:0)
[06:08:55.156] Timer tick, asking Buildbucket for the build status
[06:08:55.595] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:55.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:19:0) after 1m0s
[06:09:55.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:19:0)
[06:09:55.699] Timer tick, asking Buildbucket for the build status
[06:09:55.789] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:55.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:20:0) after 1m0s
[06:10:55.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:20:0)
[06:10:55.846] Timer tick, asking Buildbucket for the build status
[06:10:55.957] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:55.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:21:0) after 1m0s
[06:11:56.002] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:21:0)
[06:11:56.002] Timer tick, asking Buildbucket for the build status
[06:11:56.339] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:56.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:22:0) after 1m0s
[06:12:56.342] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:22:0)
[06:12:56.342] Timer tick, asking Buildbucket for the build status
[06:12:56.438] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:56.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:23:0) after 1m0s
[06:13:56.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:23:0)
[06:13:56.493] Timer tick, asking Buildbucket for the build status
[06:13:56.659] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:56.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:24:0) after 1m0s
[06:14:56.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:24:0)
[06:14:56.684] Timer tick, asking Buildbucket for the build status
[06:14:56.857] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:56.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:25:0) after 1m0s
[06:15:56.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:25:0)
[06:15:56.902] Timer tick, asking Buildbucket for the build status
[06:15:56.984] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:56.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:26:0) after 1m0s
[06:16:57.041] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:26:0)
[06:16:57.041] Timer tick, asking Buildbucket for the build status
[06:16:57.131] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:57.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:27:0) after 1m0s
[06:17:57.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:27:0)
[06:17:57.176] Timer tick, asking Buildbucket for the build status
[06:17:57.276] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:57.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:28:0) after 1m0s
[06:18:57.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:28:0)
[06:18:57.304] Timer tick, asking Buildbucket for the build status
[06:18:57.380] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:57.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:29:0) after 1m0s
[06:19:57.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:29:0)
[06:19:57.405] Timer tick, asking Buildbucket for the build status
[06:19:57.652] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:57.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:30:0) after 1m0s
[06:20:57.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:30:0)
[06:20:57.720] Timer tick, asking Buildbucket for the build status
[06:20:57.794] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:57.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:31:0) after 1m0s
[06:21:57.817] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:31:0)
[06:21:57.817] Timer tick, asking Buildbucket for the build status
[06:21:57.889] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:57.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:32:0) after 1m0s
[06:22:57.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:32:0)
[06:22:57.994] Timer tick, asking Buildbucket for the build status
[06:22:58.104] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:58.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:33:0) after 1m0s
[06:23:58.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:33:0)
[06:23:58.162] Timer tick, asking Buildbucket for the build status
[06:23:58.253] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:58.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:34:0) after 1m0s
[06:24:58.297] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:34:0)
[06:24:58.297] Timer tick, asking Buildbucket for the build status
[06:24:58.730] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:58.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:35:0) after 1m0s
[06:25:58.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:35:0)
[06:25:58.825] Timer tick, asking Buildbucket for the build status
[06:25:59.107] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:59.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:36:0) after 1m0s
[06:26:59.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:36:0)
[06:26:59.202] Timer tick, asking Buildbucket for the build status
[06:26:59.289] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:59.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:37:0) after 1m0s
[06:27:59.350] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:37:0)
[06:27:59.350] Timer tick, asking Buildbucket for the build status
[06:27:59.448] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:59.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:38:0) after 1m0s
[06:28:59.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:38:0)
[06:28:59.508] Timer tick, asking Buildbucket for the build status
[06:28:59.646] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:59.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:39:0) after 1m0s
[06:29:59.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:39:0)
[06:29:59.691] Timer tick, asking Buildbucket for the build status
[06:29:59.792] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:59.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:40:0) after 1m0s
[06:30:59.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:40:0)
[06:30:59.851] Timer tick, asking Buildbucket for the build status
[06:30:59.914] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:59.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:41:0) after 1m0s
[06:32:00.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:41:0)
[06:32:00.006] Timer tick, asking Buildbucket for the build status
[06:32:00.190] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:00.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:42:0) after 1m0s
[06:33:00.744] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:42:0)
[06:33:00.744] Timer tick, asking Buildbucket for the build status
[06:33:00.825] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:00.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:43:0) after 1m0s
[06:34:00.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:43:0)
[06:34:00.988] Timer tick, asking Buildbucket for the build status
[06:34:01.237] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:01.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:44:0) after 1m0s
[06:35:01.270] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:44:0)
[06:35:01.270] Timer tick, asking Buildbucket for the build status
[06:35:01.522] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:01.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:45:0) after 1m0s
[06:36:01.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:45:0)
[06:36:01.609] Timer tick, asking Buildbucket for the build status
[06:36:01.834] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:01.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:46:0) after 1m0s
[06:37:01.821] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:46:0)
[06:37:01.821] Timer tick, asking Buildbucket for the build status
[06:37:01.914] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:01.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:47:0) after 1m0s
[06:38:01.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:47:0)
[06:38:01.931] Timer tick, asking Buildbucket for the build status
[06:38:02.030] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:02.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:48:0) after 1m0s
[06:39:02.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:48:0)
[06:39:02.074] Timer tick, asking Buildbucket for the build status
[06:39:02.148] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:02.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:49:0) after 1m0s
[06:40:02.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:49:0)
[06:40:02.169] Timer tick, asking Buildbucket for the build status
[06:40:02.275] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:02.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:50:0) after 1m0s
[06:41:02.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:50:0)
[06:41:02.336] Timer tick, asking Buildbucket for the build status
[06:41:02.526] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:02.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:51:0) after 1m0s
[06:42:02.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:51:0)
[06:42:02.555] Timer tick, asking Buildbucket for the build status
[06:42:02.826] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:02.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:52:0) after 1m0s
[06:43:02.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:52:0)
[06:43:02.869] Timer tick, asking Buildbucket for the build status
[06:43:03.057] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:03.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:53:0) after 1m0s
[06:44:03.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:53:0)
[06:44:03.130] Timer tick, asking Buildbucket for the build status
[06:44:03.420] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:03.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:54:0) after 1m0s
[06:45:03.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:54:0)
[06:45:03.456] Timer tick, asking Buildbucket for the build status
[06:45:03.873] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:03.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:55:0) after 1m0s
[06:46:03.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:55:0)
[06:46:03.910] Timer tick, asking Buildbucket for the build status
[06:46:04.004] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:04.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:56:0) after 1m0s
[06:47:04.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:56:0)
[06:47:04.147] Timer tick, asking Buildbucket for the build status
[06:47:04.428] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:04.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:57:0) after 1m0s
[06:48:04.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:57:0)
[06:48:04.539] Timer tick, asking Buildbucket for the build status
[06:48:04.659] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:04.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:58:0) after 1m0s
[06:49:04.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:58:0)
[06:49:04.682] Timer tick, asking Buildbucket for the build status
[06:49:04.755] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:04.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:59:0) after 1m0s
[06:50:04.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:59:0)
[06:50:04.857] Timer tick, asking Buildbucket for the build status
[06:50:05.030] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:05.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:60:0) after 1m0s
[06:51:05.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:60:0)
[06:51:05.259] Timer tick, asking Buildbucket for the build status
[06:51:05.357] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:05.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:61:0) after 1m0s
[06:52:05.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:61:0)
[06:52:05.386] Timer tick, asking Buildbucket for the build status
[06:52:05.817] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:05.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:62:0) after 1m0s
[06:53:05.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:62:0)
[06:53:05.883] Timer tick, asking Buildbucket for the build status
[06:53:06.028] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:06.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:63:0) after 1m0s
[06:54:06.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:63:0)
[06:54:06.055] Timer tick, asking Buildbucket for the build status
[06:54:06.135] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:06.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:64:0) after 1m0s
[06:55:06.155] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:64:0)
[06:55:06.155] Timer tick, asking Buildbucket for the build status
[06:55:06.236] Build 8852627050134614912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:06.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018057112182113104:65:0) after 1m0s
[06:55:42.597] Received PubSub notification, asking Buildbucket for the build status
[06:55:42.680] Build 8852627050134614912: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:55:42.680] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615877741923325",
"created_by": "project:chromium",
"created_ts": "1615873971771461",
"id": "8852627050134614912",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"75f1108ede38ba6f826b94102306933404042dbf\"}}",
"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\": \"75f1108ede38ba6f826b94102306933404042dbf\", \"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-dcpw\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17813, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-2\", \"got_angle_revision\": \"687d3153197e5ef162e7bc75e931a09208ba3fe6\", \"got_dawn_revision\": \"8c40cad1c92d7e85154bbae4cb92539e2200d139\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"75f1108ede38ba6f826b94102306933404042dbf\", \"got_revision_cp\": \"refs/heads/master@{#863159}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"69b79eec6533f113920a5dd863ee2dea7d0c64e7\", \"got_v8_revision\": \"4e2fbf2b6d4fc2e9bbf239e146973a15bb3a0d40\", \"got_v8_revision_cp\": \"refs/heads/9.1.129@{#1}\", \"got_webrtc_revision\": \"6097b0fac0946a29d59a9266ea656c39b3fd7336\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33456}\", \"is_cached\": true, \"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\": \"75f1108ede38ba6f826b94102306933404042dbf\", \"swarm_hashes_refs/heads/master(at){#863159}_without_patch\": {\"absl_hardening_tests\": \"ff60933f24be52951ea45baa22a8270eb14b2143\", \"accessibility_unittests\": \"731ce8a0e159a4b28b2443e3544ffcfec8e3cf2f\", \"angle_unittests\": \"8ac3853da2be95e354d9ab5f517e9d63d7597b33\", \"app_shell_unittests\": \"988165fe140f408106273802b63e32851672a953\", \"aura_unittests\": \"c997a789c0ed337b43537b79aa10dcd7f0082cc8\", \"base_unittests\": \"a4a8b1db11a8404bc73b8a6c212847ee70f4f731\", \"base_util_unittests\": \"03d58d3f927e35e260ab4ef2eea36fcc6fe6ecf8\", \"blink_common_unittests\": \"805a55e6a930330eb45f2c89560696e810bda368\", \"blink_fuzzer_unittests\": \"46920ad89b2c8d3e9fa376a48b26a0961d2a3c79\", \"blink_heap_unittests\": \"47cb602c77a5d13b19b9d497d416a529edf0b23f\", \"blink_platform_unittests\": \"fc8f79f1f6c8021faa1fde8a9a7ef6f225c2a5a2\", \"blink_unittests\": \"89ffd182999328fe6d72cc5cc33eb5c05baff8b8\", \"boringssl_crypto_tests\": \"e6d19b47f36dd6ac31be7e976e8b900e0019e90f\", \"boringssl_ssl_tests\": \"2c1c01f70551d36027bbe39461d78f657a05c5f9\", \"browser_switcher_bho_unittests\": \"b6fc30cb933de7bdf94949827ab9c5ab3c6eec70\", \"browser_tests\": \"7b6f297d794c22bc7516901e917788d66abad498\", \"capture_unittests\": \"bf27e63a82788358552fe0394d0fd21cf15e418a\", \"cast_unittests\": \"d195d121870ad35ab558801b35164b2a08bc0067\", \"cc_unittests\": \"9ce0b4c01e1252cd35eced066022c9e15f4b6668\", \"chrome_app_unittests\": \"5876ff3119fa6f1fbcf50151fa17288a347dd599\", \"chrome_cleaner_unittests\": \"9ba8f98a3dd41bf028922a637c56f2e6bcadb8a0\", \"chrome_elf_unittests\": \"410ed25f9e018ffd52285abf9312b2ab588b5e67\", \"chromedriver_unittests\": \"f246fb1f35ecb96a627fa7b0439bc533a0108f1b\", \"color_unittests\": \"b383b15831c7870697a23187f62cf4ceef60fe60\", \"components_browsertests\": \"a653772402ba38d0dd610e4ac8bc42f5d2a93517\", \"components_unittests\": \"fe3afa98ae39d14c5600d93cf2567de9d3e71b62\", \"compositor_unittests\": \"967f1143a102fa9186de35f8469b1666c10a9f01\", \"content_browsertests\": \"16dba304888d0731935b3fe417c287fd53fc1157\", \"content_unittests\": \"4b62f27fc0dccd73a5813615dfdd4212ffc7fa0a\", \"courgette_unittests\": \"0b0d7e7d17940d7924d74d26bab51d25e7d5d810\", \"crashpad_tests\": \"b42e7bd78ef62406e35cff32d6883d380701b353\", \"cronet_tests\": \"37cb75f363c498ee5de92888db130084893b3aca\", \"cronet_unittests\": \"ef6351252acfd86cba32ee41e272049bb7e8b1dc\", \"crypto_unittests\": \"234a2ea754fc5d363a82eae14b9b92f31298d240\", \"delayloads_unittests\": \"4863b27f192fb200ba81b977fb9d5554454556fb\", \"device_unittests\": \"390804426aeee92539cff4438988b17e9a34a6ca\", \"display_unittests\": \"af3ff5142ffc65d9012f68b0f88535fd7111575d\", \"elevation_service_unittests\": \"939915ddaa0c31b582b686b6072e4053934ec931\", \"events_unittests\": \"a1efddf4efc7c6dbf80043be30aeaf71817335cc\", \"extensions_browsertests\": \"27b98b4c82506fd18d45558d28fa164ca4f26f00\", \"extensions_unittests\": \"0b9a683f6d8b4a75eb6ca90672c79285faba9d19\", \"filesystem_service_unittests\": \"f6e1a6b5562cd17171cdfb7fe8fd6219bc728278\", \"gcm_unit_tests\": \"949b16ba4b3c84d13c1c287e932f155e8df6aa40\", \"gcp_unittests\": \"fff35ba7498669562d2146d088207de7b55cfbdb\", \"gfx_unittests\": \"d1d8ab2a00557be03eae346bdb54901b31f2b148\", \"gin_unittests\": \"ff6c79a96ec98651422e15529c0fd9913d14e608\", \"google_apis_unittests\": \"b97ee960aeca08bfa9dd0070cb751ef84e653cee\", \"gpu_unittests\": \"6168169bb80b726d74b7a1f51628aff3a02c8d97\", \"gwp_asan_unittests\": \"67078447814790afc812dcb1d709d9fdd7c0b11d\", \"headless_browsertests\": \"463d0d4988ecc228b69bccbc7b3dca96abe54c42\", \"headless_unittests\": \"eabf11efb59526b959a79f6c31bf76e994628806\", \"install_static_unittests\": \"5a13698443b622088edcece1ae01a4a018e60934\", \"installer_util_unittests\": \"f50474f62a96842504a00f1944bd7360f4d1d37e\", \"interactive_ui_tests\": \"c06f7bbe4f0eea9cca9693a8edcae2b6d44f7e1f\", \"ipc_tests\": \"5852056e682aa5bcbf0ae11ff9cbee620989fbbc\", \"jingle_unittests\": \"5a5bb58aaebedbee0a69b7a04a91f51f00e42a74\", \"latency_unittests\": \"918290025d90f72b2fc02739b1c09646abbfa29f\", \"libjingle_xmpp_unittests\": \"b8ec9f7de8a2f6a27f9aad6d000084f8729a7292\", \"liburlpattern_unittests\": \"3645f0ef05bc7ad2eff3192240160a4789553ceb\", \"media_blink_unittests\": \"eed691564e3f91157b93a90b8873f377bc297b35\", \"media_unittests\": \"bad7628b259165f183b4f4fbda4e6b22f7527940\", \"message_center_unittests\": \"706a14c9c278b228ac68cc4a6cb76ebf6cbd8021\", \"midi_unittests\": \"ee8da4a9745f68ee8505926291aeb29e270771c3\", \"mojo_core_unittests\": \"32d6b16c8a36f22110e6915b7c328b462aa8e839\", \"mojo_unittests\": \"835d79141cf7bbba652d11c5379d627ac218df06\", \"nacl_loader_unittests\": \"33bb2697cf9714ae106f70ffecac7b0c5d0b1c9f\", \"native_theme_unittests\": \"a5c39c1f91b0b9dde3f5d532bd9cb04072bb972d\", \"net_unittests\": \"7925484030f336b8d6c80f08de349df6eeacd3a3\", \"notification_helper_unittests\": \"703d5ceaccb01d0273004410e122d9574ccff746\", \"pdf_unittests\": \"eeec9367e1081292c87d262bf450b049b467b09d\", \"perfetto_unittests\": \"82b46f9bc59a616c9d42cca5379bf5fe9faa7bfa\", \"ppapi_unittests\": \"8a5788dc3304f24e1c08bc7092d1d56e409ebefe\", \"printing_unittests\": \"8140380a42b5086bf999a9474aad19cd53ebf38c\", \"remoting_unittests\": \"d1c618a275d2213af0865fa5d878640d5a461e2a\", \"sbox_integration_tests\": \"752a40805ed169c1ea84237b71b315e809551d90\", \"sbox_unittests\": \"ce026b4dc0f3c417bac3e95eb43d5225589b2df4\", \"sbox_validation_tests\": \"f106ea9983854508c6c36e79a8adddefd03f96cc\", \"service_manager_unittests\": \"532896a5b153a60f718f4d30020afba73788914e\", \"services_unittests\": \"eb41bade35949c99b6a2b9b6d7620d347cbc739d\", \"setup_unittests\": \"fdabef01242633d3d7dd306b715528d974ce0440\", \"shell_dialogs_unittests\": \"3218047d9b2e58c96d3df5466b657b9ff685ce81\", \"skia_unittests\": \"43169242f2fae9eb772e33422597e055797f6035\", \"snapshot_unittests\": \"c133f99d2b695e2d2a92d9d54674293e0d83b128\", \"sql_unittests\": \"31cacd548c9a101d1ed3faec7d9fa450df657fdf\", \"storage_unittests\": \"be990866bcf8afe1d261f241d86f12c32fca90ac\", \"sync_integration_tests\": \"9f6708719043cc4bda7c2a57fd360ce5e43d9eb6\", \"traffic_annotation_auditor_unittests\": \"f50c4aa37f74a27f8772a53e508f183dcb6be975\", \"ui_base_unittests\": \"7267b7d70f8a4dea64cec622231ded0e10251d89\", \"ui_touch_selection_unittests\": \"d06195366330b0311f3ba8e9b93c7bec5962503f\", \"unit_tests\": \"56316ed9ecde91b91cd89b9f8362b8dfa23f7e48\", \"updater_tests\": \"2a34b683fd718cb8bcfd4ba811ab25c62ed70b99\", \"url_unittests\": \"1d7078bd73bd1f3a0ea379267811e9223ce6ec54\", \"views_unittests\": \"a5aa911ed1df3378169a7a5083662a6b0f4e3255\", \"viz_unittests\": \"4f1a46169e882c4eef722bb61814d65f51398600\", \"vr_common_unittests\": \"e6359254548597a586baaa0b7ddb4a88d29e4ca4\", \"vr_pixeltests\": \"b5ce5b5efc33f8e431c7fe0e7f666a101a75a621\", \"weblayer_browsertests\": \"32c05101cece95689297dbf53c6379d23524d82f\", \"weblayer_unittests\": \"787e2105489eb1d85738b785ffb2e7e6be706955\", \"wm_unittests\": \"5e5dd1c616d00a6c5d7db0f04da1aeb3d9603b5d\", \"wtf_unittests\": \"9021a0afa746d4760cb94ba7b0531c14a556a513\", \"zlib_unittests\": \"e0526d7b68e242cc47f7508b2c3baf4645f295c5\", \"zucchini_unittests\": \"4e9dac098015cd5cc454b62a59e6aadf139c4c54\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-10-dcpw\"], \"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\": [\"5754-8c4f760\"], \"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": "1615873974117889",
"status": "COMPLETED",
"status_changed_ts": "1615877742245652",
"tags": [
"build_address:luci.chromium.ci/win-asan/17813",
"builder:win-asan",
"buildset:commit/git/75f1108ede38ba6f826b94102306933404042dbf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/75f1108ede38ba6f826b94102306933404042dbf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018057112182113104",
"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/8852627050134614912/+/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:525269a0c2a28c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615877742251891",
"url": "https://ci.chromium.org/b/8852627050134614912",
"utcnow_ts": "1615877742667846"
}
}
[06:55:42.680] Invocation finished in 1h2m52.24499604s with status SUCCEEDED