[15:19:36.581] New invocation is queued and will start shortly
[15:19:37.676] Starting the invocation (attempt 1)
[15:19:37.727] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:19:37.727] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:19:37.727] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025993987213942160",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"47cf2e55df353230cd866d8971b4f9526513bd61\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9025993987213942160",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/47cf2e55df353230cd866d8971b4f9526513bd61",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/47cf2e55df353230cd866d8971b4f9526513bd61",
"gitiles_ref:refs/heads/master"
]
}
[15:19:38.515] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608304777852811",
"id": "8860563925217151712",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"47cf2e55df353230cd866d8971b4f9526513bd61\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608304778277643",
"tags": [
"build_address:luci.chromium.ci/win-asan/16303",
"builder:win-asan",
"buildset:commit/git/47cf2e55df353230cd866d8971b4f9526513bd61",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/47cf2e55df353230cd866d8971b4f9526513bd61",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025993987213942160",
"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/8860563925217151712/+/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": "1608304778277839",
"url": "https://ci.chromium.org/b/8860563925217151712",
"utcnow_ts": "1608304778506203"
}
}
[15:19:38.515] Task URL: https://ci.chromium.org/b/8860563925217151712
[15:19:38.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:2:0) after 1m0s
[15:19:41.180] Received PubSub notification, asking Buildbucket for the build status
[15:19:41.299] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:38.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:2:0)
[15:20:38.516] Timer tick, asking Buildbucket for the build status
[15:20:38.600] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:38.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:4:0) after 1m0s
[15:21:38.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:4:0)
[15:21:38.618] Timer tick, asking Buildbucket for the build status
[15:21:38.918] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:38.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:5:0) after 1m0s
[15:22:39.013] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:5:0)
[15:22:39.013] Timer tick, asking Buildbucket for the build status
[15:22:39.213] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:39.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:6:0) after 1m0s
[15:23:39.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:6:0)
[15:23:39.285] Timer tick, asking Buildbucket for the build status
[15:23:39.431] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:39.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:7:0) after 1m0s
[15:24:39.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:7:0)
[15:24:39.561] Timer tick, asking Buildbucket for the build status
[15:24:39.685] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:39.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:8:0) after 1m0s
[15:25:39.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:8:0)
[15:25:39.703] Timer tick, asking Buildbucket for the build status
[15:25:39.911] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:39.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:9:0) after 1m0s
[15:26:40.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:9:0)
[15:26:40.088] Timer tick, asking Buildbucket for the build status
[15:26:40.162] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:40.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:10:0) after 1m0s
[15:27:40.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:10:0)
[15:27:40.383] Timer tick, asking Buildbucket for the build status
[15:27:40.455] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:40.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:11:0) after 1m0s
[15:28:41.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:11:0)
[15:28:41.607] Timer tick, asking Buildbucket for the build status
[15:28:41.669] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:41.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:12:0) after 1m0s
[15:29:41.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:12:0)
[15:29:41.889] Timer tick, asking Buildbucket for the build status
[15:29:42.098] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:42.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:13:0) after 1m0s
[15:30:42.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:13:0)
[15:30:42.448] Timer tick, asking Buildbucket for the build status
[15:30:42.918] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:42.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:14:0) after 1m0s
[15:31:43.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:14:0)
[15:31:43.212] Timer tick, asking Buildbucket for the build status
[15:31:43.343] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:43.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:15:0) after 1m0s
[15:32:43.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:15:0)
[15:32:43.578] Timer tick, asking Buildbucket for the build status
[15:32:43.694] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:43.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:16:0) after 1m0s
[15:33:43.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:16:0)
[15:33:43.785] Timer tick, asking Buildbucket for the build status
[15:33:44.115] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:44.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:17:0) after 1m0s
[15:34:44.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:17:0)
[15:34:44.171] Timer tick, asking Buildbucket for the build status
[15:34:44.284] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:44.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:18:0) after 1m0s
[15:35:44.464] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:18:0)
[15:35:44.464] Timer tick, asking Buildbucket for the build status
[15:35:44.609] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:44.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:19:0) after 1m0s
[15:36:44.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:19:0)
[15:36:44.627] Timer tick, asking Buildbucket for the build status
[15:36:45.246] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:45.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:20:0) after 1m0s
[15:37:45.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:20:0)
[15:37:45.304] Timer tick, asking Buildbucket for the build status
[15:37:45.455] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:45.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:21:0) after 1m0s
[15:38:45.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:21:0)
[15:38:45.528] Timer tick, asking Buildbucket for the build status
[15:38:46.068] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:46.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:22:0) after 1m0s
[15:39:46.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:22:0)
[15:39:46.209] Timer tick, asking Buildbucket for the build status
[15:39:46.637] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:46.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:23:0) after 1m0s
[15:40:47.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:23:0)
[15:40:47.257] Timer tick, asking Buildbucket for the build status
[15:40:47.394] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:47.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:24:0) after 1m0s
[15:41:47.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:24:0)
[15:41:47.588] Timer tick, asking Buildbucket for the build status
[15:41:47.773] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:47.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:25:0) after 1m0s
[15:42:47.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:25:0)
[15:42:47.999] Timer tick, asking Buildbucket for the build status
[15:42:48.119] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:48.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:26:0) after 1m0s
[15:43:48.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:26:0)
[15:43:48.203] Timer tick, asking Buildbucket for the build status
[15:43:48.443] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:48.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:27:0) after 1m0s
[15:44:48.464] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:27:0)
[15:44:48.464] Timer tick, asking Buildbucket for the build status
[15:44:48.578] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:48.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:28:0) after 1m0s
[15:45:48.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:28:0)
[15:45:48.647] Timer tick, asking Buildbucket for the build status
[15:45:48.799] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:48.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:29:0) after 1m0s
[15:46:48.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:29:0)
[15:46:48.896] Timer tick, asking Buildbucket for the build status
[15:46:49.223] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:49.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:30:0) after 1m0s
[15:47:49.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:30:0)
[15:47:49.258] Timer tick, asking Buildbucket for the build status
[15:47:49.505] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:49.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:31:0) after 1m0s
[15:48:53.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:31:0)
[15:48:53.398] Timer tick, asking Buildbucket for the build status
[15:48:53.870] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:53.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:32:0) after 1m0s
[15:49:53.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:32:0)
[15:49:53.973] Timer tick, asking Buildbucket for the build status
[15:49:54.079] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:54.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:33:0) after 1m0s
[15:50:54.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:33:0)
[15:50:54.097] Timer tick, asking Buildbucket for the build status
[15:50:54.226] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:54.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:34:0) after 1m0s
[15:51:54.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:34:0)
[15:51:54.244] Timer tick, asking Buildbucket for the build status
[15:51:54.321] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:54.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:35:0) after 1m0s
[15:52:54.350] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:35:0)
[15:52:54.350] Timer tick, asking Buildbucket for the build status
[15:52:54.719] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:54.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:36:0) after 1m0s
[15:53:54.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:36:0)
[15:53:54.758] Timer tick, asking Buildbucket for the build status
[15:53:55.171] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:55.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:37:0) after 1m0s
[15:54:55.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:37:0)
[15:54:55.192] Timer tick, asking Buildbucket for the build status
[15:54:55.379] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:55.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:38:0) after 1m0s
[15:55:55.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:38:0)
[15:55:55.434] Timer tick, asking Buildbucket for the build status
[15:55:55.546] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:55.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:39:0) after 1m0s
[15:56:55.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:39:0)
[15:56:55.529] Timer tick, asking Buildbucket for the build status
[15:56:55.685] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:55.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:40:0) after 1m0s
[15:57:55.744] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:40:0)
[15:57:55.744] Timer tick, asking Buildbucket for the build status
[15:57:56.043] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:56.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:41:0) after 1m0s
[15:58:56.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:41:0)
[15:58:56.027] Timer tick, asking Buildbucket for the build status
[15:58:56.267] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:56.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:42:0) after 1m0s
[15:59:56.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:42:0)
[15:59:56.291] Timer tick, asking Buildbucket for the build status
[15:59:56.457] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:56.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:43:0) after 1m0s
[16:00:56.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:43:0)
[16:00:56.521] Timer tick, asking Buildbucket for the build status
[16:00:56.717] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:56.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:44:0) after 1m0s
[16:01:56.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:44:0)
[16:01:56.770] Timer tick, asking Buildbucket for the build status
[16:01:57.404] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:57.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:45:0) after 1m0s
[16:02:57.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:45:0)
[16:02:57.477] Timer tick, asking Buildbucket for the build status
[16:02:57.601] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:57.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:46:0) after 1m0s
[16:03:57.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:46:0)
[16:03:57.622] Timer tick, asking Buildbucket for the build status
[16:03:57.752] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:57.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:47:0) after 1m0s
[16:04:57.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:47:0)
[16:04:57.838] Timer tick, asking Buildbucket for the build status
[16:04:57.965] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:57.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:48:0) after 1m0s
[16:05:58.155] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:48:0)
[16:05:58.155] Timer tick, asking Buildbucket for the build status
[16:05:58.218] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:58.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:49:0) after 1m0s
[16:06:58.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:49:0)
[16:06:58.271] Timer tick, asking Buildbucket for the build status
[16:06:58.350] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:58.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:50:0) after 1m0s
[16:07:58.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:50:0)
[16:07:58.390] Timer tick, asking Buildbucket for the build status
[16:07:58.474] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:58.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:51:0) after 1m0s
[16:08:58.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:51:0)
[16:08:58.598] Timer tick, asking Buildbucket for the build status
[16:08:58.670] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:58.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:52:0) after 1m0s
[16:09:58.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:52:0)
[16:09:58.691] Timer tick, asking Buildbucket for the build status
[16:09:59.163] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:59.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:53:0) after 1m0s
[16:10:59.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:53:0)
[16:10:59.193] Timer tick, asking Buildbucket for the build status
[16:10:59.290] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:59.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:54:0) after 1m0s
[16:11:59.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:54:0)
[16:11:59.345] Timer tick, asking Buildbucket for the build status
[16:11:59.660] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:59.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:55:0) after 1m0s
[16:12:59.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:55:0)
[16:12:59.703] Timer tick, asking Buildbucket for the build status
[16:12:59.809] Build 8860563925217151712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:59.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025993987213942160:56:0) after 1m0s
[16:13:54.580] Received PubSub notification, asking Buildbucket for the build status
[16:13:54.642] Build 8860563925217151712: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:13:54.642] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608308032771262",
"created_by": "project:chromium",
"created_ts": "1608304777852811",
"id": "8860563925217151712",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"47cf2e55df353230cd866d8971b4f9526513bd61\"}}",
"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\": \"47cf2e55df353230cd866d8971b4f9526513bd61\", \"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-5-miap\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16303, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"c47b951ecbcbbba653db801bc7eb5f4fa5d72042\", \"got_dawn_revision\": \"64c5d601f89b7733091222796ff3631668fb2c25\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"47cf2e55df353230cd866d8971b4f9526513bd61\", \"got_revision_cp\": \"refs/heads/master@{#838456}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"f841b934b402f97c1b88f08c8bcb6c0dac13f0d3\", \"got_v8_revision_cp\": \"refs/heads/8.9.175@{#1}\", \"got_webrtc_revision\": \"d7f750f7be4b2f61018d3cb55a995d09daa226e6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32862}\", \"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\": \"47cf2e55df353230cd866d8971b4f9526513bd61\", \"swarm_hashes_refs/heads/master(at){#838456}_without_patch\": {\"absl_hardening_tests\": \"854ae10a0a73d897d3c2837f3eddbe08f41ff582\", \"accessibility_unittests\": \"259eaa593b904eed91572cfab62a502b727f469c\", \"angle_unittests\": \"06da09767846f92d7f0180281d5dbf80d5ee0528\", \"app_shell_unittests\": \"406d0a1ab8865533ca2b416ba006a5937a3638c5\", \"aura_unittests\": \"2d104b2a6c80bd6ff32e41f67bbd4f81f6b39b4c\", \"base_unittests\": \"ec3434c27ca793aae7fc4b315e6b345b783ed66a\", \"base_util_unittests\": \"101c6e5050da3b144164486f2739e9c52c2c9204\", \"blink_common_unittests\": \"19e19dc1265c2a7e035973298f30db529680e601\", \"blink_fuzzer_unittests\": \"7415ccf3e0f1979afdb97c0de333615cb7fc7e1d\", \"blink_heap_unittests\": \"1ad9a137e58e91ee8ac57ecd41ab3e148034ac4e\", \"blink_platform_unittests\": \"846932f02fbccae60fca4b0a998f5a84b88364bd\", \"blink_unittests\": \"4edeaad32293630e0fb27dadc153cfa92a3eb9e6\", \"boringssl_crypto_tests\": \"372e6cde463d62c392736f0ba5d5abfcd3c80d88\", \"boringssl_ssl_tests\": \"33c1075fb46c39a1956d347baa28669f25140c19\", \"browser_switcher_bho_unittests\": \"f99c21ce9eee4c5454bb25c7765689aef14cb69e\", \"browser_tests\": \"776306a4366f263bc03b9563e23d3159e0f45e9b\", \"capture_unittests\": \"bba80898eafa33335b421c185ec21f71948628eb\", \"cast_unittests\": \"c191e60d9a0db51ea10dbaf5c1d69c01b10c8e18\", \"cc_unittests\": \"026d5611e9afe1b8c20c36404b36db2762ae1a6e\", \"chrome_app_unittests\": \"936eddcc8f0743342005487b3b6421b5df160f9d\", \"chrome_cleaner_unittests\": \"48b6390701b0840c28daf7369f9044b727279551\", \"chrome_elf_unittests\": \"254848aff413c0ab12f9b33046dc606331f9535a\", \"chromedriver_unittests\": \"ebd51e3678d5de04145a46770f0e5f6718b1734f\", \"components_browsertests\": \"f1b707698f245c98910d2e6d7c7fdbdf8124ddbf\", \"components_unittests\": \"0b6b9850ba80014cb03c9634bc0f2bc9d54b4fc7\", \"compositor_unittests\": \"869f60632a82da70e3985d50ff333a21ae95edb5\", \"content_browsertests\": \"5bc37b73dfbc7e99c02253ffeafb8132d5830470\", \"content_unittests\": \"fcebd2074518da01a114087ac5f482985e70ad29\", \"courgette_unittests\": \"54173dd9ac2b518820737edecf3c331024c55e0c\", \"crashpad_tests\": \"1567afdcad6824e421bd3c5adb319e9b23bec6bb\", \"cronet_tests\": \"49a1d4561abb440183bb6042a42561207c4bb8cc\", \"cronet_unittests\": \"ca62930e34c1aef0e889bd1b975a08f17dc48638\", \"crypto_unittests\": \"55ed4ad48334db96be96e25102a4f6756c795c41\", \"delayloads_unittests\": \"0b50f7930ed895971fd714893eec87973985c5ae\", \"device_unittests\": \"949a007246d824fab46d342f16917711410ea4d5\", \"display_unittests\": \"1b888c2646415a0e71a0df36d6de262b22c4289d\", \"elevation_service_unittests\": \"dbd7110341bde7252343c6e13b7c247d34f5e2f0\", \"events_unittests\": \"b1a9a93661212df8895e146e0ff01206d1d0e98e\", \"extensions_browsertests\": \"916d3401e8c55ec37cfb5cae7497ee793bbb4f6c\", \"extensions_unittests\": \"82dce17326e210ab514f892d39725493170c0259\", \"filesystem_service_unittests\": \"43ee6a3f8699d36ed0378420881958d94e2b845b\", \"gcm_unit_tests\": \"f3f23dc49b09865abf756c72534dcab829316100\", \"gcp_unittests\": \"8a20d9a25d6760709d2727432d973db36eb481a2\", \"gfx_unittests\": \"515bb49a85e532a314e71a7ae0119bf46ca820c3\", \"gin_unittests\": \"da0703fa3dc5ad2d84aec0c820cbcccab7974f74\", \"google_apis_unittests\": \"6047c61b89e49d4545f5aa4516314cf07e90ec61\", \"gpu_unittests\": \"8c533fbc70540c631cb147824c47681098ee1276\", \"gwp_asan_unittests\": \"e7885c46373f66908885f494084d3f5c0e545883\", \"headless_browsertests\": \"e4790cfac48fc11fb7ad164dc7273776cf4f0393\", \"headless_unittests\": \"86fa953ab8f7ccadd1c528ee062951b6e6846821\", \"install_static_unittests\": \"9582d3cd591ad1d55bb248ec041700679d9e7cd3\", \"installer_util_unittests\": \"e649f8fa91a1c2dd00853c34bcf8bc83cda26544\", \"interactive_ui_tests\": \"7be9fa54c89d63ba4b4c832cdfabbdb865f6df58\", \"ipc_tests\": \"7b0cd9d808bf3c55e80c74a2d991c6a556926ddb\", \"jingle_unittests\": \"1508e564446f545f220ec0da3d8911c06838629d\", \"latency_unittests\": \"9f6fed2c0020be8f04a630b44c9381875b361184\", \"libjingle_xmpp_unittests\": \"05ac773e9b46a169ad85942b7983114f58e034d3\", \"liburlpattern_unittests\": \"f52e3080a1824a3db529011129b206de9b3104bb\", \"media_blink_unittests\": \"3b2bd438a336cb1680ff52286706f38e9e4e224f\", \"media_unittests\": \"208fabe5fdc20ab450e11efc3ca2ea15523d8cc1\", \"message_center_unittests\": \"a122cae18515da0c97bab68ed0a269a497db3449\", \"midi_unittests\": \"000eaa48403572258ecb4801db5fc59122e0771b\", \"mojo_core_unittests\": \"349e41d4189feb5a515bcaa042b209349a1e65e0\", \"mojo_unittests\": \"132a1b0b9b0f25aa296eaa8cb31403402a0edbe4\", \"nacl_loader_unittests\": \"ce432eb83d9885397249f6b4a40640364e29c8c5\", \"native_theme_unittests\": \"e1108d793947a278eca2ac97189cb6f3c5904c70\", \"net_unittests\": \"7af7dd0487c9670726035c8050f2570bb621d7be\", \"notification_helper_unittests\": \"38f69fbb600803a34808704de044a152a75e2d99\", \"pdf_unittests\": \"21e7e42a1bcf53df8c9236327b98d7792bbfefbe\", \"perfetto_unittests\": \"03edb9d0c5ed34a4116a4250ff4972d6346862bf\", \"ppapi_unittests\": \"cf860050dabc8587a1da40d726ab6e2b5ca2d7c9\", \"printing_unittests\": \"fc029b3f766fb72b67a119b2d0bb12102561ab35\", \"remoting_unittests\": \"73cc775756a0db9809310b8adc74624753aff2ad\", \"sbox_integration_tests\": \"5fb7fb962356bb582550be67ca0cf0faf179e299\", \"sbox_unittests\": \"473d26a44c2ac2b5ad74ec604574ac2a8326cbe1\", \"sbox_validation_tests\": \"567b1ec7a2244dec92047d7112705143c4e41503\", \"service_manager_unittests\": \"a80231555ace8bc33b03d8cfd79c6ae43a3c1996\", \"services_unittests\": \"4c694608301b67d53f31a1ddd4058cd266592157\", \"setup_unittests\": \"b71ec4d744919af8f3477f7e298aa1aa10c56a46\", \"shell_dialogs_unittests\": \"090c6af3ee2674ae0fd181ea077ab57ab9ae150e\", \"skia_unittests\": \"695b63921aabc1c28669d9c98fedd4e4c814b90d\", \"snapshot_unittests\": \"024cd4adeef07bc00f2faae67fdf6a26806c3e38\", \"sql_unittests\": \"2591be94a978a420159fdc01a51f518da88d0a66\", \"storage_unittests\": \"a706ea63242a611c3d025d0ca804e6651e4b5502\", \"sync_integration_tests\": \"17d4e3065d323feb57ceac4de39cf3c14b20aaca\", \"traffic_annotation_auditor_unittests\": \"080d0602248272bd37d258f17850eacba7902fa9\", \"ui_base_unittests\": \"14a77faea69160ed3473533a1fd8d547e1981c98\", \"ui_touch_selection_unittests\": \"a67d4731429a68fd3c6d9f937aa867727f80dbd0\", \"unit_tests\": \"d9472cf9443636c3aaa4843c39906cce4c3fa189\", \"updater_tests\": \"55aa138160867b55856f51acaa7514605fd0794c\", \"url_unittests\": \"fa2b0775d83e32365d3877efd42623f688bab357\", \"views_unittests\": \"cddda58e0b36e00c100084ec5243dae7928ec3e5\", \"viz_unittests\": \"35dd6b9ed58bddd1d44ea45da16def5040fd99e8\", \"vr_common_unittests\": \"269aadf006b9672c29d7ccb78598a09e863be68b\", \"vr_pixeltests\": \"a18fc7cc8a810ac83c83ff50fb1820414c37e7cb\", \"weblayer_browsertests\": \"062956fc6f7d64d2b8395ac23eba5d0cb697ba0e\", \"weblayer_unittests\": \"979e4b8579b108143b964fa6e53af678196821fc\", \"wm_unittests\": \"a1d71f48b47d033757e3cd1f2923e341d3108d53\", \"wtf_unittests\": \"9080b34b230b72b0248662638a631e0a1c6f05ea\", \"zlib_unittests\": \"c86f6c133db126bb6616278cf82c8647d9222861\", \"zucchini_unittests\": \"161974ffbacb3eafbf4d17c07db5dd49b035c955\"}}, \"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-5-miap\"], \"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": "1608304780615891",
"status": "COMPLETED",
"status_changed_ts": "1608308033294600",
"tags": [
"build_address:luci.chromium.ci/win-asan/16303",
"builder:win-asan",
"buildset:commit/git/47cf2e55df353230cd866d8971b4f9526513bd61",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/47cf2e55df353230cd866d8971b4f9526513bd61",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025993987213942160",
"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/8860563925217151712/+/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:508f40e53f75c110",
"user_agent:luci-scheduler"
],
"updated_ts": "1608308033321053",
"url": "https://ci.chromium.org/b/8860563925217151712",
"utcnow_ts": "1608308034632123"
}
}
[16:13:54.642] Invocation finished in 54m18.0781686s with status SUCCEEDED