[21:55:30.520] New invocation is queued and will start shortly
[21:55:31.746] Starting the invocation (attempt 1)
[21:55:31.783] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:55:31.783] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:55:31.783] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020533261418086256",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"acde0ea6a99b834c022a8dc8cecdb9e8cfc92974\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020533261418086256",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/acde0ea6a99b834c022a8dc8cecdb9e8cfc92974",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/acde0ea6a99b834c022a8dc8cecdb9e8cfc92974",
"gitiles_ref:refs/heads/master"
]
}
[21:55:34.004] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613512532005731",
"id": "8855103199198292000",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"acde0ea6a99b834c022a8dc8cecdb9e8cfc92974\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613512533376522",
"tags": [
"build_address:luci.chromium.ci/win-asan/17382",
"builder:win-asan",
"buildset:commit/git/acde0ea6a99b834c022a8dc8cecdb9e8cfc92974",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/acde0ea6a99b834c022a8dc8cecdb9e8cfc92974",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020533261418086256",
"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/8855103199198292000/+/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": "1613512533390031",
"url": "https://ci.chromium.org/b/8855103199198292000",
"utcnow_ts": "1613512533933673"
}
}
[21:55:34.004] Task URL: https://ci.chromium.org/b/8855103199198292000
[21:55:34.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:2:0) after 1m0s
[21:55:38.599] Received PubSub notification, asking Buildbucket for the build status
[21:55:38.688] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:33.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:2:0)
[21:56:33.994] Timer tick, asking Buildbucket for the build status
[21:56:34.129] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:34.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:4:0) after 1m0s
[21:57:34.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:4:0)
[21:57:34.166] Timer tick, asking Buildbucket for the build status
[21:57:34.397] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:34.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:5:0) after 1m0s
[21:58:34.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:5:0)
[21:58:34.501] Timer tick, asking Buildbucket for the build status
[21:58:34.619] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:34.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:6:0) after 1m0s
[21:59:34.645] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:6:0)
[21:59:34.645] Timer tick, asking Buildbucket for the build status
[21:59:34.727] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:34.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:7:0) after 1m0s
[22:00:34.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:7:0)
[22:00:34.806] Timer tick, asking Buildbucket for the build status
[22:00:34.881] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:34.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:8:0) after 1m0s
[22:01:35.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:8:0)
[22:01:35.056] Timer tick, asking Buildbucket for the build status
[22:01:35.152] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:35.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:9:0) after 1m0s
[22:02:35.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:9:0)
[22:02:35.248] Timer tick, asking Buildbucket for the build status
[22:02:35.378] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:35.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:10:0) after 1m0s
[22:03:35.440] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:10:0)
[22:03:35.440] Timer tick, asking Buildbucket for the build status
[22:03:35.518] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:35.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:11:0) after 1m0s
[22:04:35.650] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:11:0)
[22:04:35.650] Timer tick, asking Buildbucket for the build status
[22:04:35.730] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:35.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:12:0) after 1m0s
[22:05:35.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:12:0)
[22:05:35.940] Timer tick, asking Buildbucket for the build status
[22:05:36.048] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:36.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:13:0) after 1m0s
[22:06:36.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:13:0)
[22:06:36.135] Timer tick, asking Buildbucket for the build status
[22:06:36.322] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:36.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:14:0) after 1m0s
[22:07:36.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:14:0)
[22:07:36.340] Timer tick, asking Buildbucket for the build status
[22:07:36.410] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:36.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:15:0) after 1m0s
[22:08:36.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:15:0)
[22:08:36.424] Timer tick, asking Buildbucket for the build status
[22:08:36.599] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:36.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:16:0) after 1m0s
[22:09:36.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:16:0)
[22:09:36.686] Timer tick, asking Buildbucket for the build status
[22:09:36.759] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:36.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:17:0) after 1m0s
[22:10:36.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:17:0)
[22:10:36.889] Timer tick, asking Buildbucket for the build status
[22:10:37.129] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:37.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:18:0) after 1m0s
[22:11:37.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:18:0)
[22:11:37.226] Timer tick, asking Buildbucket for the build status
[22:11:37.438] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:37.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:19:0) after 1m0s
[22:12:37.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:19:0)
[22:12:37.495] Timer tick, asking Buildbucket for the build status
[22:12:37.573] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:37.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:20:0) after 1m0s
[22:13:37.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:20:0)
[22:13:37.625] Timer tick, asking Buildbucket for the build status
[22:13:37.707] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:37.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:21:0) after 1m0s
[22:14:37.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:21:0)
[22:14:37.767] Timer tick, asking Buildbucket for the build status
[22:14:37.870] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:37.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:22:0) after 1m0s
[22:15:37.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:22:0)
[22:15:37.930] Timer tick, asking Buildbucket for the build status
[22:15:38.055] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:38.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:23:0) after 1m0s
[22:16:38.114] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:23:0)
[22:16:38.114] Timer tick, asking Buildbucket for the build status
[22:16:38.217] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:38.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:24:0) after 1m0s
[22:17:38.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:24:0)
[22:17:38.260] Timer tick, asking Buildbucket for the build status
[22:17:38.360] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:38.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:25:0) after 1m0s
[22:18:38.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:25:0)
[22:18:38.407] Timer tick, asking Buildbucket for the build status
[22:18:38.589] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:38.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:26:0) after 1m0s
[22:19:38.594] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:26:0)
[22:19:38.594] Timer tick, asking Buildbucket for the build status
[22:19:38.844] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:38.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:27:0) after 1m0s
[22:20:38.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:27:0)
[22:20:38.896] Timer tick, asking Buildbucket for the build status
[22:20:39.038] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:39.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:28:0) after 1m0s
[22:21:39.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:28:0)
[22:21:39.062] Timer tick, asking Buildbucket for the build status
[22:21:39.163] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:39.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:29:0) after 1m0s
[22:22:39.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:29:0)
[22:22:39.654] Timer tick, asking Buildbucket for the build status
[22:22:39.778] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:39.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:30:0) after 1m0s
[22:23:39.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:30:0)
[22:23:39.855] Timer tick, asking Buildbucket for the build status
[22:23:39.984] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:39.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:31:0) after 1m0s
[22:24:40.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:31:0)
[22:24:40.039] Timer tick, asking Buildbucket for the build status
[22:24:40.137] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:40.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:32:0) after 1m0s
[22:25:40.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:32:0)
[22:25:40.183] Timer tick, asking Buildbucket for the build status
[22:25:40.439] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:40.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:33:0) after 1m0s
[22:26:40.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:33:0)
[22:26:40.499] Timer tick, asking Buildbucket for the build status
[22:26:40.644] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:40.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:34:0) after 1m0s
[22:27:40.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:34:0)
[22:27:40.694] Timer tick, asking Buildbucket for the build status
[22:27:40.882] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:40.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:35:0) after 1m0s
[22:28:40.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:35:0)
[22:28:40.942] Timer tick, asking Buildbucket for the build status
[22:28:41.156] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:41.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:36:0) after 1m0s
[22:29:41.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:36:0)
[22:29:41.268] Timer tick, asking Buildbucket for the build status
[22:29:41.372] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:41.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:37:0) after 1m0s
[22:30:41.440] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:37:0)
[22:30:41.440] Timer tick, asking Buildbucket for the build status
[22:30:41.541] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:41.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:38:0) after 1m0s
[22:31:41.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:38:0)
[22:31:41.608] Timer tick, asking Buildbucket for the build status
[22:31:41.913] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:41.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:39:0) after 1m0s
[22:32:42.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:39:0)
[22:32:42.027] Timer tick, asking Buildbucket for the build status
[22:32:42.134] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:42.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:40:0) after 1m0s
[22:33:42.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:40:0)
[22:33:42.211] Timer tick, asking Buildbucket for the build status
[22:33:42.505] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:42.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:41:0) after 1m0s
[22:34:42.574] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:41:0)
[22:34:42.574] Timer tick, asking Buildbucket for the build status
[22:34:42.921] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:42.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:42:0) after 1m0s
[22:35:42.935] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:42:0)
[22:35:42.935] Timer tick, asking Buildbucket for the build status
[22:35:43.189] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:43.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:43:0) after 1m0s
[22:36:43.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:43:0)
[22:36:43.285] Timer tick, asking Buildbucket for the build status
[22:36:43.535] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:43.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:44:0) after 1m0s
[22:37:43.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:44:0)
[22:37:43.750] Timer tick, asking Buildbucket for the build status
[22:37:43.906] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:43.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:45:0) after 1m0s
[22:38:43.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:45:0)
[22:38:43.985] Timer tick, asking Buildbucket for the build status
[22:38:44.082] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:44.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:46:0) after 1m0s
[22:39:44.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:46:0)
[22:39:44.112] Timer tick, asking Buildbucket for the build status
[22:39:44.234] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:44.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:47:0) after 1m0s
[22:40:44.300] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:47:0)
[22:40:44.300] Timer tick, asking Buildbucket for the build status
[22:40:44.415] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:44.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:48:0) after 1m0s
[22:41:44.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:48:0)
[22:41:44.426] Timer tick, asking Buildbucket for the build status
[22:41:44.528] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:44.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:49:0) after 1m0s
[22:42:44.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:49:0)
[22:42:44.576] Timer tick, asking Buildbucket for the build status
[22:42:44.678] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:44.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:50:0) after 1m0s
[22:43:44.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:50:0)
[22:43:44.748] Timer tick, asking Buildbucket for the build status
[22:43:44.842] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:44.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:51:0) after 1m0s
[22:44:44.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:51:0)
[22:44:44.883] Timer tick, asking Buildbucket for the build status
[22:44:44.981] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:44.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:52:0) after 1m0s
[22:45:45.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:52:0)
[22:45:45.023] Timer tick, asking Buildbucket for the build status
[22:45:45.201] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:45.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:53:0) after 1m0s
[22:46:45.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:53:0)
[22:46:45.280] Timer tick, asking Buildbucket for the build status
[22:46:45.387] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:45.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:54:0) after 1m0s
[22:47:45.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:54:0)
[22:47:45.363] Timer tick, asking Buildbucket for the build status
[22:47:45.460] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:45.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:55:0) after 1m0s
[22:48:45.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:55:0)
[22:48:45.493] Timer tick, asking Buildbucket for the build status
[22:48:45.602] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:45.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:56:0) after 1m0s
[22:49:45.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:56:0)
[22:49:45.644] Timer tick, asking Buildbucket for the build status
[22:49:45.797] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:45.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:57:0) after 1m0s
[22:50:45.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:57:0)
[22:50:45.826] Timer tick, asking Buildbucket for the build status
[22:50:45.974] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:45.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:58:0) after 1m0s
[22:51:45.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:58:0)
[22:51:45.995] Timer tick, asking Buildbucket for the build status
[22:51:46.122] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:46.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:59:0) after 1m0s
[22:52:46.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:59:0)
[22:52:46.159] Timer tick, asking Buildbucket for the build status
[22:52:46.268] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:46.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:60:0) after 1m0s
[22:53:46.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:60:0)
[22:53:46.304] Timer tick, asking Buildbucket for the build status
[22:53:46.425] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:46.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:61:0) after 1m0s
[22:54:46.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:61:0)
[22:54:46.458] Timer tick, asking Buildbucket for the build status
[22:54:46.847] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:46.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:62:0) after 1m0s
[22:55:46.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:62:0)
[22:55:46.905] Timer tick, asking Buildbucket for the build status
[22:55:47.049] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:47.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:63:0) after 1m0s
[22:56:47.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:63:0)
[22:56:47.088] Timer tick, asking Buildbucket for the build status
[22:56:47.192] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:47.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:64:0) after 1m0s
[22:57:47.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:64:0)
[22:57:47.291] Timer tick, asking Buildbucket for the build status
[22:57:47.376] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:47.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:65:0) after 1m0s
[22:58:47.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:65:0)
[22:58:47.491] Timer tick, asking Buildbucket for the build status
[22:58:47.925] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:47.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:66:0) after 1m0s
[22:59:47.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:66:0)
[22:59:47.998] Timer tick, asking Buildbucket for the build status
[22:59:48.099] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:48.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:67:0) after 1m0s
[23:00:48.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:67:0)
[23:00:48.145] Timer tick, asking Buildbucket for the build status
[23:00:48.311] Build 8855103199198292000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:48.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020533261418086256:68:0) after 1m0s
[23:01:13.068] Received PubSub notification, asking Buildbucket for the build status
[23:01:13.223] Build 8855103199198292000: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:01:13.223] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613516472239219",
"created_by": "project:chromium",
"created_ts": "1613512532005731",
"id": "8855103199198292000",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"acde0ea6a99b834c022a8dc8cecdb9e8cfc92974\"}}",
"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\": \"acde0ea6a99b834c022a8dc8cecdb9e8cfc92974\", \"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-0-6l07\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17382, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-1\", \"got_angle_revision\": \"91b6c3b1a2b12f6c834238ed680d82c3de741cd0\", \"got_dawn_revision\": \"71b8c9f4ad6f75edea66e91295015d00237703f6\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"acde0ea6a99b834c022a8dc8cecdb9e8cfc92974\", \"got_revision_cp\": \"refs/heads/master@{#854464}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"69661d887fb54f80fe251561d3924d81dc48e674\", \"got_v8_revision\": \"51a9b91e1e5176bb007e749e7f506eee7b5407ba\", \"got_v8_revision_cp\": \"refs/heads/9.0.187@{#1}\", \"got_webrtc_revision\": \"8408c9938cb543a7cd5e7127927f3b4d0834ad59\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33272}\", \"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\": \"acde0ea6a99b834c022a8dc8cecdb9e8cfc92974\", \"swarm_hashes_refs/heads/master(at){#854464}_without_patch\": {\"absl_hardening_tests\": \"78c5376c6cab568dfd02731b5f1f7814fc71e88a\", \"accessibility_unittests\": \"2681326af6017bede247f1ba3df81acf25f4bae0\", \"angle_unittests\": \"63e091247dbb7791b064dae1b7d583c0797b322a\", \"app_shell_unittests\": \"6f5f7268f9e8273dd0939da054baeb442feb35d8\", \"aura_unittests\": \"d59da2f5392f8320bb1efe40b37bb8a0edb8dfd2\", \"base_unittests\": \"2136460fca4d553faa2854e7d9b3498ff7b383aa\", \"base_util_unittests\": \"8ce15ba8104f97aec401ef323e00c3b893493342\", \"blink_common_unittests\": \"0379b9a2ad9bf4934be2e576007abaa33d498a9f\", \"blink_fuzzer_unittests\": \"e9423d5d6a326463a421e8142ba8fcab8118c6ac\", \"blink_heap_unittests\": \"7b2623b42996e3560f84949d0288c647e326dac7\", \"blink_platform_unittests\": \"f204933fa28d0e332ca55532cdd50a2359c40e6b\", \"blink_unittests\": \"9f3d8ae825c9fecc24cb45817628b5e6d3876af8\", \"boringssl_crypto_tests\": \"9108fb64242108a6e6f42b04030194cc6a54cde4\", \"boringssl_ssl_tests\": \"dba48211b66fc8971f1ecf5fa8a3ae3b10282661\", \"browser_switcher_bho_unittests\": \"919796450b3a64d6e5b1a7f36d734f072210cacd\", \"browser_tests\": \"0507ef58019de2aa520c7bda09cea36e7c1426fd\", \"capture_unittests\": \"417b67f533c53347e6ad0072f3ae9795a6b30318\", \"cast_unittests\": \"5fb42845c370c32d399ed5ecba730e43e7980634\", \"cc_unittests\": \"8738108b63bad272aa163921531781cfbceec6e2\", \"chrome_app_unittests\": \"bfb6ab821d2030f99eb5979b0c358d320ff71a78\", \"chrome_cleaner_unittests\": \"f3cd43ee67e1e41dfd43db088a64cf22016973b6\", \"chrome_elf_unittests\": \"8c183e3b9b549d79f2a2d04e05032ce550d5eefd\", \"chromedriver_unittests\": \"2cdfff65ab506d903e245dfac8f11d577e149b73\", \"components_browsertests\": \"788c7f9a9ce8f9a70b8a05888e411b69a1a5ce08\", \"components_unittests\": \"3838c9fc249868dd7c516bd5dd1562024a672b47\", \"compositor_unittests\": \"7bd6f4376f506b087eed1ebf1c8007b6cbd7cd41\", \"content_browsertests\": \"f1461a0355c61b4f68cb736658b2a98faf42803f\", \"content_unittests\": \"d0d6dff51ac301786e9f0280652a35975fe7f6b4\", \"courgette_unittests\": \"147122c40731cdb5ece6d525b333422fdda18ce4\", \"crashpad_tests\": \"b08a44b077ffc76ae95b7fd2e191e10d8387be50\", \"cronet_tests\": \"6bbe9493682c40f25d00a81a7d761ec6dc0dd397\", \"cronet_unittests\": \"630915e95165643cc063fdf189633ced3e400a5c\", \"crypto_unittests\": \"316469c8ddf0af054a5d919a504bb8c5b00bcfea\", \"delayloads_unittests\": \"70c8008783cf7dc997b19c8a7466a4adedc046cc\", \"device_unittests\": \"1966e317278172cd8741c12cc077aed155731dbf\", \"display_unittests\": \"74e60167484a5a3b783b2a09dc13354d2e581b7f\", \"elevation_service_unittests\": \"2190469157c507475103c188bb4eaf9d500addb9\", \"events_unittests\": \"41698f3dc6eee0e32f37dda41110d88c338f2368\", \"extensions_browsertests\": \"c1e98ca0c5391669efead7ad393bc39115978905\", \"extensions_unittests\": \"fb4411207552db5e92515f796472f4ca5a5fa5fe\", \"filesystem_service_unittests\": \"273c400bfb39ba268f658183edfa4311b4c6e107\", \"gcm_unit_tests\": \"fb5f998097adbe5d1b9de6dd7b599abed35ef588\", \"gcp_unittests\": \"185ab519b331daead0e09931de967dbba5ce9d4b\", \"gfx_unittests\": \"c84ae52440846a8bbcdd36308d03763b3a142c5f\", \"gin_unittests\": \"54ae00077cc60bb990ef86e67d92c502425b007d\", \"google_apis_unittests\": \"a45b49beafadf91f528582d5ed531118932fedb6\", \"gpu_unittests\": \"8487b995696a803abe918506a7ee65677e029d66\", \"gwp_asan_unittests\": \"6aeffaaf12f78435928891242f768d466747d188\", \"headless_browsertests\": \"2a5a8979774eb3bef621cebed152ee75788bf08a\", \"headless_unittests\": \"35371b04fa3c993d585c5bd7cc801c881d01238d\", \"install_static_unittests\": \"b7498949eaa70121b374b699df120ef72de666a9\", \"installer_util_unittests\": \"37988de0c20df9e8c0b71dc1602011ae93328bbd\", \"interactive_ui_tests\": \"bfa4cb1f4fb647b21a6b282f8dc147e1bc5f38d4\", \"ipc_tests\": \"122d1470ac2a40be1cbcb0dbb209bcc3ef5f72a9\", \"jingle_unittests\": \"892a9043931c9886353457379b17e8275574a185\", \"latency_unittests\": \"d67ad8e2c7fe4cb070c7106ecc42d88007630172\", \"libjingle_xmpp_unittests\": \"4726cce8f64b76dbcb978a949a4b1e0be99c8710\", \"liburlpattern_unittests\": \"c5aecb4fa3b340e4e0cf0842d5328d66f50d4a07\", \"media_blink_unittests\": \"0e2fdb7f6463776d66d45d809e00206cc362f561\", \"media_unittests\": \"f22c9922a10b8d46f9144cb288369c55b4131d8a\", \"message_center_unittests\": \"9b4a94717c245f92b272fea2a4d46121204d1422\", \"midi_unittests\": \"34cb8c9c227008e39b223ce11d1442910ac4e4b8\", \"mojo_core_unittests\": \"70a03ae267a64441ba1fd52f674acf4d3aeab9c4\", \"mojo_unittests\": \"9fea80c480a4866bb01506e1dd7e0e0cc7aeed1c\", \"nacl_loader_unittests\": \"e83c3bf1c7b8e6f86dcb0c8821e97953c81d0b93\", \"native_theme_unittests\": \"16b1faaeccb97063442760b8260c93547d7493b2\", \"net_unittests\": \"9566ddcec9930019be3114fb9930f877d4c65c16\", \"notification_helper_unittests\": \"66744fa3ea7110dbe58eb8ac580a10e5e1555c39\", \"pdf_unittests\": \"ee808adc20866a73856d11b0a9e74f24679d100e\", \"perfetto_unittests\": \"ffdd0f8951c78891e1fd2c9b0410e720672ec056\", \"ppapi_unittests\": \"4fc709d407dbcca90fd8bcef89e946bfc4c938bf\", \"printing_unittests\": \"99a784b8b82f5305fc24f913422d236c0bb26f20\", \"remoting_unittests\": \"9d6300879d5ce1e3c7d8776270d1084b0147fd7e\", \"sbox_integration_tests\": \"c7d92a3d14a44aa4082dab5402af5aa3a5d775d8\", \"sbox_unittests\": \"690e1665de4ef7d42d1ddba3bdbfabcff5ba4b53\", \"sbox_validation_tests\": \"ffe12ef36ef1354b20b214d2e1465e98c1a10851\", \"service_manager_unittests\": \"e95f5f4c7a597218027933274e54f48f47cca915\", \"services_unittests\": \"4a50325bd636d14a32312e5a261ee33f4efe658a\", \"setup_unittests\": \"dfc1b466a8540c83476365b1ae13a4ed5b5f9937\", \"shell_dialogs_unittests\": \"eb4dc1543fa2cbcad0ea2823e0e5bccd22695ef2\", \"skia_unittests\": \"d87a180dc9212a1394c7afad72c71fbc5d5c8d5d\", \"snapshot_unittests\": \"dd6adcb743f6ba2cb98f6272b96aea9949a97020\", \"sql_unittests\": \"3af3f451664c9048b8751aac78f7c1c358424a43\", \"storage_unittests\": \"0d63660b7f97d0ded740968855a918c71ddaee09\", \"sync_integration_tests\": \"13e149c13f1b285d32446710f26050c7d4bfc90e\", \"traffic_annotation_auditor_unittests\": \"34f6b1737aea97fb10b732e130f7901be90f7e2d\", \"ui_base_unittests\": \"c4642aa6278fc24da4b8c26d12e03cc41774e784\", \"ui_touch_selection_unittests\": \"35276f305fa582d2bc1c0a03010f53abb0c8d609\", \"unit_tests\": \"5dc4012e90aadec5f5e08cb6b041e526405e7162\", \"updater_tests\": \"4f71a5fafabcd587b92c4aeb2d984413d6bab0aa\", \"url_unittests\": \"52cdd0e36d2708f13a6931db8797e8547b16a858\", \"views_unittests\": \"a10980c590acbddf0d93cfe22d88f8164bff3b43\", \"viz_unittests\": \"32ba3fad1531246ad52d0ba9651bfccd8f02cd0c\", \"vr_common_unittests\": \"6e9b5d109079cb3db07f3f838aba10327f860da5\", \"vr_pixeltests\": \"6e86532faf16386611a1d7cbce53340f8410a7bc\", \"weblayer_browsertests\": \"1625aec6a8e48e74aba66cbe8109026e54c876b0\", \"weblayer_unittests\": \"4371f179ca63cc4cdf5da2c7861d2db959c3416e\", \"wm_unittests\": \"a123f810600498538760e2ca3083bf111c2713b5\", \"wtf_unittests\": \"efab2817cf81c9d58c96b5e3258a681067a21c2e\", \"zlib_unittests\": \"b1847eac2e652f4afa12f8d28cb9b9e2c76d0131\", \"zucchini_unittests\": \"7a694ff83ca09524b4a2298fdc62e8cb95b5ecd8\"}}, \"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-0-6l07\"], \"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\": [\"5700-b36f18b\"], \"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": "1613512538025321",
"status": "COMPLETED",
"status_changed_ts": "1613516472719096",
"tags": [
"build_address:luci.chromium.ci/win-asan/17382",
"builder:win-asan",
"buildset:commit/git/acde0ea6a99b834c022a8dc8cecdb9e8cfc92974",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/acde0ea6a99b834c022a8dc8cecdb9e8cfc92974",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020533261418086256",
"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/8855103199198292000/+/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:51c5a8f0cc14a010",
"user_agent:luci-scheduler"
],
"updated_ts": "1613516472723827",
"url": "https://ci.chromium.org/b/8855103199198292000",
"utcnow_ts": "1613516473211452"
}
}
[23:01:13.223] Invocation finished in 1h5m42.718261849s with status SUCCEEDED