[06:24:37.060] New invocation is queued and will start shortly
[06:24:38.451] Starting the invocation (attempt 1)
[06:24:38.495] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:24:38.495] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:24:38.495] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021135409813498320",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"54790469c429e36b9fa0f5924da45a54f9012c26\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021135409813498320",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/54790469c429e36b9fa0f5924da45a54f9012c26",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/54790469c429e36b9fa0f5924da45a54f9012c26",
"gitiles_ref:refs/heads/master"
]
}
[06:24:39.070] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612938278578426",
"id": "8855705347559990528",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"54790469c429e36b9fa0f5924da45a54f9012c26\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612938278933634",
"tags": [
"build_address:luci.chromium.ci/win-asan/17265",
"builder:win-asan",
"buildset:commit/git/54790469c429e36b9fa0f5924da45a54f9012c26",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/54790469c429e36b9fa0f5924da45a54f9012c26",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021135409813498320",
"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/8855705347559990528/+/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": "1612938278933741",
"url": "https://ci.chromium.org/b/8855705347559990528",
"utcnow_ts": "1612938279061256"
}
}
[06:24:39.070] Task URL: https://ci.chromium.org/b/8855705347559990528
[06:24:39.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:2:0) after 1m0s
[06:24:41.171] Received PubSub notification, asking Buildbucket for the build status
[06:24:41.276] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:39.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:2:0)
[06:25:39.146] Timer tick, asking Buildbucket for the build status
[06:25:39.218] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:39.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:4:0) after 1m0s
[06:26:39.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:4:0)
[06:26:39.263] Timer tick, asking Buildbucket for the build status
[06:26:39.597] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:39.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:5:0) after 1m0s
[06:27:40.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:5:0)
[06:27:40.653] Timer tick, asking Buildbucket for the build status
[06:27:41.331] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:41.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:6:0) after 1m0s
[06:28:41.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:6:0)
[06:28:41.460] Timer tick, asking Buildbucket for the build status
[06:28:41.599] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:41.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:7:0) after 1m0s
[06:29:41.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:7:0)
[06:29:41.780] Timer tick, asking Buildbucket for the build status
[06:29:41.890] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:41.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:8:0) after 1m0s
[06:30:41.961] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:8:0)
[06:30:41.961] Timer tick, asking Buildbucket for the build status
[06:30:42.164] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:42.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:9:0) after 1m0s
[06:31:42.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:9:0)
[06:31:42.222] Timer tick, asking Buildbucket for the build status
[06:31:42.510] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:42.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:10:0) after 1m0s
[06:32:42.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:10:0)
[06:32:42.629] Timer tick, asking Buildbucket for the build status
[06:32:42.697] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:42.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:11:0) after 1m0s
[06:33:42.731] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:11:0)
[06:33:42.731] Timer tick, asking Buildbucket for the build status
[06:33:42.980] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:42.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:12:0) after 1m0s
[06:34:43.009] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:12:0)
[06:34:43.009] Timer tick, asking Buildbucket for the build status
[06:34:43.388] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:43.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:13:0) after 1m0s
[06:35:43.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:13:0)
[06:35:43.375] Timer tick, asking Buildbucket for the build status
[06:35:43.444] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:43.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:14:0) after 1m0s
[06:36:43.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:14:0)
[06:36:43.813] Timer tick, asking Buildbucket for the build status
[06:36:43.922] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:43.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:15:0) after 1m0s
[06:37:43.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:15:0)
[06:37:43.947] Timer tick, asking Buildbucket for the build status
[06:37:44.046] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:44.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:16:0) after 1m0s
[06:38:44.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:16:0)
[06:38:44.092] Timer tick, asking Buildbucket for the build status
[06:38:44.191] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:44.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:17:0) after 1m0s
[06:39:44.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:17:0)
[06:39:44.177] Timer tick, asking Buildbucket for the build status
[06:39:44.289] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:44.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:18:0) after 1m0s
[06:40:44.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:18:0)
[06:40:44.357] Timer tick, asking Buildbucket for the build status
[06:40:44.445] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:44.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:19:0) after 1m0s
[06:41:44.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:19:0)
[06:41:44.521] Timer tick, asking Buildbucket for the build status
[06:41:44.597] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:44.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:20:0) after 1m0s
[06:42:44.610] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:20:0)
[06:42:44.610] Timer tick, asking Buildbucket for the build status
[06:42:44.694] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:44.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:21:0) after 1m0s
[06:43:44.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:21:0)
[06:43:44.778] Timer tick, asking Buildbucket for the build status
[06:43:44.893] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:44.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:22:0) after 1m0s
[06:44:45.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:22:0)
[06:44:45.007] Timer tick, asking Buildbucket for the build status
[06:44:45.196] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:45.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:23:0) after 1m0s
[06:45:45.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:23:0)
[06:45:45.572] Timer tick, asking Buildbucket for the build status
[06:45:45.690] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:45.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:24:0) after 1m0s
[06:46:45.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:24:0)
[06:46:45.749] Timer tick, asking Buildbucket for the build status
[06:46:45.866] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:45.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:25:0) after 1m0s
[06:47:45.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:25:0)
[06:47:45.910] Timer tick, asking Buildbucket for the build status
[06:47:46.003] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:46.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:26:0) after 1m0s
[06:48:46.040] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:26:0)
[06:48:46.040] Timer tick, asking Buildbucket for the build status
[06:48:46.227] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:46.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:27:0) after 1m0s
[06:49:46.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:27:0)
[06:49:46.283] Timer tick, asking Buildbucket for the build status
[06:49:46.449] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:46.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:28:0) after 1m0s
[06:50:46.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:28:0)
[06:50:46.545] Timer tick, asking Buildbucket for the build status
[06:50:46.688] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:46.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:29:0) after 1m0s
[06:51:46.729] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:29:0)
[06:51:46.729] Timer tick, asking Buildbucket for the build status
[06:51:46.842] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:46.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:30:0) after 1m0s
[06:52:47.876] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:30:0)
[06:52:47.876] Timer tick, asking Buildbucket for the build status
[06:52:48.089] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:48.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:31:0) after 1m0s
[06:53:48.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:31:0)
[06:53:48.247] Timer tick, asking Buildbucket for the build status
[06:53:48.620] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:48.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:32:0) after 1m0s
[06:54:48.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:32:0)
[06:54:48.783] Timer tick, asking Buildbucket for the build status
[06:54:48.951] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:48.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:33:0) after 1m0s
[06:55:49.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:33:0)
[06:55:49.028] Timer tick, asking Buildbucket for the build status
[06:55:49.554] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:49.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:34:0) after 1m0s
[06:56:49.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:34:0)
[06:56:49.608] Timer tick, asking Buildbucket for the build status
[06:56:49.675] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:49.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:35:0) after 1m0s
[06:57:49.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:35:0)
[06:57:49.769] Timer tick, asking Buildbucket for the build status
[06:57:49.908] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:49.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:36:0) after 1m0s
[06:58:49.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:36:0)
[06:58:49.944] Timer tick, asking Buildbucket for the build status
[06:58:50.091] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:50.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:37:0) after 1m0s
[06:59:50.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:37:0)
[06:59:50.123] Timer tick, asking Buildbucket for the build status
[06:59:50.221] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:50.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:38:0) after 1m0s
[07:00:50.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:38:0)
[07:00:50.244] Timer tick, asking Buildbucket for the build status
[07:00:50.320] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:50.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:39:0) after 1m0s
[07:01:50.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:39:0)
[07:01:50.397] Timer tick, asking Buildbucket for the build status
[07:01:50.525] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:50.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:40:0) after 1m0s
[07:02:50.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:40:0)
[07:02:50.647] Timer tick, asking Buildbucket for the build status
[07:02:50.719] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:50.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:41:0) after 1m0s
[07:03:51.013] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:41:0)
[07:03:51.013] Timer tick, asking Buildbucket for the build status
[07:03:51.084] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:51.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:42:0) after 1m0s
[07:04:51.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:42:0)
[07:04:51.346] Timer tick, asking Buildbucket for the build status
[07:04:51.458] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:51.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:43:0) after 1m0s
[07:05:51.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:43:0)
[07:05:51.614] Timer tick, asking Buildbucket for the build status
[07:05:51.786] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:51.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:44:0) after 1m0s
[07:06:51.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:44:0)
[07:06:51.869] Timer tick, asking Buildbucket for the build status
[07:06:51.948] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:51.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:45:0) after 1m0s
[07:07:52.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:45:0)
[07:07:52.154] Timer tick, asking Buildbucket for the build status
[07:07:52.252] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:52.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:46:0) after 1m0s
[07:08:52.685] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:46:0)
[07:08:52.685] Timer tick, asking Buildbucket for the build status
[07:08:52.811] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:52.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:47:0) after 1m0s
[07:09:52.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:47:0)
[07:09:52.911] Timer tick, asking Buildbucket for the build status
[07:09:53.007] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:53.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:48:0) after 1m0s
[07:10:53.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:48:0)
[07:10:53.146] Timer tick, asking Buildbucket for the build status
[07:10:53.258] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:53.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:49:0) after 1m0s
[07:11:53.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:49:0)
[07:11:53.315] Timer tick, asking Buildbucket for the build status
[07:11:53.390] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:53.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:50:0) after 1m0s
[07:12:53.510] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:50:0)
[07:12:53.510] Timer tick, asking Buildbucket for the build status
[07:12:53.765] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:53.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:51:0) after 1m0s
[07:13:53.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:51:0)
[07:13:53.855] Timer tick, asking Buildbucket for the build status
[07:13:54.117] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:54.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:52:0) after 1m0s
[07:14:54.179] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:52:0)
[07:14:54.179] Timer tick, asking Buildbucket for the build status
[07:14:54.322] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:54.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:53:0) after 1m0s
[07:15:54.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:53:0)
[07:15:54.649] Timer tick, asking Buildbucket for the build status
[07:15:54.941] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:54.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:54:0) after 1m0s
[07:16:55.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:54:0)
[07:16:55.036] Timer tick, asking Buildbucket for the build status
[07:16:55.097] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:55.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:55:0) after 1m0s
[07:17:55.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:55:0)
[07:17:55.142] Timer tick, asking Buildbucket for the build status
[07:17:55.599] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:55.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:56:0) after 1m0s
[07:18:55.961] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:56:0)
[07:18:55.961] Timer tick, asking Buildbucket for the build status
[07:18:56.079] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:56.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:57:0) after 1m0s
[07:19:56.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:57:0)
[07:19:56.107] Timer tick, asking Buildbucket for the build status
[07:19:56.218] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:56.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:58:0) after 1m0s
[07:20:56.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:58:0)
[07:20:56.311] Timer tick, asking Buildbucket for the build status
[07:20:56.424] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:56.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:59:0) after 1m0s
[07:21:56.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:59:0)
[07:21:56.469] Timer tick, asking Buildbucket for the build status
[07:21:56.538] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:56.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:60:0) after 1m0s
[07:22:56.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:60:0)
[07:22:56.615] Timer tick, asking Buildbucket for the build status
[07:22:56.807] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:56.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:61:0) after 1m0s
[07:23:56.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:61:0)
[07:23:56.853] Timer tick, asking Buildbucket for the build status
[07:23:57.175] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:57.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:62:0) after 1m0s
[07:24:57.286] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:62:0)
[07:24:57.286] Timer tick, asking Buildbucket for the build status
[07:24:58.048] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:58.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:63:0) after 1m0s
[07:25:58.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:63:0)
[07:25:58.101] Timer tick, asking Buildbucket for the build status
[07:25:58.246] Build 8855705347559990528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:58.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021135409813498320:64:0) after 1m0s
[07:26:14.430] Received PubSub notification, asking Buildbucket for the build status
[07:26:14.556] Build 8855705347559990528: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:26:14.556] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612941973171040",
"created_by": "project:chromium",
"created_ts": "1612938278578426",
"id": "8855705347559990528",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"54790469c429e36b9fa0f5924da45a54f9012c26\"}}",
"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\": \"54790469c429e36b9fa0f5924da45a54f9012c26\", \"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-02em\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17265, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"aca0dc35f482ab6de0bf3be11fffcf15a0d53076\", \"got_dawn_revision\": \"b6b0090b501799fb98f8ca3cae1d87bd991635ed\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"54790469c429e36b9fa0f5924da45a54f9012c26\", \"got_revision_cp\": \"refs/heads/master@{#852514}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"94805df16b6f458269eba42e630b1fbf9eb8bbf6\", \"got_v8_revision\": \"c369eee9f5827733bc6784843744c4ac8a9bea0b\", \"got_v8_revision_cp\": \"refs/heads/9.0.147@{#1}\", \"got_webrtc_revision\": \"bb8f32f5410c93c675a1dac55aebd15c11945839\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33207}\", \"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\": \"54790469c429e36b9fa0f5924da45a54f9012c26\", \"swarm_hashes_refs/heads/master(at){#852514}_without_patch\": {\"absl_hardening_tests\": \"fcef62c3f5f27f6d2e03efcd528911359ee4ba2d\", \"accessibility_unittests\": \"4f5dc8cd8a60f220dad5a6b25b76c1e4ef249c44\", \"angle_unittests\": \"211c3e1c63f9681e1ee3e10c20f0b2f3798aa9bc\", \"app_shell_unittests\": \"f5567a6b43ea60924ce3c2dcf80ba72f5003c1bd\", \"aura_unittests\": \"c9cc319c94e116311244c504fbdea3ca41fb998e\", \"base_unittests\": \"2b1dd456dbca2a4c7d74c56dd41005f398eead8a\", \"base_util_unittests\": \"5939e3b28b31a5220b4721ae68d6dfd5670161d1\", \"blink_common_unittests\": \"1d03e9650a371c274fba0da4fa01b9f5a505d477\", \"blink_fuzzer_unittests\": \"71f0f930a5169c3d6d0c08d2ead5487ec6037d54\", \"blink_heap_unittests\": \"9344fa90157909463a2a4c2d6d901c7114da87ad\", \"blink_platform_unittests\": \"97468f3a8b4f4ab2d3d7bac49bc891226e40e757\", \"blink_unittests\": \"a51d929178db6bf038630c74e54eee927099ac39\", \"boringssl_crypto_tests\": \"4621e62e2f6d114d10cd3e2f6845cb4f35d33ab8\", \"boringssl_ssl_tests\": \"a232db71b356dd8bedd7d1b75ef5d59ae6b2655b\", \"browser_switcher_bho_unittests\": \"46ad3f1271ee0451c5ded90b64bec7596dd61313\", \"browser_tests\": \"efa166c9b0a48870d2c6a37a8870c34ed44e89aa\", \"capture_unittests\": \"2e83ac17940f1defa3100ca60d1ad28a682e5fb9\", \"cast_unittests\": \"73abea46440c2830792ccac209853ab3ab1c5848\", \"cc_unittests\": \"8cadfc786c4a265fc5417f20aa36d8371f59407e\", \"chrome_app_unittests\": \"3fc35fb35a876b48a60d70f862221d916f0cabe6\", \"chrome_cleaner_unittests\": \"86e742e955b7cc56fb6ae18a1641f0eceebad4d1\", \"chrome_elf_unittests\": \"ae085c90794548733df10777b1adb0b1ef3212a1\", \"chromedriver_unittests\": \"9a33f93586c6852654cff34ab87d2ddd74a485a4\", \"components_browsertests\": \"e6f5f646198184f5aa878bdf535df728e6d62acb\", \"components_unittests\": \"d396f32738e4683f602feaec7ccd33ce278d95fc\", \"compositor_unittests\": \"6ad3f1cf5682027cfe8489dae5e1fb3d4ee33a25\", \"content_browsertests\": \"6bbe5845df65e5ded3416a9740949c92570b23ce\", \"content_unittests\": \"abd8f45fdd336228c44f52fc216183ff19a7bdca\", \"courgette_unittests\": \"eaeb9081b6199201f4dea3731f5bd6f835543304\", \"crashpad_tests\": \"fd7fb00ec26494476c1d88f751a635f7e69fc63d\", \"cronet_tests\": \"66a815243d016df912893d38f670f43888b4f1d5\", \"cronet_unittests\": \"93da98008e0e3a13f8a5b07893eb5233cbe68a18\", \"crypto_unittests\": \"7c980df14649969d2c48d4c4853f0eff3fac71e8\", \"delayloads_unittests\": \"b34c9142c6d5e297fba7794a1d0c94748689400d\", \"device_unittests\": \"86d379aacaa36d90bf480248c5493edbff2b8d61\", \"display_unittests\": \"985e11b07d68e95cc37c1624f9220ad058f03b2a\", \"elevation_service_unittests\": \"a31c1b78fed7e40bcbb2bc9f100096d79586a95e\", \"events_unittests\": \"1d1d26d49de83f0346424fdac3dc9e73743df7d7\", \"extensions_browsertests\": \"43420f3bb13264b1a661c1fc4a6a2febe3e8f0da\", \"extensions_unittests\": \"685d49e4037c7b8a63a3cdaded4f36c8d306602b\", \"filesystem_service_unittests\": \"dc1e897c3ac732acffbc120a0601f2bfc8642b46\", \"gcm_unit_tests\": \"5849db77d56f7741261c596d14b911084ce743c1\", \"gcp_unittests\": \"ad7e583fa3f1771a498cef5343d83edd0a2884f2\", \"gfx_unittests\": \"02575ab33d5e3a897437a6e7952b6525bc3f5eb2\", \"gin_unittests\": \"a5e38d82fed23669db5f22bc5adc9e715aa08c42\", \"google_apis_unittests\": \"ccaac7ff33df43ec50b393b39fb8694175c1aa46\", \"gpu_unittests\": \"141c7bf6d66464f520f3bdbb7ad31f27a8b575df\", \"gwp_asan_unittests\": \"2e73f5049dffd88ba61f2385300daf415cb654dd\", \"headless_browsertests\": \"9eeee1413dc8e6927e30188850eba13f4820fc9e\", \"headless_unittests\": \"771b8751d22d53359940bb89e0cdcfb32e7b4be6\", \"install_static_unittests\": \"2fd855587e8a6e5edd48328e914ef79ab2a3c8ee\", \"installer_util_unittests\": \"8e36ee0e475a019f19e557f3d0a28fe2f128667d\", \"interactive_ui_tests\": \"389b102c16be05666b1c94cf208d2e88bbec9ef9\", \"ipc_tests\": \"6951a9a882c9bfb58138284b6532390a9a7f4485\", \"jingle_unittests\": \"73f7a5bcb446c341b85fe39e67ec6a9f2d196f03\", \"latency_unittests\": \"78493a9025d2e9c151d39b69537eb08fa7d6adb0\", \"libjingle_xmpp_unittests\": \"5f43d9c713dd0293ba06ad776d6208a84f81250a\", \"liburlpattern_unittests\": \"ec4f5aedd31753a4456a5b7188e0d244133d5752\", \"media_blink_unittests\": \"33f345b142e60480ad214df53e70d672c5ff96fd\", \"media_unittests\": \"1cae2e485e51303402372eaad336626a503421f9\", \"message_center_unittests\": \"6a5cf07abe22bb114bc6944752ce1a04ca4fc1d4\", \"midi_unittests\": \"e090e969f7e680edc1a66e2e499e00efb51d8b6d\", \"mojo_core_unittests\": \"267aa8f3dddbdec52dcb672397ad13a712b75261\", \"mojo_unittests\": \"4b75bb3136769f7c0aeb62cb03c6c66e209c505f\", \"nacl_loader_unittests\": \"e46752d7e6f67288dcf2e88c9194a54102df42e0\", \"native_theme_unittests\": \"477579793d7c7e9285c5bc582e4420824ffc6107\", \"net_unittests\": \"f8ea8fa146cc0051b7a9ecc8e95d95ec42f12ecd\", \"notification_helper_unittests\": \"22c7340a67f2323c1e96c37703483920cd23c57f\", \"pdf_unittests\": \"dd25d6d90bdb63c0c3253873b991fd57f7f5c2ee\", \"perfetto_unittests\": \"bef160bd35fe66c63dd596e0d05274c7d2adad0d\", \"ppapi_unittests\": \"d1a6ad82af64913984b593e9b30b6f3f05e4e8a9\", \"printing_unittests\": \"304da743f2513ed31d9efd56c21df4c586540069\", \"remoting_unittests\": \"d6fa9878333e35ec8865c2f9e09ae72dcbbe2785\", \"sbox_integration_tests\": \"3e9a55707347b7bfe64894594e663b675f0aa463\", \"sbox_unittests\": \"015b00a6bdd9d107114c324b282a6f9e5e73286b\", \"sbox_validation_tests\": \"1541fb55f18fad73d7e288f6c8333d6008bfe7af\", \"service_manager_unittests\": \"ff3afa4bbfa1cd9268779565dd0cf558177abecf\", \"services_unittests\": \"3b427aa4ec35d117cd0faf5d6278ed5aa64c6e36\", \"setup_unittests\": \"dcb75a180161215dc800ced35fc2b3e9674c9987\", \"shell_dialogs_unittests\": \"e58962dc058a50d73d1edab9b0da5ed070f94a80\", \"skia_unittests\": \"56ad8eb295a52fbbaf6fa1287e34cd0a258d9986\", \"snapshot_unittests\": \"8b6b7fee0ba992a0f9ffdcdab417f74bbb9e323d\", \"sql_unittests\": \"c77827e1a3732786b88cda14a7250935d943d2e5\", \"storage_unittests\": \"c7e29f7571460eed9a467ce67fdab5a4cbc0cc55\", \"sync_integration_tests\": \"fc9162a8aaf9102fd66c9dd19c6a67b3c7d3c6db\", \"traffic_annotation_auditor_unittests\": \"5b64730da0b5254ef4404b4a0078ccbbd9d9df3c\", \"ui_base_unittests\": \"6573aa79530f6b660d6f97d6ee468b3a671c19a2\", \"ui_touch_selection_unittests\": \"de21b41ecc85b4ab34b8a0f5d54997c12b1ae683\", \"unit_tests\": \"c6dc8f50a44425d43113178f77870dda0d06afc9\", \"updater_tests\": \"901de28cd57639cab89378b5b2aca9bc881ae68d\", \"url_unittests\": \"de795424b3ce28d7fb96ac3100856fa2c6782136\", \"views_unittests\": \"bc713ab979f405fd094843c61963d9efab726747\", \"viz_unittests\": \"7a61ed6b1dcdaaa17a0de29c49cabfc19fcec3ed\", \"vr_common_unittests\": \"c405b9c3ad32f18822be53164b1c0e0f8a981505\", \"vr_pixeltests\": \"11de03ff203f72c2202e14b9162839403ceb069e\", \"weblayer_browsertests\": \"89db73a5d7d79e04c38af0c116919a948ed240c5\", \"weblayer_unittests\": \"8b6b0b2fffa004a95277dda009c708a87349c792\", \"wm_unittests\": \"ed52439b25302f45a972a6b14273d693bb30db61\", \"wtf_unittests\": \"47fbb49a795be2cfacd5fe4fb763cefbf9661c70\", \"zlib_unittests\": \"e5c107f7ab4494ceebee12ea542077f6c895d0f3\", \"zucchini_unittests\": \"8baa9c93a5f4f56bcf2c6ba0c7cdd8803965b25d\"}}, \"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-02em\"], \"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\": [\"5685-1877293\"], \"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": "1612938280302457",
"status": "COMPLETED",
"status_changed_ts": "1612941973891739",
"tags": [
"build_address:luci.chromium.ci/win-asan/17265",
"builder:win-asan",
"buildset:commit/git/54790469c429e36b9fa0f5924da45a54f9012c26",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/54790469c429e36b9fa0f5924da45a54f9012c26",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021135409813498320",
"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/8855705347559990528/+/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:51a36e82c7691710",
"user_agent:luci-scheduler"
],
"updated_ts": "1612941973896594",
"url": "https://ci.chromium.org/b/8855705347559990528",
"utcnow_ts": "1612941974547116"
}
}
[07:26:14.556] Invocation finished in 1h1m37.511050462s with status SUCCEEDED