[18:36:44.870] New invocation is queued and will start shortly
[18:36:46.089] Starting the invocation (attempt 1)
[18:36:46.142] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:36:46.150] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:36:46.150] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019730393679538496",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b3929fd911497e2122c6be9b3d772b6903c1b200\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019730393679538496",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/b3929fd911497e2122c6be9b3d772b6903c1b200",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b3929fd911497e2122c6be9b3d772b6903c1b200",
"gitiles_ref:refs/heads/master"
]
}
[18:36:47.589] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614278206340230",
"id": "8854300331467191888",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b3929fd911497e2122c6be9b3d772b6903c1b200\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614278207293680",
"tags": [
"build_address:luci.chromium.ci/win-asan/17531",
"builder:win-asan",
"buildset:commit/git/b3929fd911497e2122c6be9b3d772b6903c1b200",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b3929fd911497e2122c6be9b3d772b6903c1b200",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019730393679538496",
"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/8854300331467191888/+/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": "1614278207294380",
"url": "https://ci.chromium.org/b/8854300331467191888",
"utcnow_ts": "1614278207571297"
}
}
[18:36:47.589] Task URL: https://ci.chromium.org/b/8854300331467191888
[18:36:47.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:2:0) after 1m0s
[18:36:52.059] Received PubSub notification, asking Buildbucket for the build status
[18:36:52.216] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:47.659] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:2:0)
[18:37:47.659] Timer tick, asking Buildbucket for the build status
[18:37:48.824] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:48.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:4:0) after 1m0s
[18:38:48.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:4:0)
[18:38:48.926] Timer tick, asking Buildbucket for the build status
[18:38:48.994] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:48.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:5:0) after 1m0s
[18:39:49.069] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:5:0)
[18:39:49.069] Timer tick, asking Buildbucket for the build status
[18:39:49.237] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:49.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:6:0) after 1m0s
[18:40:49.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:6:0)
[18:40:49.363] Timer tick, asking Buildbucket for the build status
[18:40:49.561] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:49.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:7:0) after 1m0s
[18:41:49.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:7:0)
[18:41:49.688] Timer tick, asking Buildbucket for the build status
[18:41:49.771] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:49.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:8:0) after 1m0s
[18:42:49.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:8:0)
[18:42:49.826] Timer tick, asking Buildbucket for the build status
[18:42:49.933] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:49.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:9:0) after 1m0s
[18:43:50.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:9:0)
[18:43:50.027] Timer tick, asking Buildbucket for the build status
[18:43:50.136] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:50.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:10:0) after 1m0s
[18:44:50.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:10:0)
[18:44:50.195] Timer tick, asking Buildbucket for the build status
[18:44:50.523] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:50.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:11:0) after 1m0s
[18:45:50.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:11:0)
[18:45:50.569] Timer tick, asking Buildbucket for the build status
[18:45:50.657] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:50.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:12:0) after 1m0s
[18:46:50.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:12:0)
[18:46:50.670] Timer tick, asking Buildbucket for the build status
[18:46:50.769] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:50.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:13:0) after 1m0s
[18:47:50.784] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:13:0)
[18:47:50.784] Timer tick, asking Buildbucket for the build status
[18:47:50.923] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:50.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:14:0) after 1m0s
[18:48:50.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:14:0)
[18:48:50.973] Timer tick, asking Buildbucket for the build status
[18:48:51.069] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:51.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:15:0) after 1m0s
[18:49:51.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:15:0)
[18:49:51.089] Timer tick, asking Buildbucket for the build status
[18:49:51.324] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:51.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:16:0) after 1m0s
[18:50:51.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:16:0)
[18:50:51.338] Timer tick, asking Buildbucket for the build status
[18:50:51.480] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:51.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:17:0) after 1m0s
[18:51:51.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:17:0)
[18:51:51.573] Timer tick, asking Buildbucket for the build status
[18:51:51.653] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:51.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:18:0) after 1m0s
[18:52:51.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:18:0)
[18:52:51.655] Timer tick, asking Buildbucket for the build status
[18:52:51.747] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:51.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:19:0) after 1m0s
[18:53:51.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:19:0)
[18:53:51.762] Timer tick, asking Buildbucket for the build status
[18:53:51.902] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:51.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:20:0) after 1m0s
[18:54:51.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:20:0)
[18:54:51.942] Timer tick, asking Buildbucket for the build status
[18:54:52.070] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:52.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:21:0) after 1m0s
[18:55:52.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:21:0)
[18:55:52.127] Timer tick, asking Buildbucket for the build status
[18:55:52.227] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:52.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:22:0) after 1m0s
[18:56:52.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:22:0)
[18:56:52.355] Timer tick, asking Buildbucket for the build status
[18:56:52.456] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:52.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:23:0) after 1m0s
[18:57:52.640] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:23:0)
[18:57:52.640] Timer tick, asking Buildbucket for the build status
[18:57:52.734] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:52.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:24:0) after 1m0s
[18:58:55.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:24:0)
[18:58:55.125] Timer tick, asking Buildbucket for the build status
[18:58:55.562] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:55.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:25:0) after 1m0s
[18:59:55.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:25:0)
[18:59:55.763] Timer tick, asking Buildbucket for the build status
[18:59:55.873] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:55.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:26:0) after 1m0s
[19:00:56.041] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:26:0)
[19:00:56.041] Timer tick, asking Buildbucket for the build status
[19:00:56.164] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:56.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:27:0) after 1m0s
[19:01:56.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:27:0)
[19:01:56.444] Timer tick, asking Buildbucket for the build status
[19:01:56.581] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:56.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:28:0) after 1m0s
[19:02:56.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:28:0)
[19:02:56.946] Timer tick, asking Buildbucket for the build status
[19:02:57.348] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:57.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:29:0) after 1m0s
[19:03:57.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:29:0)
[19:03:57.663] Timer tick, asking Buildbucket for the build status
[19:03:57.931] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:57.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:30:0) after 1m0s
[19:04:58.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:30:0)
[19:04:58.119] Timer tick, asking Buildbucket for the build status
[19:04:58.422] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:58.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:31:0) after 1m0s
[19:05:58.510] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:31:0)
[19:05:58.510] Timer tick, asking Buildbucket for the build status
[19:05:58.637] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:58.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:32:0) after 1m0s
[19:06:58.730] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:32:0)
[19:06:58.730] Timer tick, asking Buildbucket for the build status
[19:06:58.919] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:58.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:33:0) after 1m0s
[19:07:59.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:33:0)
[19:07:59.233] Timer tick, asking Buildbucket for the build status
[19:07:59.323] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:59.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:34:0) after 1m0s
[19:08:59.594] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:34:0)
[19:08:59.594] Timer tick, asking Buildbucket for the build status
[19:08:59.757] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:59.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:35:0) after 1m0s
[19:09:59.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:35:0)
[19:09:59.951] Timer tick, asking Buildbucket for the build status
[19:10:00.358] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:00.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:36:0) after 1m0s
[19:11:00.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:36:0)
[19:11:00.482] Timer tick, asking Buildbucket for the build status
[19:11:00.622] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:00.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:37:0) after 1m0s
[19:12:01.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:37:0)
[19:12:01.331] Timer tick, asking Buildbucket for the build status
[19:12:01.827] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:01.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:38:0) after 1m0s
[19:13:02.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:38:0)
[19:13:02.152] Timer tick, asking Buildbucket for the build status
[19:13:02.233] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:02.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:39:0) after 1m0s
[19:14:02.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:39:0)
[19:14:02.271] Timer tick, asking Buildbucket for the build status
[19:14:02.381] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:02.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:40:0) after 1m0s
[19:15:02.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:40:0)
[19:15:02.523] Timer tick, asking Buildbucket for the build status
[19:15:02.713] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:02.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:41:0) after 1m0s
[19:16:02.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:41:0)
[19:16:02.831] Timer tick, asking Buildbucket for the build status
[19:16:02.931] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:02.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:42:0) after 1m0s
[19:17:02.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:42:0)
[19:17:02.981] Timer tick, asking Buildbucket for the build status
[19:17:03.087] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:03.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:43:0) after 1m0s
[19:18:03.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:43:0)
[19:18:03.146] Timer tick, asking Buildbucket for the build status
[19:18:03.246] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:03.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:44:0) after 1m0s
[19:19:04.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:44:0)
[19:19:04.193] Timer tick, asking Buildbucket for the build status
[19:19:04.301] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:04.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:45:0) after 1m0s
[19:20:04.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:45:0)
[19:20:04.359] Timer tick, asking Buildbucket for the build status
[19:20:04.449] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:04.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:46:0) after 1m0s
[19:21:04.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:46:0)
[19:21:04.496] Timer tick, asking Buildbucket for the build status
[19:21:04.780] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:04.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:47:0) after 1m0s
[19:22:04.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:47:0)
[19:22:04.825] Timer tick, asking Buildbucket for the build status
[19:22:04.917] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:04.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:48:0) after 1m0s
[19:23:04.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:48:0)
[19:23:04.990] Timer tick, asking Buildbucket for the build status
[19:23:05.333] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:05.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:49:0) after 1m0s
[19:24:05.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:49:0)
[19:24:05.316] Timer tick, asking Buildbucket for the build status
[19:24:05.415] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:05.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:50:0) after 1m0s
[19:25:05.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:50:0)
[19:25:05.460] Timer tick, asking Buildbucket for the build status
[19:25:05.795] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:05.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:51:0) after 1m0s
[19:26:06.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:51:0)
[19:26:06.187] Timer tick, asking Buildbucket for the build status
[19:26:06.344] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:06.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:52:0) after 1m0s
[19:27:06.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:52:0)
[19:27:06.609] Timer tick, asking Buildbucket for the build status
[19:27:06.767] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:06.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:53:0) after 1m0s
[19:28:07.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:53:0)
[19:28:07.012] Timer tick, asking Buildbucket for the build status
[19:28:07.111] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:07.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:54:0) after 1m0s
[19:29:07.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:54:0)
[19:29:07.140] Timer tick, asking Buildbucket for the build status
[19:29:07.283] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:07.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:55:0) after 1m0s
[19:30:07.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:55:0)
[19:30:07.329] Timer tick, asking Buildbucket for the build status
[19:30:07.420] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:07.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:56:0) after 1m0s
[19:31:07.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:56:0)
[19:31:07.589] Timer tick, asking Buildbucket for the build status
[19:31:07.948] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:07.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:57:0) after 1m0s
[19:32:08.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:57:0)
[19:32:08.032] Timer tick, asking Buildbucket for the build status
[19:32:08.192] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:08.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:58:0) after 1m0s
[19:33:08.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:58:0)
[19:33:08.338] Timer tick, asking Buildbucket for the build status
[19:33:08.423] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:08.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:59:0) after 1m0s
[19:34:08.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:59:0)
[19:34:08.536] Timer tick, asking Buildbucket for the build status
[19:34:09.053] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:09.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:60:0) after 1m0s
[19:35:09.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:60:0)
[19:35:09.074] Timer tick, asking Buildbucket for the build status
[19:35:09.318] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:09.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:61:0) after 1m0s
[19:36:09.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:61:0)
[19:36:09.413] Timer tick, asking Buildbucket for the build status
[19:36:09.665] Build 8854300331467191888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:09.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019730393679538496:62:0) after 1m0s
[19:36:54.391] Received PubSub notification, asking Buildbucket for the build status
[19:36:54.677] Build 8854300331467191888: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:36:54.677] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614281809044201",
"created_by": "project:chromium",
"created_ts": "1614278206340230",
"id": "8854300331467191888",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b3929fd911497e2122c6be9b3d772b6903c1b200\"}}",
"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\": \"b3929fd911497e2122c6be9b3d772b6903c1b200\", \"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-3-xpf4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17531, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"9b1c569b14e90765cdd7c07e449400e88f1d6c45\", \"got_dawn_revision\": \"5eb496b863da5b40dc5c9a713c8ca1e413811f35\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"b3929fd911497e2122c6be9b3d772b6903c1b200\", \"got_revision_cp\": \"refs/heads/master@{#857680}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"349abccec8f7ebd1259de11965d58790a76b0ba4\", \"got_v8_revision\": \"bd0108b4c88e0d6f2350cb79b5f363fbd02f3eb7\", \"got_v8_revision_cp\": \"refs/heads/9.0.257@{#1}\", \"got_webrtc_revision\": \"cd5127b11e04b7f49031b2412625c36e4a86c3da\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33340}\", \"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\": \"b3929fd911497e2122c6be9b3d772b6903c1b200\", \"swarm_hashes_refs/heads/master(at){#857680}_without_patch\": {\"absl_hardening_tests\": \"684a3f08e1a36cecd08d97097e2dacfb83becee4\", \"accessibility_unittests\": \"f87f45540b5a326791f7b51232d9297507c4b5b9\", \"angle_unittests\": \"9f7b2fa7554af531d0d5b27d4fc8c550103f6723\", \"app_shell_unittests\": \"35db5c90e0607815bf9f7159b3679807a51e5a74\", \"aura_unittests\": \"eaa1608ae7c15b5936a7ee86b8ed6c213158937e\", \"base_unittests\": \"f59968c5579ab60741bb6c42c1649f689e7c33b9\", \"base_util_unittests\": \"d0c493cd905025a2fc56166bc0f32c13416286a9\", \"blink_common_unittests\": \"96d6068509931f83969e277652885cafa0502bd8\", \"blink_fuzzer_unittests\": \"b904742f58baeeb0de3d36378cc817cd11c4bdb1\", \"blink_heap_unittests\": \"79b6b9a8d29faf3af1e375e1f75150205f33573e\", \"blink_platform_unittests\": \"00cbf1e28227233d02e922db21aac7ca1b58ef87\", \"blink_unittests\": \"a0b0df9e723dca105fbff3d736ebe2210d6423da\", \"boringssl_crypto_tests\": \"008e4b792f2876d5be6f2c6d04915150e553939b\", \"boringssl_ssl_tests\": \"16cec33a1f19bd538a6d1255724d06ecfa3bc04a\", \"browser_switcher_bho_unittests\": \"d9c609656f5f56c340e23ffcbcb0c2418c574d24\", \"browser_tests\": \"91226711b3d5436d9017969773f6450f84e998e4\", \"capture_unittests\": \"5e42f23c7103a698ab4702b3689c785d3fc768c7\", \"cast_unittests\": \"160c2501823139eedc9dbb7431c539fedea0a457\", \"cc_unittests\": \"18009e0fbb96bce1515969b1b605c702010c902e\", \"chrome_app_unittests\": \"fd11a3cdf76d13a1c39beccb84a9b4726efe83e7\", \"chrome_cleaner_unittests\": \"e90edf6a3e8d16541cb64122996a77432450a341\", \"chrome_elf_unittests\": \"43196a1096d2c01b11304c013f5257f5d68bada2\", \"chromedriver_unittests\": \"4dc2fd4187e6eb5515ebc97ae0c9147b4f10db41\", \"color_unittests\": \"790172d8e02b3f30c6770561b0a6e4be66ceb620\", \"components_browsertests\": \"6b24f05b96cb7e5184c18930fc454be164fad386\", \"components_unittests\": \"24c3d629edee8be036a10e4051834b2c060863a6\", \"compositor_unittests\": \"301cd325b02cd644d5577b8ab72377b4f9000942\", \"content_browsertests\": \"f51703126f336fe2237829bf2e3bc25a345d3e90\", \"content_unittests\": \"d6662d90cf8878e3eaf76faca8d56154bb77329c\", \"courgette_unittests\": \"54eb956589bf0c068f12ccb8363a4355cb1b269c\", \"crashpad_tests\": \"55c9cc66a5c2afff97976c65b611badf8b23951c\", \"cronet_tests\": \"072ed6c71150c730330380fefd1b5d4b336faf8e\", \"cronet_unittests\": \"f35d7c785d28a2593b4d723ead6e659638ccb6e0\", \"crypto_unittests\": \"5737d145a534d8bfb4795f04cbeda9c5b7789608\", \"delayloads_unittests\": \"f5ab1fcd16903338e33b9c12dd2e93e0f22abe53\", \"device_unittests\": \"2442533489ab152571d03dd370c4275add0b01c9\", \"display_unittests\": \"ac1c2dc1965e1181a79470b79b54c3e971d6d6dd\", \"elevation_service_unittests\": \"a74f1e5401f34e7073d9ce12cc4260c9a193266b\", \"events_unittests\": \"d9006324193c6b6e88b63a3f2a4cb7be9d092c2d\", \"extensions_browsertests\": \"eb3fe680a65ad7896ed59578f5d915ef39c8c702\", \"extensions_unittests\": \"691389932533170aefc480af7e114e8a9b055934\", \"filesystem_service_unittests\": \"c4e5acf740928a185f743d45e709393015f4b80d\", \"gcm_unit_tests\": \"7f29ed3c06b9d64c951acfbb3147bd5ed44f16b1\", \"gcp_unittests\": \"e30a9d3f04bd88ab2f6986262e8719e6efe4f30f\", \"gfx_unittests\": \"845c48157d06b7c2b61ba1e341bb07cc783a7360\", \"gin_unittests\": \"d6d54f578cf5bbbbcdf0fb3b517a8c7de014aa40\", \"google_apis_unittests\": \"d1695264813817b65de9f3693601ca23588c3727\", \"gpu_unittests\": \"8d5d2df1ee13ebd3d3284d6ef55e998c0d6021ea\", \"gwp_asan_unittests\": \"b4da9c4ad0f470f1931b13b2f376d7b0e30416b3\", \"headless_browsertests\": \"d65bbb863dbab746423bb52416de89a88b7a57f3\", \"headless_unittests\": \"7d7a3d8cf9b4c7485e8622066b4548a38d64e035\", \"install_static_unittests\": \"6a6f4da78a6e70cf0d5c7e28724e284adfd1063d\", \"installer_util_unittests\": \"15091a5471dc43c6b5d35169a49659da0bc08603\", \"interactive_ui_tests\": \"4b698e2f0e3f4103fbb9ebea11a20646fb3fc68c\", \"ipc_tests\": \"8a7798c74c9d92484addb5b736f334882f8f660e\", \"jingle_unittests\": \"2c543e78cb0bf1702f3b9afa57d1044a0d2723b2\", \"latency_unittests\": \"bee842f638e1e8b98fecf5498c91a94c7a548034\", \"libjingle_xmpp_unittests\": \"b5912b03b640912fb008b6785908a4038c6dae08\", \"liburlpattern_unittests\": \"b78b4757049d7e35dbdb4aafd6f246e1ce9ac4f1\", \"media_blink_unittests\": \"cb8a4dd17928dffa24595c4a0c1eb5e8a77f8c2b\", \"media_unittests\": \"2bdda43c3662671a0489a64cb4d37f95e3c5b2a3\", \"message_center_unittests\": \"15c04fafb618a5db796cffe34ca037563651b1fb\", \"midi_unittests\": \"850656c0bd7969ca42b099204599df7f6e583c67\", \"mojo_core_unittests\": \"a94fa9a9782fe48d29e1ffdb8e7494c48fb35e08\", \"mojo_unittests\": \"ff36c72a4b5c50c82fb334ef5fb5f9a7240233f4\", \"nacl_loader_unittests\": \"d2237088b0860711992e68d4f50e78ced030ec89\", \"native_theme_unittests\": \"d48ae01b36dd1c22b08123f459e35c235a5b342a\", \"net_unittests\": \"71126748f1b3603e5dadec848b3f052308f29497\", \"notification_helper_unittests\": \"d910e311dcbc44774defc606366c838b85c1391c\", \"pdf_unittests\": \"e255955c7a8e182f8ff3aefd46431af3e228ede9\", \"perfetto_unittests\": \"2b7069f812585084152e12221820fec3e79dfb50\", \"ppapi_unittests\": \"a12dee2d80180cebd09c055899df5c9064706321\", \"printing_unittests\": \"f5c2e818786b8cbbe8da2200a6e59ce547a08b50\", \"remoting_unittests\": \"457c0f3a74ba3b92ea0d594a447d819f104fb5bc\", \"sbox_integration_tests\": \"9f50f5dd3bb98fafc65a6772f2a7203a668bcf01\", \"sbox_unittests\": \"36c5c6f037d43a908ab686a9202c6a4e0b3d54e7\", \"sbox_validation_tests\": \"c94fe5456aec715203a5f105ad82880a325df1e3\", \"service_manager_unittests\": \"ce9e455d4a47fd9fb46f17933d074801e0723886\", \"services_unittests\": \"f427c6ea31eb31796051e4cd0a483017564f8a4a\", \"setup_unittests\": \"3332a7f45082bb2db8869e8f2e83101b6e7bf038\", \"shell_dialogs_unittests\": \"a6b0c6d6f6b9c29528dc51fc03e04bf0d67131fb\", \"skia_unittests\": \"e40ead4e5c40300f81b5376d2ab21db364306267\", \"snapshot_unittests\": \"4960edbf324612aa8d532f3a9612a274f17b332e\", \"sql_unittests\": \"cc3986588614ead17a3558846c80494c84879774\", \"storage_unittests\": \"567dd198985a46a9284f2d9496f1d5b231dc3248\", \"sync_integration_tests\": \"39fd4c2d533ded5410ab003e796a163a7044b0ee\", \"traffic_annotation_auditor_unittests\": \"7fe9d574fda8b599c686977186a4ae2b483a972d\", \"ui_base_unittests\": \"3bbe5c7a490832735b73c30c1ab1b0169687d4d3\", \"ui_touch_selection_unittests\": \"2f8b638439a334f89c95e5b2d29fe91ac5c422d8\", \"unit_tests\": \"f62d9786a03820bb8b69d8b675bdc9de44143026\", \"updater_tests\": \"19e78c466881f1e7f7a307b1e8c8c48c19d0536d\", \"url_unittests\": \"5d26c3b3bc6fb57a49543c4537a113304318a8c8\", \"views_unittests\": \"71ffecdeff09d2768a69770d7dee59f9da1eeb79\", \"viz_unittests\": \"a804e37a066d6585e4cb2a53e93ec748ef90eccf\", \"vr_common_unittests\": \"7b9f26b46566751ebb9cba51a45bdefa05619707\", \"vr_pixeltests\": \"0df9ecc82d32efb1846c149ca34ade1fbbbada71\", \"weblayer_browsertests\": \"dbc2ff4d407ed27a6e138062669b4b79e448b702\", \"weblayer_unittests\": \"2d5741126e8859f88135f59637ff3aec424be32b\", \"wm_unittests\": \"33fd892e0e4ca5a61188d2179ecc5d30b86f1dac\", \"wtf_unittests\": \"322f334bb596a55754d59202e00a5bd304c9cbdf\", \"zlib_unittests\": \"f277bc8534a3901876d1525e81c17a7613a4b3c8\", \"zucchini_unittests\": \"8f069d11c277b2fa737e7f15a72c67f9aaeeedcb\"}}, \"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-3-xpf4\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5712-339372b\"], \"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": "1614278211475359",
"status": "COMPLETED",
"status_changed_ts": "1614281809447260",
"tags": [
"build_address:luci.chromium.ci/win-asan/17531",
"builder:win-asan",
"buildset:commit/git/b3929fd911497e2122c6be9b3d772b6903c1b200",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b3929fd911497e2122c6be9b3d772b6903c1b200",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019730393679538496",
"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/8854300331467191888/+/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:51f34c326fe40110",
"user_agent:luci-scheduler"
],
"updated_ts": "1614281809451320",
"url": "https://ci.chromium.org/b/8854300331467191888",
"utcnow_ts": "1614281814658922"
}
}
[19:36:54.677] Invocation finished in 1h0m9.831135716s with status SUCCEEDED