[17:59:47.819] New invocation is queued and will start shortly
[17:59:48.933] Starting the invocation (attempt 1)
[17:59:48.963] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:59:48.963] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:59:48.963] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020004509317659904",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2d619248d7ead083037ecc8a87803c998cdc7749\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020004509317659904",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/2d619248d7ead083037ecc8a87803c998cdc7749",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2d619248d7ead083037ecc8a87803c998cdc7749",
"gitiles_ref:refs/heads/master"
]
}
[17:59:49.855] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614016789054156",
"id": "8854574447359391440",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2d619248d7ead083037ecc8a87803c998cdc7749\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614016789623194",
"tags": [
"build_address:luci.chromium.ci/win-asan/17474",
"builder:win-asan",
"buildset:commit/git/2d619248d7ead083037ecc8a87803c998cdc7749",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2d619248d7ead083037ecc8a87803c998cdc7749",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020004509317659904",
"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/8854574447359391440/+/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": "1614016789623314",
"url": "https://ci.chromium.org/b/8854574447359391440",
"utcnow_ts": "1614016789838949"
}
}
[17:59:49.855] Task URL: https://ci.chromium.org/b/8854574447359391440
[17:59:49.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:2:0) after 1m0s
[18:00:31.279] Received PubSub notification, asking Buildbucket for the build status
[18:00:31.479] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:49.864] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:2:0)
[18:00:49.864] Timer tick, asking Buildbucket for the build status
[18:00:49.951] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:49.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:4:0) after 1m0s
[18:01:49.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:4:0)
[18:01:49.978] Timer tick, asking Buildbucket for the build status
[18:01:50.215] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:50.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:5:0) after 1m0s
[18:02:50.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:5:0)
[18:02:50.195] Timer tick, asking Buildbucket for the build status
[18:02:50.318] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:50.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:6:0) after 1m0s
[18:03:50.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:6:0)
[18:03:50.338] Timer tick, asking Buildbucket for the build status
[18:03:50.643] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:50.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:7:0) after 1m0s
[18:04:50.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:7:0)
[18:04:50.661] Timer tick, asking Buildbucket for the build status
[18:04:50.900] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:50.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:8:0) after 1m0s
[18:05:50.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:8:0)
[18:05:50.942] Timer tick, asking Buildbucket for the build status
[18:05:51.203] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:51.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:9:0) after 1m0s
[18:06:51.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:9:0)
[18:06:51.235] Timer tick, asking Buildbucket for the build status
[18:06:51.334] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:51.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:10:0) after 1m0s
[18:07:51.339] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:10:0)
[18:07:51.339] Timer tick, asking Buildbucket for the build status
[18:07:51.454] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:51.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:11:0) after 1m0s
[18:08:51.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:11:0)
[18:08:51.472] Timer tick, asking Buildbucket for the build status
[18:08:51.599] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:51.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:12:0) after 1m0s
[18:09:51.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:12:0)
[18:09:51.635] Timer tick, asking Buildbucket for the build status
[18:09:52.045] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:52.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:13:0) after 1m0s
[18:10:52.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:13:0)
[18:10:52.134] Timer tick, asking Buildbucket for the build status
[18:10:52.228] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:52.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:14:0) after 1m0s
[18:11:52.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:14:0)
[18:11:52.385] Timer tick, asking Buildbucket for the build status
[18:11:52.462] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:52.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:15:0) after 1m0s
[18:12:52.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:15:0)
[18:12:52.576] Timer tick, asking Buildbucket for the build status
[18:12:52.767] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:52.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:16:0) after 1m0s
[18:13:52.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:16:0)
[18:13:52.844] Timer tick, asking Buildbucket for the build status
[18:13:52.924] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:52.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:17:0) after 1m0s
[18:14:53.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:17:0)
[18:14:53.031] Timer tick, asking Buildbucket for the build status
[18:14:53.152] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:53.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:18:0) after 1m0s
[18:15:53.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:18:0)
[18:15:53.319] Timer tick, asking Buildbucket for the build status
[18:15:53.509] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:53.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:19:0) after 1m0s
[18:16:53.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:19:0)
[18:16:53.661] Timer tick, asking Buildbucket for the build status
[18:16:53.736] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:53.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:20:0) after 1m0s
[18:17:53.739] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:20:0)
[18:17:53.739] Timer tick, asking Buildbucket for the build status
[18:17:53.895] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:53.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:21:0) after 1m0s
[18:18:54.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:21:0)
[18:18:54.012] Timer tick, asking Buildbucket for the build status
[18:18:54.220] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:54.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:22:0) after 1m0s
[18:19:54.278] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:22:0)
[18:19:54.278] Timer tick, asking Buildbucket for the build status
[18:19:54.422] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:54.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:23:0) after 1m0s
[18:20:54.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:23:0)
[18:20:54.517] Timer tick, asking Buildbucket for the build status
[18:20:54.837] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:54.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:24:0) after 1m0s
[18:21:55.013] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:24:0)
[18:21:55.013] Timer tick, asking Buildbucket for the build status
[18:21:55.128] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:55.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:25:0) after 1m0s
[18:22:55.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:25:0)
[18:22:55.165] Timer tick, asking Buildbucket for the build status
[18:22:55.504] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:55.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:26:0) after 1m0s
[18:23:55.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:26:0)
[18:23:55.585] Timer tick, asking Buildbucket for the build status
[18:23:55.698] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:55.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:27:0) after 1m0s
[18:24:55.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:27:0)
[18:24:55.767] Timer tick, asking Buildbucket for the build status
[18:24:55.994] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:55.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:28:0) after 1m0s
[18:25:55.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:28:0)
[18:25:55.985] Timer tick, asking Buildbucket for the build status
[18:25:56.076] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:56.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:29:0) after 1m0s
[18:26:56.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:29:0)
[18:26:56.102] Timer tick, asking Buildbucket for the build status
[18:26:56.187] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:56.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:30:0) after 1m0s
[18:27:56.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:30:0)
[18:27:56.219] Timer tick, asking Buildbucket for the build status
[18:27:56.300] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:56.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:31:0) after 1m0s
[18:28:56.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:31:0)
[18:28:56.501] Timer tick, asking Buildbucket for the build status
[18:28:56.663] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:56.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:32:0) after 1m0s
[18:29:56.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:32:0)
[18:29:56.638] Timer tick, asking Buildbucket for the build status
[18:29:56.750] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:56.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:33:0) after 1m0s
[18:30:56.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:33:0)
[18:30:56.752] Timer tick, asking Buildbucket for the build status
[18:30:56.843] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:56.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:34:0) after 1m0s
[18:31:56.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:34:0)
[18:31:56.870] Timer tick, asking Buildbucket for the build status
[18:31:56.974] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:56.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:35:0) after 1m0s
[18:32:57.017] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:35:0)
[18:32:57.017] Timer tick, asking Buildbucket for the build status
[18:32:57.236] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:57.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:36:0) after 1m0s
[18:33:57.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:36:0)
[18:33:57.209] Timer tick, asking Buildbucket for the build status
[18:33:57.524] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:57.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:37:0) after 1m0s
[18:34:57.570] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:37:0)
[18:34:57.570] Timer tick, asking Buildbucket for the build status
[18:34:57.819] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:57.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:38:0) after 1m0s
[18:35:57.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:38:0)
[18:35:57.837] Timer tick, asking Buildbucket for the build status
[18:35:57.927] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:57.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:39:0) after 1m0s
[18:36:57.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:39:0)
[18:36:57.918] Timer tick, asking Buildbucket for the build status
[18:36:58.336] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:58.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:40:0) after 1m0s
[18:37:58.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:40:0)
[18:37:58.404] Timer tick, asking Buildbucket for the build status
[18:37:58.499] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:58.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:41:0) after 1m0s
[18:38:58.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:41:0)
[18:38:58.556] Timer tick, asking Buildbucket for the build status
[18:38:59.242] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:59.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:42:0) after 1m0s
[18:39:59.230] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:42:0)
[18:39:59.230] Timer tick, asking Buildbucket for the build status
[18:39:59.420] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:59.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:43:0) after 1m0s
[18:40:59.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:43:0)
[18:40:59.484] Timer tick, asking Buildbucket for the build status
[18:40:59.584] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:59.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:44:0) after 1m0s
[18:41:59.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:44:0)
[18:41:59.621] Timer tick, asking Buildbucket for the build status
[18:41:59.703] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:59.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:45:0) after 1m0s
[18:42:59.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:45:0)
[18:42:59.749] Timer tick, asking Buildbucket for the build status
[18:42:59.999] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:59.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:46:0) after 1m0s
[18:44:00.017] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:46:0)
[18:44:00.017] Timer tick, asking Buildbucket for the build status
[18:44:00.225] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:00.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:47:0) after 1m0s
[18:45:00.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:47:0)
[18:45:00.203] Timer tick, asking Buildbucket for the build status
[18:45:00.528] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:00.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:48:0) after 1m0s
[18:46:00.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:48:0)
[18:46:00.589] Timer tick, asking Buildbucket for the build status
[18:46:00.698] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:00.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:49:0) after 1m0s
[18:47:01.001] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:49:0)
[18:47:01.001] Timer tick, asking Buildbucket for the build status
[18:47:01.128] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:01.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:50:0) after 1m0s
[18:48:01.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:50:0)
[18:48:01.426] Timer tick, asking Buildbucket for the build status
[18:48:01.697] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:01.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:51:0) after 1m0s
[18:49:02.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:51:0)
[18:49:02.016] Timer tick, asking Buildbucket for the build status
[18:49:02.109] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:02.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:52:0) after 1m0s
[18:50:02.160] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:52:0)
[18:50:02.160] Timer tick, asking Buildbucket for the build status
[18:50:02.324] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:02.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:53:0) after 1m0s
[18:51:02.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:53:0)
[18:51:02.316] Timer tick, asking Buildbucket for the build status
[18:51:02.466] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:02.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:54:0) after 1m0s
[18:52:02.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:54:0)
[18:52:02.485] Timer tick, asking Buildbucket for the build status
[18:52:02.606] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:02.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:55:0) after 1m0s
[18:53:02.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:55:0)
[18:53:02.614] Timer tick, asking Buildbucket for the build status
[18:53:02.956] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:02.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:56:0) after 1m0s
[18:54:03.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:56:0)
[18:54:03.048] Timer tick, asking Buildbucket for the build status
[18:54:03.428] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:03.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:57:0) after 1m0s
[18:55:03.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:57:0)
[18:55:03.516] Timer tick, asking Buildbucket for the build status
[18:55:03.658] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:03.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:58:0) after 1m0s
[18:56:03.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:58:0)
[18:56:03.701] Timer tick, asking Buildbucket for the build status
[18:56:03.955] Build 8854574447359391440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:03.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020004509317659904:59:0) after 1m0s
[18:56:59.843] Received PubSub notification, asking Buildbucket for the build status
[18:56:59.927] Build 8854574447359391440: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:56:59.927] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614020214260863",
"created_by": "project:chromium",
"created_ts": "1614016789054156",
"id": "8854574447359391440",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2d619248d7ead083037ecc8a87803c998cdc7749\"}}",
"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\": \"2d619248d7ead083037ecc8a87803c998cdc7749\", \"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\": 17474, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"c624d4101ea6c01e494e5322ca0baca5fef085a6\", \"got_dawn_revision\": \"1258e245a978083cab0b707c84dea814eadb3fbb\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"2d619248d7ead083037ecc8a87803c998cdc7749\", \"got_revision_cp\": \"refs/heads/master@{#856321}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"d85a7a27b27269741a779a72f33436b83d98687c\", \"got_v8_revision\": \"f8d07bea91ea6345e153dfef7bde9b55da6e59ea\", \"got_v8_revision_cp\": \"refs/heads/9.0.234@{#1}\", \"got_webrtc_revision\": \"753c76a705199ea752933f3688a8e4c8233dca53\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33298}\", \"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\": \"2d619248d7ead083037ecc8a87803c998cdc7749\", \"swarm_hashes_refs/heads/master(at){#856321}_without_patch\": {\"absl_hardening_tests\": \"77719e81557e70364610618acd520b379aadaa96\", \"accessibility_unittests\": \"8f9b748dcf44c77c498ff174be4ff0c825bb1364\", \"angle_unittests\": \"a5debc7e93c4066153d4d463211c99e2f13a35d5\", \"app_shell_unittests\": \"e92cecdb05863f3dab9eb5fb500c5d63ce69abcb\", \"aura_unittests\": \"9c74f5e5999013c94fda3d82923596b84f49e763\", \"base_unittests\": \"cdcaed0fae8727d7eb375829a28c73eb0306a8f4\", \"base_util_unittests\": \"a889c8178aad53fc24a7a3c0fbabb93d6a22c2db\", \"blink_common_unittests\": \"99a0698e55905ae1f6d84851d01cee16108a06b4\", \"blink_fuzzer_unittests\": \"6c20b1675498611bbefebdc6ec937594a2b9fc3f\", \"blink_heap_unittests\": \"9271e47279fe87ec128510d272867774dddd3d57\", \"blink_platform_unittests\": \"b6ec1c25b284cb1f8f36bffdc8c519373b4f6c6c\", \"blink_unittests\": \"7750093a5fd64a6abc0758250959fdf151036b25\", \"boringssl_crypto_tests\": \"cd85dfaadb1e9288ae2d117c7010942d121712f9\", \"boringssl_ssl_tests\": \"36523e11dec874cd24d334a79a54651be462281f\", \"browser_switcher_bho_unittests\": \"d44d7547db1f6c0af175a286ea9ef8b11a46e781\", \"browser_tests\": \"fe5c33c2f70ecabbf2662d70978f688f147299ff\", \"capture_unittests\": \"094f5251198bc0f1e47799eaaf20955925be9390\", \"cast_unittests\": \"8fe169dcc041d9fd3dc35c6804a8cdfa929dd91f\", \"cc_unittests\": \"2ab0901e3f6c60ee5025615d5bbd5008c401e205\", \"chrome_app_unittests\": \"bd9796a88c7a401fc31a8d9a80533f633730e448\", \"chrome_cleaner_unittests\": \"52df5906f1796adbf8213eacc65eced950724aca\", \"chrome_elf_unittests\": \"3f4bfb542817114739605da3210bed45638308c1\", \"chromedriver_unittests\": \"2a2abea28586ce416bd959ddc94c90c130e5d731\", \"components_browsertests\": \"c92bf0819c0f19ae9d4d1eac408b17ec9b848e7a\", \"components_unittests\": \"322a8d4550fd1b170827290a110a8f400c2f1c84\", \"compositor_unittests\": \"e0b3720673d797d21aefd9e92773cb5d3d67960d\", \"content_browsertests\": \"78c60dbf42ad7a63f5171eccb0c179c6c088fb6a\", \"content_unittests\": \"7c8f2e830fdaefb44020ad1c26e8ec752432b699\", \"courgette_unittests\": \"e83224d4c7b48ca8773755209def5a8f18928741\", \"crashpad_tests\": \"31c1ec62a70052216d201501e589288e44cecb5e\", \"cronet_tests\": \"44cebca79b7287d50bf0f43bc0491f84dc28ffd3\", \"cronet_unittests\": \"2faf9a729b078246b5a03c1c82df74acb85ea9d6\", \"crypto_unittests\": \"95eda521f8279fe2509d3eda600cfc7e34e052da\", \"delayloads_unittests\": \"17238b37bbcb15ae46103640a7db7db6aab29c89\", \"device_unittests\": \"3d440590e3944d7b34e3575975e79658da5cd39c\", \"display_unittests\": \"99eaa871d54178b3a177cad7ed6b6daa716dd56f\", \"elevation_service_unittests\": \"61da6f7a57fa1572a265a884828709cc053a2ab1\", \"events_unittests\": \"0548da403b2704e43259bbf90663034af8339d70\", \"extensions_browsertests\": \"8d60c207690bab544ea9e3f117ca76f726fb55de\", \"extensions_unittests\": \"503bbae7a40674fb26f124ab71ecd1ddca43c217\", \"filesystem_service_unittests\": \"40ed1010eb6e7d59f276221e5e4e54a48425c26a\", \"gcm_unit_tests\": \"d4db6e6cefd11a97bb154adc19232f849e168e77\", \"gcp_unittests\": \"25ae7bdfd70f5e9d3b57e7bf6d4dde3ab68ebdae\", \"gfx_unittests\": \"cad2a72d2d7cb64c28ce89c9084788052aa944ab\", \"gin_unittests\": \"ac1fccc2f40b252726b4ef5e6b63a35f442a3546\", \"google_apis_unittests\": \"84fc292560b645408c15abcea9ac4029982c2c6d\", \"gpu_unittests\": \"011b9a7576a1c5b0746123da698edb3d3d0624f3\", \"gwp_asan_unittests\": \"d97c1e949a83149acbf120c84cc12d365c0d1a62\", \"headless_browsertests\": \"06610290a067ec3888e10a4f1fe04823e60648f2\", \"headless_unittests\": \"70bdc5499f07e483a21a18657cf1a01175c07945\", \"install_static_unittests\": \"596dcd617434b35a1ba42397131c3f79a4a5bc77\", \"installer_util_unittests\": \"e60e8e3f3f63c772bda6ba40fd5bb74d7cd106d3\", \"interactive_ui_tests\": \"2b4be18246e46dd37906cfa50947acb90a5cfdf2\", \"ipc_tests\": \"8189ce671cee33a877504d8ac06084787cc39214\", \"jingle_unittests\": \"f4d87b765fb23add9583e8fe5f49c764e04a5433\", \"latency_unittests\": \"5273030af4c6b717e3c070a759f9fae4a1e16f13\", \"libjingle_xmpp_unittests\": \"74f5f0c77945a987a8afc5f90445dd2de13557a5\", \"liburlpattern_unittests\": \"dea5e569d195bfca3aacd38fb465106c8e61a832\", \"media_blink_unittests\": \"a99d792ddfdaa8c095b6e55ff58d6567b9e622c7\", \"media_unittests\": \"d4f6e47cb19916d4c98751c6755e44271c09d406\", \"message_center_unittests\": \"fb472fe5c00053d3760ccbdd5a023db5dede0f55\", \"midi_unittests\": \"fbaee699ff2d3caf2c613555be07e3ff949d5a58\", \"mojo_core_unittests\": \"f37482c37fbd0d20b17c6715adf7fe84cc63319a\", \"mojo_unittests\": \"20e5b6c27751ad4bc5f8d3adb0e4e94b90d06a54\", \"nacl_loader_unittests\": \"32f54021ae96b2dc8cb57d0b27fd544bf6add6f3\", \"native_theme_unittests\": \"f454e2f3f4db1f3a89ace3e629219c799e3d4925\", \"net_unittests\": \"418b644e5a6de039ce0fda2b31de13a1e6bad489\", \"notification_helper_unittests\": \"41f165b6dae3f1f92a13067700ad5de43b638536\", \"pdf_unittests\": \"649ecb3b2698393f8f3693d947abd92a2d9968c5\", \"perfetto_unittests\": \"475f77640758d3135555e3a0c61bb0503336288f\", \"ppapi_unittests\": \"d2b18c1f0d92ff63964fa7e2512b8c497dba0227\", \"printing_unittests\": \"9adc9dbac724252b6db7c7b0f57c8e06b5bb422a\", \"remoting_unittests\": \"00af4c94fe2b8fb55469b0d35a4cbfc9c026d355\", \"sbox_integration_tests\": \"e7d5fb61bf58b901661697eb9727a5efc3c73f69\", \"sbox_unittests\": \"7ee5d06f464e7cf857a4a8332955f72f57b18645\", \"sbox_validation_tests\": \"53945b8dddc321031058b3ed964bdd4ee4f1a105\", \"service_manager_unittests\": \"057bbf73c6cc8e5012cd06a5a283aa9bcd1fb285\", \"services_unittests\": \"576102f959e5c554a286a3644b4c0d77699f8567\", \"setup_unittests\": \"09524b9c02a4f29889893d6443d3f137b2f95d05\", \"shell_dialogs_unittests\": \"930e3b9581fefcfeefe258735e6dd384016f62ec\", \"skia_unittests\": \"8660697e2243431edd4033c21048c7f70e7286c5\", \"snapshot_unittests\": \"ee1f57cc045e0de0f0c8ff921b61036ba14838c1\", \"sql_unittests\": \"7840df688004a0b2ee84fd7883ecc08e3eb29b7c\", \"storage_unittests\": \"7f7bb8a3d7c75ac991f16c64ad9a816a096f7301\", \"sync_integration_tests\": \"d82193d05f6bffb829e04ae0b33a3d6f5f2f57c8\", \"traffic_annotation_auditor_unittests\": \"4da0ed93be341c1d1540f40d4ed18cf82546cc58\", \"ui_base_unittests\": \"8f03611779f4b7bd47c6e1973933e9f67eed4cfe\", \"ui_touch_selection_unittests\": \"0ea2e169d041e0bafea605cdd9002a6ce680f214\", \"unit_tests\": \"bffae6eb7c1eb2d695e0a70cd9031c12ba9a98b9\", \"updater_tests\": \"7f390ab603c6f72483b55959383c2e53b803a82f\", \"url_unittests\": \"6e5d518d34e99a3a235901f2f642ccecde484f2c\", \"views_unittests\": \"83eb6efbfa1de1bfb0207f240c41048c6c2ac014\", \"viz_unittests\": \"b8061c79e3920e1111645dc3438f135c4b6eb7ba\", \"vr_common_unittests\": \"6a873975a9692dbb1405ed5bc80b921b4b3fe93a\", \"vr_pixeltests\": \"83172e99fe715d1735a73eda6ded96dae6ca72db\", \"weblayer_browsertests\": \"07550ad7f2da80ee850dc4dbd426af1048383b7d\", \"weblayer_unittests\": \"0ea4b1b46251e87d0fca544525037629faa644f2\", \"wm_unittests\": \"f21514c60445cbd1d61c88745da3659732cd8deb\", \"wtf_unittests\": \"389e804aaa1fcc7594d47ebbd6644e9aa288e225\", \"zlib_unittests\": \"7a7235d8249466aec4772ff1839cae8dd452f2bc\", \"zucchini_unittests\": \"a5fcc385f8d184f845cbf4d2e6d058b7bdbae044\"}}, \"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\": [\"5706-397711c\"], \"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": "1614016793006317",
"status": "COMPLETED",
"status_changed_ts": "1614020214628202",
"tags": [
"build_address:luci.chromium.ci/win-asan/17474",
"builder:win-asan",
"buildset:commit/git/2d619248d7ead083037ecc8a87803c998cdc7749",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2d619248d7ead083037ecc8a87803c998cdc7749",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020004509317659904",
"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/8854574447359391440/+/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:51e3b74591f77e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614020214632432",
"url": "https://ci.chromium.org/b/8854574447359391440",
"utcnow_ts": "1614020219918749"
}
}
[18:56:59.927] Invocation finished in 57m12.123869425s with status SUCCEEDED