[11:13:31.001] New invocation is queued and will start shortly
[11:13:32.105] Starting the invocation (attempt 1)
[11:13:32.139] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:13:32.139] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:13:32.139] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024741112516008400",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9c3615988d6afb851448a0807859b53916b976b2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9024741112516008400",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/9c3615988d6afb851448a0807859b53916b976b2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9c3615988d6afb851448a0807859b53916b976b2",
"gitiles_ref:refs/heads/master"
]
}
[11:13:32.719] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1609499612246634",
"id": "8859311050547348800",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9c3615988d6afb851448a0807859b53916b976b2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609499612593784",
"tags": [
"build_address:luci.chromium.ci/win-asan/16573",
"builder:win-asan",
"buildset:commit/git/9c3615988d6afb851448a0807859b53916b976b2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9c3615988d6afb851448a0807859b53916b976b2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024741112516008400",
"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/8859311050547348800/+/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": "1609499612593883",
"url": "https://ci.chromium.org/b/8859311050547348800",
"utcnow_ts": "1609499612713851"
}
}
[11:13:32.719] Task URL: https://ci.chromium.org/b/8859311050547348800
[11:13:32.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:2:0) after 1m0s
[11:13:34.427] Received PubSub notification, asking Buildbucket for the build status
[11:13:34.563] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:32.735] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:2:0)
[11:14:32.735] Timer tick, asking Buildbucket for the build status
[11:14:32.893] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:32.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:4:0) after 1m0s
[11:15:32.908] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:4:0)
[11:15:32.908] Timer tick, asking Buildbucket for the build status
[11:15:33.003] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:33.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:5:0) after 1m0s
[11:16:33.020] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:5:0)
[11:16:33.020] Timer tick, asking Buildbucket for the build status
[11:16:33.228] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:33.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:6:0) after 1m0s
[11:17:33.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:6:0)
[11:17:33.261] Timer tick, asking Buildbucket for the build status
[11:17:33.348] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:33.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:7:0) after 1m0s
[11:18:33.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:7:0)
[11:18:33.343] Timer tick, asking Buildbucket for the build status
[11:18:33.478] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:33.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:8:0) after 1m0s
[11:19:33.560] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:8:0)
[11:19:33.560] Timer tick, asking Buildbucket for the build status
[11:19:34.000] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:34.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:9:0) after 1m0s
[11:20:34.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:9:0)
[11:20:34.022] Timer tick, asking Buildbucket for the build status
[11:20:34.319] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:34.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:10:0) after 1m0s
[11:21:34.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:10:0)
[11:21:34.385] Timer tick, asking Buildbucket for the build status
[11:21:34.443] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:34.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:11:0) after 1m0s
[11:22:34.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:11:0)
[11:22:34.469] Timer tick, asking Buildbucket for the build status
[11:22:34.635] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:34.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:12:0) after 1m0s
[11:23:34.659] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:12:0)
[11:23:34.659] Timer tick, asking Buildbucket for the build status
[11:23:34.768] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:34.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:13:0) after 1m0s
[11:24:34.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:13:0)
[11:24:34.836] Timer tick, asking Buildbucket for the build status
[11:24:35.398] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:35.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:14:0) after 1m0s
[11:25:35.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:14:0)
[11:25:35.419] Timer tick, asking Buildbucket for the build status
[11:25:35.571] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:35.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:15:0) after 1m0s
[11:26:35.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:15:0)
[11:26:35.633] Timer tick, asking Buildbucket for the build status
[11:26:35.790] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:35.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:16:0) after 1m0s
[11:27:35.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:16:0)
[11:27:35.762] Timer tick, asking Buildbucket for the build status
[11:27:36.125] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:36.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:17:0) after 1m0s
[11:28:36.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:17:0)
[11:28:36.181] Timer tick, asking Buildbucket for the build status
[11:28:36.509] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:36.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:18:0) after 1m0s
[11:29:36.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:18:0)
[11:29:36.528] Timer tick, asking Buildbucket for the build status
[11:29:37.031] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:37.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:19:0) after 1m0s
[11:30:37.093] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:19:0)
[11:30:37.093] Timer tick, asking Buildbucket for the build status
[11:30:37.325] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:37.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:20:0) after 1m0s
[11:31:37.366] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:20:0)
[11:31:37.366] Timer tick, asking Buildbucket for the build status
[11:31:38.162] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:38.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:21:0) after 1m0s
[11:32:38.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:21:0)
[11:32:38.189] Timer tick, asking Buildbucket for the build status
[11:32:38.409] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:38.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:22:0) after 1m0s
[11:33:38.488] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:22:0)
[11:33:38.488] Timer tick, asking Buildbucket for the build status
[11:33:38.585] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:38.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:23:0) after 1m0s
[11:34:38.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:23:0)
[11:34:38.614] Timer tick, asking Buildbucket for the build status
[11:34:39.072] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:39.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:24:0) after 1m0s
[11:35:39.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:24:0)
[11:35:39.107] Timer tick, asking Buildbucket for the build status
[11:35:39.223] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:39.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:25:0) after 1m0s
[11:36:39.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:25:0)
[11:36:39.243] Timer tick, asking Buildbucket for the build status
[11:36:39.378] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:39.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:26:0) after 1m0s
[11:37:39.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:26:0)
[11:37:39.397] Timer tick, asking Buildbucket for the build status
[11:37:39.500] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:39.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:27:0) after 1m0s
[11:38:39.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:27:0)
[11:38:39.522] Timer tick, asking Buildbucket for the build status
[11:38:40.218] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:40.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:28:0) after 1m0s
[11:39:40.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:28:0)
[11:39:40.244] Timer tick, asking Buildbucket for the build status
[11:39:40.398] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:40.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:29:0) after 1m0s
[11:40:40.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:29:0)
[11:40:40.451] Timer tick, asking Buildbucket for the build status
[11:40:40.713] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:40.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:30:0) after 1m0s
[11:41:40.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:30:0)
[11:41:40.889] Timer tick, asking Buildbucket for the build status
[11:41:41.228] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:41.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:31:0) after 1m0s
[11:42:41.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:31:0)
[11:42:41.312] Timer tick, asking Buildbucket for the build status
[11:42:41.689] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:41.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:32:0) after 1m0s
[11:43:41.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:32:0)
[11:43:41.760] Timer tick, asking Buildbucket for the build status
[11:43:42.080] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:42.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:33:0) after 1m0s
[11:44:42.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:33:0)
[11:44:42.111] Timer tick, asking Buildbucket for the build status
[11:44:42.220] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:42.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:34:0) after 1m0s
[11:45:42.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:34:0)
[11:45:42.385] Timer tick, asking Buildbucket for the build status
[11:45:42.485] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:42.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:35:0) after 1m0s
[11:46:42.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:35:0)
[11:46:42.517] Timer tick, asking Buildbucket for the build status
[11:46:42.579] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:42.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:36:0) after 1m0s
[11:47:42.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:36:0)
[11:47:42.701] Timer tick, asking Buildbucket for the build status
[11:47:42.956] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:42.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:37:0) after 1m0s
[11:48:43.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:37:0)
[11:48:43.098] Timer tick, asking Buildbucket for the build status
[11:48:43.374] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:43.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:38:0) after 1m0s
[11:49:43.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:38:0)
[11:49:43.395] Timer tick, asking Buildbucket for the build status
[11:49:43.472] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:43.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:39:0) after 1m0s
[11:50:43.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:39:0)
[11:50:43.762] Timer tick, asking Buildbucket for the build status
[11:50:44.184] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:44.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:40:0) after 1m0s
[11:51:44.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:40:0)
[11:51:44.722] Timer tick, asking Buildbucket for the build status
[11:51:45.182] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:45.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:41:0) after 1m0s
[11:52:45.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:41:0)
[11:52:45.404] Timer tick, asking Buildbucket for the build status
[11:52:45.510] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:45.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:42:0) after 1m0s
[11:53:45.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:42:0)
[11:53:45.750] Timer tick, asking Buildbucket for the build status
[11:53:45.885] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:45.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:43:0) after 1m0s
[11:54:46.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:43:0)
[11:54:46.016] Timer tick, asking Buildbucket for the build status
[11:54:46.208] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:46.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:44:0) after 1m0s
[11:55:46.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:44:0)
[11:55:46.404] Timer tick, asking Buildbucket for the build status
[11:55:46.524] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:46.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:45:0) after 1m0s
[11:56:46.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:45:0)
[11:56:46.609] Timer tick, asking Buildbucket for the build status
[11:56:47.001] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:47.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:46:0) after 1m0s
[11:57:47.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:46:0)
[11:57:47.008] Timer tick, asking Buildbucket for the build status
[11:57:47.445] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:47.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:47:0) after 1m0s
[11:58:47.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:47:0)
[11:58:47.518] Timer tick, asking Buildbucket for the build status
[11:58:47.792] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:47.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:48:0) after 1m0s
[11:59:47.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:48:0)
[11:59:47.811] Timer tick, asking Buildbucket for the build status
[11:59:47.928] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:47.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:49:0) after 1m0s
[12:00:47.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:49:0)
[12:00:47.948] Timer tick, asking Buildbucket for the build status
[12:00:48.019] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:48.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:50:0) after 1m0s
[12:01:48.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:50:0)
[12:01:48.039] Timer tick, asking Buildbucket for the build status
[12:01:48.123] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:48.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:51:0) after 1m0s
[12:02:48.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:51:0)
[12:02:48.145] Timer tick, asking Buildbucket for the build status
[12:02:48.303] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:48.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:52:0) after 1m0s
[12:03:48.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:52:0)
[12:03:48.343] Timer tick, asking Buildbucket for the build status
[12:03:48.466] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:48.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:53:0) after 1m0s
[12:04:48.488] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:53:0)
[12:04:48.488] Timer tick, asking Buildbucket for the build status
[12:04:48.602] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:48.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:54:0) after 1m0s
[12:05:48.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:54:0)
[12:05:48.621] Timer tick, asking Buildbucket for the build status
[12:05:48.722] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:48.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:55:0) after 1m0s
[12:06:48.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:55:0)
[12:06:48.747] Timer tick, asking Buildbucket for the build status
[12:06:49.106] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:49.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:56:0) after 1m0s
[12:07:49.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:56:0)
[12:07:49.129] Timer tick, asking Buildbucket for the build status
[12:07:49.238] Build 8859311050547348800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:49.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024741112516008400:57:0) after 1m0s
[12:08:28.532] Received PubSub notification, asking Buildbucket for the build status
[12:08:28.591] Build 8859311050547348800: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:08:28.591] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1609502907363518",
"created_by": "project:chromium",
"created_ts": "1609499612246634",
"id": "8859311050547348800",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9c3615988d6afb851448a0807859b53916b976b2\"}}",
"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\": \"9c3615988d6afb851448a0807859b53916b976b2\", \"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-1-9pgs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16573, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"2cbcb18e95e6820e1266fa17f421efe86f1bcc52\", \"got_dawn_revision\": \"86980018b3f2786aeb5ded1220ec94bc927fa6b2\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"9c3615988d6afb851448a0807859b53916b976b2\", \"got_revision_cp\": \"refs/heads/master@{#839893}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"ff29e249d3179766a40c9486183559fe3e5c1456\", \"got_v8_revision\": \"a3f2933bb6d06bd7473ccbed77714874e6bf63b3\", \"got_v8_revision_cp\": \"refs/heads/8.9.201@{#1}\", \"got_webrtc_revision\": \"6215ba804eb500f3e28b39088c73af3c4f4cd10a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32897}\", \"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\": \"9c3615988d6afb851448a0807859b53916b976b2\", \"swarm_hashes_refs/heads/master(at){#839893}_without_patch\": {\"absl_hardening_tests\": \"4b5c3ca1221575578a87a70377477b7181dac560\", \"accessibility_unittests\": \"55b8eef447e4e1e1bfa97cdc6273104a66ea669e\", \"angle_unittests\": \"1650a0574320d3c94e4893279072710ff6c4406e\", \"app_shell_unittests\": \"05a1968f704d76b4797c70513313e09c70980a79\", \"aura_unittests\": \"8964da53aea069de4823aa6f83879d0a2a724b80\", \"base_unittests\": \"f389b31b4fa77b28bd2557986be1757977c1f6de\", \"base_util_unittests\": \"30cd4e78714aae2b8d42d7f2b477ad3f4bc4923b\", \"blink_common_unittests\": \"cbb43f41ff3517f9a02e03c82eacbb2a23b94b31\", \"blink_fuzzer_unittests\": \"2c57dd5de8ac36f7da9396a74c98f07d5fe31df6\", \"blink_heap_unittests\": \"4e9e0579ebf1e7fca3390fbb85558959ae22e9c8\", \"blink_platform_unittests\": \"5e3734c9307e515c0cd0ce9b7935a7ad629d1b95\", \"blink_unittests\": \"9f766fdf1b3733e5f7381356b876477febb7c3ba\", \"boringssl_crypto_tests\": \"e8272ba7e9b69b4bf0a35039ee8b72950ca04c0e\", \"boringssl_ssl_tests\": \"bc8e5da089e5c401dd7e977ec3165a3abc98e124\", \"browser_switcher_bho_unittests\": \"3b0b8468d116f1ccb582cb9131e89d6fdb3b444c\", \"browser_tests\": \"d3292e9a922d6441e1dd02a0f89a06248eb48bff\", \"capture_unittests\": \"bd4145d085f8ff09ba6b52b3ad04dd8685448b94\", \"cast_unittests\": \"3ecfa906ad64c58d516d3689ff21f0d3c3a0f680\", \"cc_unittests\": \"ddc594af52985e17d39c13082c0dbe8b2e14d10e\", \"chrome_app_unittests\": \"17d37f104fe29fdb1e2d7a3bfb15a9e601a51d1b\", \"chrome_cleaner_unittests\": \"835107da6c2c935039925b54c294af64ffa196c7\", \"chrome_elf_unittests\": \"22558e1a48e118f78b7564392f7a497738ea5ff6\", \"chromedriver_unittests\": \"98dc5a8a013661e32c668fd3ac5db32f335544df\", \"components_browsertests\": \"18e2f6865f263a764887588aee0daab22dbc28a5\", \"components_unittests\": \"e1e0fc219e248025ef5c6a01225742021ddfdaec\", \"compositor_unittests\": \"a1f8e82ca3829f62f145ea694f799c639589a19e\", \"content_browsertests\": \"08eb9536f2e9c0b6fcfb159225df16665e546116\", \"content_unittests\": \"2b3edb97b72566e5d8aaa3e362f23a9c06562c2d\", \"courgette_unittests\": \"8291c07967c4855e7d366ef0c4dab1329dc2c358\", \"crashpad_tests\": \"2fc96d89198874c1095261057fb2b07a456bdaf1\", \"cronet_tests\": \"869385412ebe58403fe419507b1dbb8833fbf267\", \"cronet_unittests\": \"a25cb3656bc859c9e57091ab86ca7ecbbd042d01\", \"crypto_unittests\": \"5f95f1c4e3d78558a42991b0bafdcab70c1d0a8e\", \"delayloads_unittests\": \"59ac66f3a2d030c54e7eeac717083e250b83a331\", \"device_unittests\": \"9477cdb471490aeae3bca385c96ed7d2885947d9\", \"display_unittests\": \"068bf8b4f6231220f17e2a05551b99f830e4e084\", \"elevation_service_unittests\": \"0fbcfa4ad25961d7a92d2720aab4fc5c5614254b\", \"events_unittests\": \"dcb10c704801724ba7412d3738d349b78a61d8bc\", \"extensions_browsertests\": \"eec8453e46485a8933a3372e4f126a8a97da1030\", \"extensions_unittests\": \"a95f0ec77189e62d1b8daa083ac34f07b7b37e98\", \"filesystem_service_unittests\": \"526e7640405984d57e8e98483603a160f179f2fc\", \"gcm_unit_tests\": \"3c28677560db55a6200a0c9456487a9188d79727\", \"gcp_unittests\": \"c834b8319ff1a5ecbe6c84e0ca1bbf38be67594d\", \"gfx_unittests\": \"55d72e000cf6b2064f7044b430804d20c8e1d809\", \"gin_unittests\": \"12e85f845677340c24dde2a5d325c2ca0a37845d\", \"google_apis_unittests\": \"d40c88cf574cfd98a21ee6e604828d46469f7659\", \"gpu_unittests\": \"6c2747bc2bab9a0ba0637a88dbfb39a3dd4bcf7b\", \"gwp_asan_unittests\": \"70494ee86946b11b5428dcae620c3ff511dc5cf7\", \"headless_browsertests\": \"83718bdf598c4cfcefe0aa31f2e9c63383df8693\", \"headless_unittests\": \"297392d277208a3c5a6de805b2b087c2d4ddc67d\", \"install_static_unittests\": \"f00a5d5403820fe29fae32b1f6e985bbdff4a10f\", \"installer_util_unittests\": \"3577cae5cdfc031278c9adb274e1202cfe2e1222\", \"interactive_ui_tests\": \"aa15549e9ffaf49206f5cc21c4a72dd20da54a57\", \"ipc_tests\": \"d3d9b577d2992349455aa2ca0ef760b0116b7285\", \"jingle_unittests\": \"70e70db8a79fafe1e22ddc26720697065b450e16\", \"latency_unittests\": \"5bee15533fe6ed948b4a43dc3802cee1a47be377\", \"libjingle_xmpp_unittests\": \"764102ab86a5511a0ed7af932cd70633c9f1298d\", \"liburlpattern_unittests\": \"05e42b7bd00e8b3aedd47087663fcb4ddc35ad3b\", \"media_blink_unittests\": \"0b2b2c55c0cda97a9f6856e53a9515a2a6a04c8b\", \"media_unittests\": \"87eb5db9a37ed5e9ac1a5d300355da086da85d8a\", \"message_center_unittests\": \"8f0eafc795b8ed77083e6cc726f1ade3562594cb\", \"midi_unittests\": \"f8f3d17fe9d48366f503ffeee50063beb90eec9d\", \"mojo_core_unittests\": \"e1ac989b5f5bcc278b16a4c87c8292504311ceab\", \"mojo_unittests\": \"11853d1e40d79380396c0b1d7cd18ccd9f018cc5\", \"nacl_loader_unittests\": \"ac09002fecbdda0ca5001f6c0bb2c108a6d122b0\", \"native_theme_unittests\": \"6655c92506564afff0e8c4a867e1351dd17af7cf\", \"net_unittests\": \"6175b2f79e34204658c42c9df23254fede5ba4cb\", \"notification_helper_unittests\": \"4a96bd268c88a97be48f6741768baa1e9f610ef7\", \"pdf_unittests\": \"e037c7369a92ac1e0698ef4ec24590b568d45549\", \"perfetto_unittests\": \"b903917a353625c02407765030c826cffb2a72e2\", \"ppapi_unittests\": \"4f34d246c7acfad2b9fde28567f593a986bfa2de\", \"printing_unittests\": \"aa57c1057327b708909b11f14c3d79aaa7c76493\", \"remoting_unittests\": \"b66d2544b670440994e6fa457b3cc0bab9995b7a\", \"sbox_integration_tests\": \"27bc4514b56c6c16af16296ac5daa1ca08d8f63a\", \"sbox_unittests\": \"90b9fe4846a75eee70608081ef25bfba13c29416\", \"sbox_validation_tests\": \"3cc333cb24d22c229df887cb736e6aed6a23fbef\", \"service_manager_unittests\": \"e1a6e564c58e1edbc2ced5f4a6601bf1a884b002\", \"services_unittests\": \"97dec1b6392c602b65f8014446492c7b595c0ed7\", \"setup_unittests\": \"d45dd19f9097b4f0324b17f7bbde01ac3384bc86\", \"shell_dialogs_unittests\": \"26cc47c32b6c7cee72139ec37104cdde02d85509\", \"skia_unittests\": \"25dbccb70ddf779068494596f7900ae216caa6fa\", \"snapshot_unittests\": \"3928b33c1c4f9136b9cce19fbcf368eab4066bf8\", \"sql_unittests\": \"e58c761f7c2e18a41887a089151f58e640a70ea0\", \"storage_unittests\": \"125904228057224141770d6ff63901c5b0469373\", \"sync_integration_tests\": \"d063c41acce5d7364be3c56930e9bf2a0595cb82\", \"traffic_annotation_auditor_unittests\": \"d344eeaf1af69394405d10245ee47a34134916a8\", \"ui_base_unittests\": \"8f82da303b702df676276f8abf732ba517a0ca13\", \"ui_touch_selection_unittests\": \"e8e7526a2c0b0a0c10668383987803c044ffd64f\", \"unit_tests\": \"8190491a584cc3a1f6e3d41af6f0469e7bc38ce1\", \"updater_tests\": \"93022622f671c7c81288c9110b908f2f7acd1722\", \"url_unittests\": \"0b04704ac98c2bb2f9b738054da078b7501bcbdb\", \"views_unittests\": \"80e9927a82a91df30c4421fc75eff977b3897a08\", \"viz_unittests\": \"e8f5012b9b1efc6b962f9c671d033163bd239970\", \"vr_common_unittests\": \"63ab85b25f202ab4ccbfd6056849e93eee19b041\", \"vr_pixeltests\": \"9199d746c5e8c76631967d7de57efe9b4ae49523\", \"weblayer_browsertests\": \"4cc58a98050935af89e2e4ddafcdfe3f2142774c\", \"weblayer_unittests\": \"ba3629cb5c1f527a5aa40440635dcbfcd4fc443a\", \"wm_unittests\": \"897b5b52b2d6b4104f465e909a45d23cbb36e452\", \"wtf_unittests\": \"700a9e4a80d9a027b0e4054ca84fb9151d0411ea\", \"zlib_unittests\": \"dbd6ebb7321f8e3b9fb5bed64bd1af9b79c91268\", \"zucchini_unittests\": \"8c5e18b623a78e7916c544bde56c95541d04fc2b\"}}, \"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-1-9pgs\"], \"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-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1609499613578797",
"status": "COMPLETED",
"status_changed_ts": "1609502907770917",
"tags": [
"build_address:luci.chromium.ci/win-asan/16573",
"builder:win-asan",
"buildset:commit/git/9c3615988d6afb851448a0807859b53916b976b2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9c3615988d6afb851448a0807859b53916b976b2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024741112516008400",
"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/8859311050547348800/+/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:50d6789e6448e510",
"user_agent:luci-scheduler"
],
"updated_ts": "1609502907788122",
"url": "https://ci.chromium.org/b/8859311050547348800",
"utcnow_ts": "1609502908583304"
}
}
[12:08:28.591] Invocation finished in 54m57.606506613s with status SUCCEEDED