[04:01:48.483] New invocation is queued and will start shortly
[04:01:49.705] Starting the invocation (attempt 1)
[04:01:49.743] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:01:49.743] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:01:49.743] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023681109689329904",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a481f3f69044de5c19725b8a6066f9536a413813\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023681109689329904",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/a481f3f69044de5c19725b8a6066f9536a413813",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a481f3f69044de5c19725b8a6066f9536a413813",
"gitiles_ref:refs/heads/master"
]
}
[04:01:50.492] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610510509843140",
"id": "8858251047588721472",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a481f3f69044de5c19725b8a6066f9536a413813\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610510510286760",
"tags": [
"build_address:luci.chromium.ci/win-asan/16789",
"builder:win-asan",
"buildset:commit/git/a481f3f69044de5c19725b8a6066f9536a413813",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a481f3f69044de5c19725b8a6066f9536a413813",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023681109689329904",
"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/8858251047588721472/+/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": "1610510510286899",
"url": "https://ci.chromium.org/b/8858251047588721472",
"utcnow_ts": "1610510510478856"
}
}
[04:01:50.492] Task URL: https://ci.chromium.org/b/8858251047588721472
[04:01:50.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:2:0) after 1m0s
[04:01:54.304] Received PubSub notification, asking Buildbucket for the build status
[04:01:54.388] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:50.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:2:0)
[04:02:50.536] Timer tick, asking Buildbucket for the build status
[04:02:50.656] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:50.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:4:0) after 1m0s
[04:03:50.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:4:0)
[04:03:50.678] Timer tick, asking Buildbucket for the build status
[04:03:51.181] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:51.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:5:0) after 1m0s
[04:04:51.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:5:0)
[04:04:51.301] Timer tick, asking Buildbucket for the build status
[04:04:51.461] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:51.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:6:0) after 1m0s
[04:05:51.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:6:0)
[04:05:51.453] Timer tick, asking Buildbucket for the build status
[04:05:51.563] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:51.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:7:0) after 1m0s
[04:06:51.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:7:0)
[04:06:51.585] Timer tick, asking Buildbucket for the build status
[04:06:51.720] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:51.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:8:0) after 1m0s
[04:07:51.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:8:0)
[04:07:51.856] Timer tick, asking Buildbucket for the build status
[04:07:51.923] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:51.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:9:0) after 1m0s
[04:08:52.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:9:0)
[04:08:52.178] Timer tick, asking Buildbucket for the build status
[04:08:52.502] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:52.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:10:0) after 1m0s
[04:09:52.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:10:0)
[04:09:52.676] Timer tick, asking Buildbucket for the build status
[04:09:52.759] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:52.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:11:0) after 1m0s
[04:10:52.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:11:0)
[04:10:52.878] Timer tick, asking Buildbucket for the build status
[04:10:53.549] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:53.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:12:0) after 1m0s
[04:11:53.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:12:0)
[04:11:53.804] Timer tick, asking Buildbucket for the build status
[04:11:53.926] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:53.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:13:0) after 1m0s
[04:12:54.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:13:0)
[04:12:54.109] Timer tick, asking Buildbucket for the build status
[04:12:54.160] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:54.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:14:0) after 1m0s
[04:13:54.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:14:0)
[04:13:54.222] Timer tick, asking Buildbucket for the build status
[04:13:54.285] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:54.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:15:0) after 1m0s
[04:14:54.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:15:0)
[04:14:54.490] Timer tick, asking Buildbucket for the build status
[04:14:54.566] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:54.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:16:0) after 1m0s
[04:15:54.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:16:0)
[04:15:54.770] Timer tick, asking Buildbucket for the build status
[04:15:54.884] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:54.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:17:0) after 1m0s
[04:16:54.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:17:0)
[04:16:54.981] Timer tick, asking Buildbucket for the build status
[04:16:55.092] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:55.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:18:0) after 1m0s
[04:17:55.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:18:0)
[04:17:55.116] Timer tick, asking Buildbucket for the build status
[04:17:55.169] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:55.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:19:0) after 1m0s
[04:18:55.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:19:0)
[04:18:55.181] Timer tick, asking Buildbucket for the build status
[04:18:55.329] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:55.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:20:0) after 1m0s
[04:19:55.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:20:0)
[04:19:55.571] Timer tick, asking Buildbucket for the build status
[04:19:55.842] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:55.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:21:0) after 1m0s
[04:20:56.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:21:0)
[04:20:56.033] Timer tick, asking Buildbucket for the build status
[04:20:56.231] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:56.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:22:0) after 1m0s
[04:21:56.225] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:22:0)
[04:21:56.225] Timer tick, asking Buildbucket for the build status
[04:21:56.349] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:56.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:23:0) after 1m0s
[04:22:56.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:23:0)
[04:22:56.369] Timer tick, asking Buildbucket for the build status
[04:22:56.659] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:56.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:24:0) after 1m0s
[04:23:56.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:24:0)
[04:23:56.687] Timer tick, asking Buildbucket for the build status
[04:23:56.784] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:56.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:25:0) after 1m0s
[04:24:56.862] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:25:0)
[04:24:56.862] Timer tick, asking Buildbucket for the build status
[04:24:56.940] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:56.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:26:0) after 1m0s
[04:25:56.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:26:0)
[04:25:56.962] Timer tick, asking Buildbucket for the build status
[04:25:57.078] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:57.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:27:0) after 1m0s
[04:26:57.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:27:0)
[04:26:57.152] Timer tick, asking Buildbucket for the build status
[04:26:57.272] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:57.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:28:0) after 1m0s
[04:27:57.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:28:0)
[04:27:57.965] Timer tick, asking Buildbucket for the build status
[04:27:58.365] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:58.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:29:0) after 1m0s
[04:28:58.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:29:0)
[04:28:58.384] Timer tick, asking Buildbucket for the build status
[04:28:58.589] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:58.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:30:0) after 1m0s
[04:29:58.667] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:30:0)
[04:29:58.667] Timer tick, asking Buildbucket for the build status
[04:29:59.447] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:59.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:31:0) after 1m0s
[04:30:59.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:31:0)
[04:30:59.502] Timer tick, asking Buildbucket for the build status
[04:30:59.659] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:59.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:32:0) after 1m0s
[04:31:59.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:32:0)
[04:31:59.753] Timer tick, asking Buildbucket for the build status
[04:31:59.877] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:59.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:33:0) after 1m0s
[04:33:00.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:33:0)
[04:33:00.075] Timer tick, asking Buildbucket for the build status
[04:33:00.553] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:00.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:34:0) after 1m0s
[04:34:01.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:34:0)
[04:34:01.010] Timer tick, asking Buildbucket for the build status
[04:34:01.172] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:01.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:35:0) after 1m0s
[04:35:01.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:35:0)
[04:35:01.499] Timer tick, asking Buildbucket for the build status
[04:35:01.946] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:01.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:36:0) after 1m0s
[04:36:01.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:36:0)
[04:36:01.975] Timer tick, asking Buildbucket for the build status
[04:36:02.600] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:02.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:37:0) after 1m0s
[04:37:02.690] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:37:0)
[04:37:02.690] Timer tick, asking Buildbucket for the build status
[04:37:02.818] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:02.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:38:0) after 1m0s
[04:38:02.920] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:38:0)
[04:38:02.920] Timer tick, asking Buildbucket for the build status
[04:38:03.051] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:03.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:39:0) after 1m0s
[04:39:03.249] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:39:0)
[04:39:03.249] Timer tick, asking Buildbucket for the build status
[04:39:04.163] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:04.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:40:0) after 1m0s
[04:40:04.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:40:0)
[04:40:04.200] Timer tick, asking Buildbucket for the build status
[04:40:04.404] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:04.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:41:0) after 1m0s
[04:41:04.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:41:0)
[04:41:04.422] Timer tick, asking Buildbucket for the build status
[04:41:05.050] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:05.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:42:0) after 1m0s
[04:42:05.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:42:0)
[04:42:05.266] Timer tick, asking Buildbucket for the build status
[04:42:05.421] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:05.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:43:0) after 1m0s
[04:43:05.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:43:0)
[04:43:05.476] Timer tick, asking Buildbucket for the build status
[04:43:05.581] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:05.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:44:0) after 1m0s
[04:44:05.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:44:0)
[04:44:05.626] Timer tick, asking Buildbucket for the build status
[04:44:05.724] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:05.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:45:0) after 1m0s
[04:45:05.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:45:0)
[04:45:05.780] Timer tick, asking Buildbucket for the build status
[04:45:05.869] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:05.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:46:0) after 1m0s
[04:46:06.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:46:0)
[04:46:06.004] Timer tick, asking Buildbucket for the build status
[04:46:06.087] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:06.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:47:0) after 1m0s
[04:47:06.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:47:0)
[04:47:06.115] Timer tick, asking Buildbucket for the build status
[04:47:06.250] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:06.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:48:0) after 1m0s
[04:48:06.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:48:0)
[04:48:06.364] Timer tick, asking Buildbucket for the build status
[04:48:06.509] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:06.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:49:0) after 1m0s
[04:49:06.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:49:0)
[04:49:06.564] Timer tick, asking Buildbucket for the build status
[04:49:07.017] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:07.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:50:0) after 1m0s
[04:50:07.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:50:0)
[04:50:07.147] Timer tick, asking Buildbucket for the build status
[04:50:07.318] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:07.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:51:0) after 1m0s
[04:51:07.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:51:0)
[04:51:07.387] Timer tick, asking Buildbucket for the build status
[04:51:07.467] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:07.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:52:0) after 1m0s
[04:52:07.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:52:0)
[04:52:07.539] Timer tick, asking Buildbucket for the build status
[04:52:07.711] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:07.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:53:0) after 1m0s
[04:53:07.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:53:0)
[04:53:07.742] Timer tick, asking Buildbucket for the build status
[04:53:07.804] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:07.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:54:0) after 1m0s
[04:54:07.835] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:54:0)
[04:54:07.835] Timer tick, asking Buildbucket for the build status
[04:54:07.925] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:07.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:55:0) after 1m0s
[04:55:07.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:55:0)
[04:55:07.956] Timer tick, asking Buildbucket for the build status
[04:55:08.038] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:08.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:56:0) after 1m0s
[04:56:08.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:56:0)
[04:56:08.075] Timer tick, asking Buildbucket for the build status
[04:56:08.516] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:08.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:57:0) after 1m0s
[04:57:08.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:57:0)
[04:57:08.649] Timer tick, asking Buildbucket for the build status
[04:57:08.879] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:08.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:58:0) after 1m0s
[04:58:08.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:58:0)
[04:58:08.957] Timer tick, asking Buildbucket for the build status
[04:58:09.543] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:09.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:59:0) after 1m0s
[04:59:09.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:59:0)
[04:59:09.657] Timer tick, asking Buildbucket for the build status
[04:59:09.755] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:09.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:60:0) after 1m0s
[05:00:09.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:60:0)
[05:00:09.833] Timer tick, asking Buildbucket for the build status
[05:00:09.930] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:09.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:61:0) after 1m0s
[05:01:09.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:61:0)
[05:01:09.957] Timer tick, asking Buildbucket for the build status
[05:01:10.093] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:10.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:62:0) after 1m0s
[05:02:10.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:62:0)
[05:02:10.124] Timer tick, asking Buildbucket for the build status
[05:02:10.334] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:10.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:63:0) after 1m0s
[05:03:10.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:63:0)
[05:03:10.363] Timer tick, asking Buildbucket for the build status
[05:03:10.457] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:10.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:64:0) after 1m0s
[05:04:10.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:64:0)
[05:04:10.595] Timer tick, asking Buildbucket for the build status
[05:04:10.813] Build 8858251047588721472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:10.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023681109689329904:65:0) after 1m0s
[05:04:36.421] Received PubSub notification, asking Buildbucket for the build status
[05:04:36.708] Build 8858251047588721472: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:04:36.708] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610514274220334",
"created_by": "project:chromium",
"created_ts": "1610510509843140",
"id": "8858251047588721472",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a481f3f69044de5c19725b8a6066f9536a413813\"}}",
"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\": \"a481f3f69044de5c19725b8a6066f9536a413813\", \"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-8-wwgq\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16789, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"d38b9f86ff8c57378ed2b301ad1f1b9ba80837ed\", \"got_dawn_revision\": \"22eff1eb8c2ff44e1928121d93559fcf0b81f834\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"a481f3f69044de5c19725b8a6066f9536a413813\", \"got_revision_cp\": \"refs/heads/master@{#842870}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"3549479dc4ccd92ef3b9e179080dcec1a430b01d\", \"got_v8_revision\": \"3d7f91cf38ba97e2f8c8ba88ddeb8e9cc51cfeaf\", \"got_v8_revision_cp\": \"refs/heads/8.9.256@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"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\": \"a481f3f69044de5c19725b8a6066f9536a413813\", \"swarm_hashes_refs/heads/master(at){#842870}_without_patch\": {\"absl_hardening_tests\": \"1ef04d65afd764da8e896aeba26aada62e6909f3\", \"accessibility_unittests\": \"e611986cdf77120b54018b7b3c0ca9f7cfed302d\", \"angle_unittests\": \"8cb344c6120ce20e33e1de6da414e44fc2341709\", \"app_shell_unittests\": \"07a609e21fe63c6ce91eb24052fc7968597993ba\", \"aura_unittests\": \"46cf682b8c0a4f3d7c68696423ca32f0eb93bc94\", \"base_unittests\": \"06ba49c045eab786cb7ae4a49961143d56402f3a\", \"base_util_unittests\": \"3737961d0c8907433d49c3c79757dfa42e8e044e\", \"blink_common_unittests\": \"29a4976f7b8d56a812818b8ce9450e448c581c99\", \"blink_fuzzer_unittests\": \"863dbf8dae2839270ff93b041f854e3db49b14d9\", \"blink_heap_unittests\": \"4f906955686fba7374c112afd88284c0f7199b39\", \"blink_platform_unittests\": \"61eabaab8116c1e14c281a98ce737460525688cd\", \"blink_unittests\": \"3e65a458672953d7e2342a77d32876123b867c4b\", \"boringssl_crypto_tests\": \"5ee30f498c0f5848954b33a14a1e19c67cec788d\", \"boringssl_ssl_tests\": \"ee8da5a346cba968462f7108f0783dfd697f9e1a\", \"browser_switcher_bho_unittests\": \"7b8bc711ffb6bead9b12b397a47bae67cb5f6cb3\", \"browser_tests\": \"2451bdd9256cc475fdbeaf7d1551ec43aed3f00b\", \"capture_unittests\": \"822d9ffe7dc2b88e8e5d1bda38f370b9caeaf119\", \"cast_unittests\": \"477c9256b981fc7844bd312d0f252061d8fe5431\", \"cc_unittests\": \"09b8c481c74d4b21de664add84a4e9d481fa271d\", \"chrome_app_unittests\": \"c108ed84c7d090a4ead709fe9cdff6c8be0a8a36\", \"chrome_cleaner_unittests\": \"4912d06993cbb7eff87ef7c0385581ebefa2dee8\", \"chrome_elf_unittests\": \"5c5e69c6da64f90c9fe7528fa7d543bc0b555985\", \"chromedriver_unittests\": \"a3b3d1d50469eae801a0af20e6653300a680ad64\", \"components_browsertests\": \"caa70f27b9294b6378a2903e5c41794cdb778409\", \"components_unittests\": \"19eed67098bf6d4e1af9ffdc910e9b32ac3f6ab1\", \"compositor_unittests\": \"8bd10c16e733590ef8e3355f8713ae50136e53d3\", \"content_browsertests\": \"59f022454b84422ce267dd5a6af2dba3fbe2790b\", \"content_unittests\": \"4d33068bf2d75ec42709e9225a8e25941777d00f\", \"courgette_unittests\": \"098be4bf12255bab4f09da15b9478f91f5aa7040\", \"crashpad_tests\": \"361cc0081fa38be3532c6c97962a28b6e246ccea\", \"cronet_tests\": \"3c2e709dce0e8dfb77c4db2a0fd20226a3ecd7d3\", \"cronet_unittests\": \"180afe1051606cb75694d5821cbdbece4a0fed24\", \"crypto_unittests\": \"adb7ab3f67626e0925d8d0be0410ea6582422342\", \"delayloads_unittests\": \"e949d670ab35ff6cc27d2946ff61777bcff69607\", \"device_unittests\": \"f286c02a967a6a3987393d5619b7dfb8c4535a92\", \"display_unittests\": \"b662eb253358c9d47b1a43a93dd5920d6826fac9\", \"elevation_service_unittests\": \"585a46045393b4420e98a6f7c967a9f9f0a6c0d2\", \"events_unittests\": \"a1c521ec120d5bba111fdbb7763b979b289c50d5\", \"extensions_browsertests\": \"508b74124f8a8c8a5b07d465a06f8c37436def36\", \"extensions_unittests\": \"67ca083b2bb2ab0c068d941c3dc4b587b2d207ce\", \"filesystem_service_unittests\": \"e557834e91848de1df9b5462c4c6b8b787439fc3\", \"gcm_unit_tests\": \"c3f2838c08f6feedbbac94accd6bca0dc78cc629\", \"gcp_unittests\": \"94cb38a8852cccefc8b5887734d9802a38987a8b\", \"gfx_unittests\": \"ed0a8d426d50d63c654789f827fb2eccc941dba5\", \"gin_unittests\": \"177329b912f631dee6f245af01c607d4d9857e75\", \"google_apis_unittests\": \"e0d46b95208dabcdaef166a4be6a5e1dedd6c145\", \"gpu_unittests\": \"7bde5e727dd99e59631a195fc35dbfd636f5515b\", \"gwp_asan_unittests\": \"15a1f0574f29e720be5b43fa47ee06d9e0434f18\", \"headless_browsertests\": \"4dcf2c5cbeaa60a4cd0b1ae3295614e9cbf582c2\", \"headless_unittests\": \"e65b8c852a9a16c13460ac6f2c60c62e06b6bf7f\", \"install_static_unittests\": \"f819b91d88b5a6fe815d2ac3459ae8a8cf478e09\", \"installer_util_unittests\": \"f5c3c3b0d1d8b3b4d3570c8ea04fdee5e92f7363\", \"interactive_ui_tests\": \"e822ffc022f146dbbaf9454a44472fe0dc075fa9\", \"ipc_tests\": \"95cdd70d3cf5dfa776a9bc68c31985d2c8cc2d1c\", \"jingle_unittests\": \"1f9e94de869d69730f26222f0a016e0d8697f434\", \"latency_unittests\": \"16ba66628d92fce9ba39e90662a7582b0acfd409\", \"libjingle_xmpp_unittests\": \"93b6af5404d60342d6fafa3f75628cd13d783970\", \"liburlpattern_unittests\": \"62e961b1330c1644b0d3affbb61d654378d011af\", \"media_blink_unittests\": \"30c62b75b7999677b295b889d7139d48bcee9071\", \"media_unittests\": \"b063c28e799a9e293006a40998542e0254cfe8ae\", \"message_center_unittests\": \"b435af1823d793cc3f920e229efeab75d6420e7c\", \"midi_unittests\": \"a9d79e5b6cff8ad02be584bc1564cbd5c166a5f1\", \"mojo_core_unittests\": \"a00b4b89d31be2ef0e6e41c059f16e5c5d3bc3ac\", \"mojo_unittests\": \"b23697a3588f13f3b78a1d47282768553271cc11\", \"nacl_loader_unittests\": \"cf3cc02d708e117eed3e800de0fa86564ce2e66e\", \"native_theme_unittests\": \"b5d8e00df884e980414ccce3b6d6e5d63500b1ea\", \"net_unittests\": \"bbfd42d9d551678c58cf2b497f0ea92856be398a\", \"notification_helper_unittests\": \"493ceac1c4a3ad243ce42d0ffc539be1b02a4917\", \"pdf_unittests\": \"72ae011fd6ff1da417e48a1b6459cd0d098642e4\", \"perfetto_unittests\": \"390f48362389f5bdccdb0cfb1480c57c5f2cab07\", \"ppapi_unittests\": \"1a0c453faa9d15c50bd30356f3661b40a190cc6d\", \"printing_unittests\": \"8fcf70e726c8e906b2a3449d300010523040591a\", \"remoting_unittests\": \"604a305298c2c6806ff1322152aee0879ca55718\", \"sbox_integration_tests\": \"63f3f07972685db3e67474c11c9144490ba3d089\", \"sbox_unittests\": \"013a8f0f93a07a08be956ee47c608e8d190523b1\", \"sbox_validation_tests\": \"80bb28f3ae967686eab469f9cfb08f4c0a6ac8dc\", \"service_manager_unittests\": \"36f53be7189c80f2353f4be2098e4c729fa9ddb3\", \"services_unittests\": \"19f7b2efbe7e1544d5ea4f5045d10049b94a46f2\", \"setup_unittests\": \"5be2f9bcea58b89420efe58f73530da44fcb2599\", \"shell_dialogs_unittests\": \"8170e494f0a71be6f313e16e3cc8060e00eeef8b\", \"skia_unittests\": \"82dbd940f1cff099de716446db4b72541a3d8281\", \"snapshot_unittests\": \"a8471723a62f2629f5664885358ffe1ff54c9302\", \"sql_unittests\": \"6385b5245ef97c28b769d16b2019b293e544e5ff\", \"storage_unittests\": \"06c4177d51ad6be24b57a9ed7221584e44058885\", \"sync_integration_tests\": \"21cd93841b68c58c6ea879b15a22f64c776fa302\", \"traffic_annotation_auditor_unittests\": \"89fe1969f1a1773f0a10b63f7c862b83ec9b5b47\", \"ui_base_unittests\": \"4fe3e55d8430d6200e654dbc2cf30d4d4105093f\", \"ui_touch_selection_unittests\": \"eecff912c9dd6e37ae0477da72cf8c4a16532f7d\", \"unit_tests\": \"326562cfe13796f0289115ed5bee7eee7aaff0db\", \"updater_tests\": \"938bf2bc978342a1de69f756c10ceb9284e83918\", \"url_unittests\": \"c9872d3ce0f26050a14c24375bb604cad788794a\", \"views_unittests\": \"9054359ada376e044818aca197895a70df4420a0\", \"viz_unittests\": \"8930a312d35bc1ad614761ac3aaab1cc610fd866\", \"vr_common_unittests\": \"2a2ad56116cf1aeb6c3ad05c076b8a9974c3d9ea\", \"vr_pixeltests\": \"b6f54fb8ca870bf842743e04156210f1fde8c7fa\", \"weblayer_browsertests\": \"372ea6c6a0ff610d3f66a9d857a50ddd1348e9f9\", \"weblayer_unittests\": \"66889dcf46c0ba9756d53069e7639a38719556d2\", \"wm_unittests\": \"2063f43644cad1d1cf42b7245d655b1534ab1034\", \"wtf_unittests\": \"02529a70f545551da13a8463e0a2ca0a80188d9f\", \"zlib_unittests\": \"eac41890e5ef08112a0c18acda29c480652e0f6d\", \"zucchini_unittests\": \"feb6cc47956e67508e476dd2ec0bd9e27a1b3704\"}}, \"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-8-wwgq\"], \"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\": [\"5661-c6f6250\"], \"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": "1610510513675985",
"status": "COMPLETED",
"status_changed_ts": "1610514274507353",
"tags": [
"build_address:luci.chromium.ci/win-asan/16789",
"builder:win-asan",
"buildset:commit/git/a481f3f69044de5c19725b8a6066f9536a413813",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a481f3f69044de5c19725b8a6066f9536a413813",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023681109689329904",
"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/8858251047588721472/+/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:5112b9b1e6775010",
"user_agent:luci-scheduler"
],
"updated_ts": "1610514274524141",
"url": "https://ci.chromium.org/b/8858251047588721472",
"utcnow_ts": "1610514276689283"
}
}
[05:04:36.708] Invocation finished in 1h2m48.251274734s with status SUCCEEDED