[07:42:30.483] New invocation is queued and will start shortly
[07:42:31.661] Starting the invocation (attempt 1)
[07:42:31.710] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:42:31.710] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:42:31.710] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9017869018623094976",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"96b58f4953c122313e67f600624b6b56ffeabd93\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9017869018623094976",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/96b58f4953c122313e67f600624b6b56ffeabd93",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/96b58f4953c122313e67f600624b6b56ffeabd93",
"gitiles_ref:refs/heads/master"
]
}
[07:42:32.398] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1616053351892557",
"id": "8852438956445034992",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"96b58f4953c122313e67f600624b6b56ffeabd93\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616053352260333",
"tags": [
"build_address:luci.chromium.ci/win-asan/17848",
"builder:win-asan",
"buildset:commit/git/96b58f4953c122313e67f600624b6b56ffeabd93",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/96b58f4953c122313e67f600624b6b56ffeabd93",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017869018623094976",
"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/8852438956445034992/+/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": "1616053352260450",
"url": "https://ci.chromium.org/b/8852438956445034992",
"utcnow_ts": "1616053352391185"
}
}
[07:42:32.398] Task URL: https://ci.chromium.org/b/8852438956445034992
[07:42:32.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:2:0) after 1m0s
[07:42:36.341] Received PubSub notification, asking Buildbucket for the build status
[07:42:36.403] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:32.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:2:0)
[07:43:32.449] Timer tick, asking Buildbucket for the build status
[07:43:32.626] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:32.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:4:0) after 1m0s
[07:44:32.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:4:0)
[07:44:32.615] Timer tick, asking Buildbucket for the build status
[07:44:32.687] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:32.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:5:0) after 1m0s
[07:45:32.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:5:0)
[07:45:32.890] Timer tick, asking Buildbucket for the build status
[07:45:33.120] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:33.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:6:0) after 1m0s
[07:46:33.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:6:0)
[07:46:33.112] Timer tick, asking Buildbucket for the build status
[07:46:33.219] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:33.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:7:0) after 1m0s
[07:47:33.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:7:0)
[07:47:33.224] Timer tick, asking Buildbucket for the build status
[07:47:33.422] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:33.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:8:0) after 1m0s
[07:48:33.510] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:8:0)
[07:48:33.510] Timer tick, asking Buildbucket for the build status
[07:48:33.909] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:33.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:9:0) after 1m0s
[07:49:33.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:9:0)
[07:49:33.968] Timer tick, asking Buildbucket for the build status
[07:49:34.302] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:34.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:10:0) after 1m0s
[07:50:34.412] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:10:0)
[07:50:34.412] Timer tick, asking Buildbucket for the build status
[07:50:34.479] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:34.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:11:0) after 1m0s
[07:51:34.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:11:0)
[07:51:34.595] Timer tick, asking Buildbucket for the build status
[07:51:34.678] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:34.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:12:0) after 1m0s
[07:52:34.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:12:0)
[07:52:34.700] Timer tick, asking Buildbucket for the build status
[07:52:34.777] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:34.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:13:0) after 1m0s
[07:53:34.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:13:0)
[07:53:34.888] Timer tick, asking Buildbucket for the build status
[07:53:34.969] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:34.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:14:0) after 1m0s
[07:54:35.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:14:0)
[07:54:35.027] Timer tick, asking Buildbucket for the build status
[07:54:35.131] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:35.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:15:0) after 1m0s
[07:55:35.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:15:0)
[07:55:35.195] Timer tick, asking Buildbucket for the build status
[07:55:35.358] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:35.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:16:0) after 1m0s
[07:56:35.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:16:0)
[07:56:35.478] Timer tick, asking Buildbucket for the build status
[07:56:35.565] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:35.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:17:0) after 1m0s
[07:57:35.580] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:17:0)
[07:57:35.580] Timer tick, asking Buildbucket for the build status
[07:57:35.684] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:35.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:18:0) after 1m0s
[07:58:35.702] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:18:0)
[07:58:35.702] Timer tick, asking Buildbucket for the build status
[07:58:35.771] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:35.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:19:0) after 1m0s
[07:59:35.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:19:0)
[07:59:35.890] Timer tick, asking Buildbucket for the build status
[07:59:35.951] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:35.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:20:0) after 1m0s
[08:00:35.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:20:0)
[08:00:35.992] Timer tick, asking Buildbucket for the build status
[08:00:36.092] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:36.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:21:0) after 1m0s
[08:01:36.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:21:0)
[08:01:36.224] Timer tick, asking Buildbucket for the build status
[08:01:36.311] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:36.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:22:0) after 1m0s
[08:02:36.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:22:0)
[08:02:36.358] Timer tick, asking Buildbucket for the build status
[08:02:36.713] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:36.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:23:0) after 1m0s
[08:03:36.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:23:0)
[08:03:36.760] Timer tick, asking Buildbucket for the build status
[08:03:36.835] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:36.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:24:0) after 1m0s
[08:04:36.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:24:0)
[08:04:36.859] Timer tick, asking Buildbucket for the build status
[08:04:37.171] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:37.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:25:0) after 1m0s
[08:05:37.155] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:25:0)
[08:05:37.155] Timer tick, asking Buildbucket for the build status
[08:05:37.231] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:37.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:26:0) after 1m0s
[08:06:37.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:26:0)
[08:06:37.280] Timer tick, asking Buildbucket for the build status
[08:06:37.572] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:37.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:27:0) after 1m0s
[08:07:37.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:27:0)
[08:07:37.613] Timer tick, asking Buildbucket for the build status
[08:07:37.852] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:37.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:28:0) after 1m0s
[08:08:37.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:28:0)
[08:08:37.844] Timer tick, asking Buildbucket for the build status
[08:08:37.999] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:37.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:29:0) after 1m0s
[08:09:38.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:29:0)
[08:09:38.291] Timer tick, asking Buildbucket for the build status
[08:09:38.449] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:38.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:30:0) after 1m0s
[08:10:38.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:30:0)
[08:10:38.608] Timer tick, asking Buildbucket for the build status
[08:10:39.012] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:39.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:31:0) after 1m0s
[08:11:39.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:31:0)
[08:11:39.072] Timer tick, asking Buildbucket for the build status
[08:11:39.747] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:39.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:32:0) after 1m0s
[08:12:39.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:32:0)
[08:12:39.937] Timer tick, asking Buildbucket for the build status
[08:12:40.403] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:40.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:33:0) after 1m0s
[08:13:40.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:33:0)
[08:13:40.508] Timer tick, asking Buildbucket for the build status
[08:13:40.621] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:40.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:34:0) after 1m0s
[08:14:40.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:34:0)
[08:14:40.671] Timer tick, asking Buildbucket for the build status
[08:14:41.065] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:41.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:35:0) after 1m0s
[08:15:41.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:35:0)
[08:15:41.095] Timer tick, asking Buildbucket for the build status
[08:15:41.198] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:41.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:36:0) after 1m0s
[08:16:41.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:36:0)
[08:16:41.274] Timer tick, asking Buildbucket for the build status
[08:16:41.412] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:41.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:37:0) after 1m0s
[08:17:41.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:37:0)
[08:17:41.436] Timer tick, asking Buildbucket for the build status
[08:17:41.594] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:41.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:38:0) after 1m0s
[08:18:41.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:38:0)
[08:18:41.614] Timer tick, asking Buildbucket for the build status
[08:18:41.693] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:41.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:39:0) after 1m0s
[08:19:41.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:39:0)
[08:19:41.719] Timer tick, asking Buildbucket for the build status
[08:19:41.889] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:41.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:40:0) after 1m0s
[08:20:41.943] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:40:0)
[08:20:41.943] Timer tick, asking Buildbucket for the build status
[08:20:42.046] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:42.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:41:0) after 1m0s
[08:21:42.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:41:0)
[08:21:42.057] Timer tick, asking Buildbucket for the build status
[08:21:42.395] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:42.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:42:0) after 1m0s
[08:22:42.440] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:42:0)
[08:22:42.440] Timer tick, asking Buildbucket for the build status
[08:22:42.539] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:42.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:43:0) after 1m0s
[08:23:42.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:43:0)
[08:23:42.557] Timer tick, asking Buildbucket for the build status
[08:23:42.642] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:42.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:44:0) after 1m0s
[08:24:42.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:44:0)
[08:24:42.662] Timer tick, asking Buildbucket for the build status
[08:24:42.901] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:42.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:45:0) after 1m0s
[08:25:42.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:45:0)
[08:25:42.944] Timer tick, asking Buildbucket for the build status
[08:25:43.047] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:43.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:46:0) after 1m0s
[08:26:43.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:46:0)
[08:26:43.064] Timer tick, asking Buildbucket for the build status
[08:26:43.162] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:43.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:47:0) after 1m0s
[08:27:43.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:47:0)
[08:27:43.217] Timer tick, asking Buildbucket for the build status
[08:27:43.317] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:43.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:48:0) after 1m0s
[08:28:43.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:48:0)
[08:28:43.375] Timer tick, asking Buildbucket for the build status
[08:28:43.453] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:43.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:49:0) after 1m0s
[08:29:43.473] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:49:0)
[08:29:43.473] Timer tick, asking Buildbucket for the build status
[08:29:43.564] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:43.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:50:0) after 1m0s
[08:30:43.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:50:0)
[08:30:43.595] Timer tick, asking Buildbucket for the build status
[08:30:43.671] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:43.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:51:0) after 1m0s
[08:31:43.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:51:0)
[08:31:43.675] Timer tick, asking Buildbucket for the build status
[08:31:44.184] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:44.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:52:0) after 1m0s
[08:32:44.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:52:0)
[08:32:44.206] Timer tick, asking Buildbucket for the build status
[08:32:44.464] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:44.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:53:0) after 1m0s
[08:33:44.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:53:0)
[08:33:44.520] Timer tick, asking Buildbucket for the build status
[08:33:44.615] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:44.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:54:0) after 1m0s
[08:34:44.640] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:54:0)
[08:34:44.640] Timer tick, asking Buildbucket for the build status
[08:34:44.720] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:44.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:55:0) after 1m0s
[08:35:44.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:55:0)
[08:35:44.758] Timer tick, asking Buildbucket for the build status
[08:35:44.868] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:44.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:56:0) after 1m0s
[08:36:44.924] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:56:0)
[08:36:44.924] Timer tick, asking Buildbucket for the build status
[08:36:45.012] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:45.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:57:0) after 1m0s
[08:37:45.047] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:57:0)
[08:37:45.047] Timer tick, asking Buildbucket for the build status
[08:37:45.291] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:45.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:58:0) after 1m0s
[08:38:45.417] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:58:0)
[08:38:45.417] Timer tick, asking Buildbucket for the build status
[08:38:45.498] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:45.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:59:0) after 1m0s
[08:39:45.773] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:59:0)
[08:39:45.773] Timer tick, asking Buildbucket for the build status
[08:39:46.113] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:46.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:60:0) after 1m0s
[08:40:46.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:60:0)
[08:40:46.411] Timer tick, asking Buildbucket for the build status
[08:40:46.661] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:46.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:61:0) after 1m0s
[08:41:46.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:61:0)
[08:41:46.892] Timer tick, asking Buildbucket for the build status
[08:41:47.065] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:47.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:62:0) after 1m0s
[08:42:47.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:62:0)
[08:42:47.211] Timer tick, asking Buildbucket for the build status
[08:42:47.309] Build 8852438956445034992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:47.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017869018623094976:63:0) after 1m0s
[08:43:31.994] Received PubSub notification, asking Buildbucket for the build status
[08:43:32.171] Build 8852438956445034992: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:43:32.171] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1616057010353397",
"created_by": "project:chromium",
"created_ts": "1616053351892557",
"id": "8852438956445034992",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"96b58f4953c122313e67f600624b6b56ffeabd93\"}}",
"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\": \"96b58f4953c122313e67f600624b6b56ffeabd93\", \"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-2-jb1u\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17848, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-2\", \"got_angle_revision\": \"bb7c1442587acf6bdee94f62f18d85e0485c3ab7\", \"got_dawn_revision\": \"3209df5a9cc2de5415688653d1142b1adda554db\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"96b58f4953c122313e67f600624b6b56ffeabd93\", \"got_revision_cp\": \"refs/heads/master@{#864140}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a98fb3f8380a3a5c9ae3e0175634ba88c85a434f\", \"got_v8_revision\": \"4f0f4e2a3c5ad9dc7312774da5197e0179c30863\", \"got_v8_revision_cp\": \"refs/heads/9.1.146@{#1}\", \"got_webrtc_revision\": \"92a768ad66f54ca57002f6f2e87c7a732e3fec39\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33488}\", \"is_cached\": true, \"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\": \"96b58f4953c122313e67f600624b6b56ffeabd93\", \"swarm_hashes_refs/heads/master(at){#864140}_without_patch\": {\"absl_hardening_tests\": \"d63f57694b170326078981968c982874efc9bdf5\", \"accessibility_unittests\": \"f09e159dd4be2582dda46fe27af3d7acba165891\", \"angle_unittests\": \"4564d270a9ca0db0da458378b8702722d810e3a6\", \"app_shell_unittests\": \"a80ef50a9f1ab3552cc2ee40de709db5f1ecc120\", \"aura_unittests\": \"9b6878417caf531efd89e7b632f39e74d60f1506\", \"base_unittests\": \"bdec48a5841a65533bce18f7cd61f5ddfb28ed7b\", \"base_util_unittests\": \"cf804366f51d298a56e9903dd493818388b37869\", \"blink_common_unittests\": \"5775a0547aa72ea901b57c932e40efe54e136f5e\", \"blink_fuzzer_unittests\": \"1a6bb06e2994c54d497e10565d58236694006838\", \"blink_heap_unittests\": \"2a3999635ebf36becec0d578f0ad09be6eb1e646\", \"blink_platform_unittests\": \"bbc6692966398ba8d41b8f64283eca9b7bcb21b1\", \"blink_unittests\": \"c677ef1318d546b2e88b687a12dc3bb4b566ac6c\", \"boringssl_crypto_tests\": \"fdcd595c3ebcc2240824fd14595ee68246e7d939\", \"boringssl_ssl_tests\": \"5de7cf0d4420f9fd0d8322113226a413efb7d8f5\", \"browser_switcher_bho_unittests\": \"d7808c45a5e1051fa546f824f98420572e6156bd\", \"browser_tests\": \"a134738bce4473c34c433817a5292b703a221da8\", \"capture_unittests\": \"2f5886a00d1c87d16770f51a5ac38067d7e00c67\", \"cast_unittests\": \"67bf7fd4164a2ac0abf991b354bc9379b9bd060d\", \"cc_unittests\": \"c05e42da50651d0a5c0137728880747ee86fd2b7\", \"chrome_app_unittests\": \"13e5a52f3c7300b916410fe3c98054c98d17c7a2\", \"chrome_cleaner_unittests\": \"020ffaf9f322738183a6cb8361243798ef34d0a3\", \"chrome_elf_unittests\": \"44f74f30c3a0852b5db5a08a29e30e39290a80be\", \"chromedriver_unittests\": \"5e794cc27ad89de6cda407e915dfb8597d982717\", \"color_unittests\": \"0cd7563f9f09d376cd51152f3fe7212c2cb8de43\", \"components_browsertests\": \"c163f3b59911594e28dee4abf7c3c527a46eb812\", \"components_unittests\": \"ba2af150ace42d41c95870619073d99d125195ce\", \"compositor_unittests\": \"31e8fc73c6102c43f0976be46560278454857039\", \"content_browsertests\": \"62041087fa52fb2ab0964eee7cc8fa4b5d10c141\", \"content_unittests\": \"93e723c53f37d4ae62b9980798d20c17f82462f3\", \"courgette_unittests\": \"0c56f6aedea45cb2d0bd9ef1339a86f082790ded\", \"crashpad_tests\": \"6b953191b67f3c5d2bd1543e934cfa92310167e7\", \"cronet_tests\": \"9b1e22099ecdae4611e8c49f74894b159200d3c4\", \"cronet_unittests\": \"3f9c5060fa7dbaf50b523df1e47a10cad7507441\", \"crypto_unittests\": \"4fa0687d1c566541eb223e8efbbcc7010b74bb42\", \"delayloads_unittests\": \"a3a18ce029e0976795437a09005a9dbbe4ff090c\", \"device_unittests\": \"276d85e24e23f46ba090d6eb6e0b8d02ba626650\", \"display_unittests\": \"fd4c01566d4f0dd84c4638ca9cb6f1a8b821291b\", \"elevation_service_unittests\": \"718552c7f47021547a989c5c94aa9693c5f1d528\", \"events_unittests\": \"330d4658fcc151ff991046b829639681ec9deee8\", \"extensions_browsertests\": \"2173ca6c60f1a130091fe0f66ab3f315e64f8a3d\", \"extensions_unittests\": \"6c6855c008457c02d009b9bfb1f8e5f1bf52ce02\", \"filesystem_service_unittests\": \"4963fe98690d6828657e0008a3d7268fa8fe3c36\", \"gcm_unit_tests\": \"8dedc0134c90f3097f2ddf73731ff7b9c58ceaa1\", \"gcp_unittests\": \"5bfc313566112df5ef66c9d6505dff9674a100e3\", \"gfx_unittests\": \"87e5b0d8fc3c72ad4cc86aa399c00a9bd753cb79\", \"gin_unittests\": \"fa0f009483deeaf7aca579d1a2651ff449885c01\", \"google_apis_unittests\": \"8f07d105940324b215f591c74f7b0c947ad90b15\", \"gpu_unittests\": \"19cb3a5a546fa1cecadb3f93b1df4dc91ea2f80b\", \"gwp_asan_unittests\": \"3e40145497fbf6069f221f3124bcd1c8c741ca37\", \"headless_browsertests\": \"271a04277c0708014c5e58293e1880e725cb3a2a\", \"headless_unittests\": \"d13f8818331b73a4c463b61f4f21f9305e18db4c\", \"install_static_unittests\": \"d19571396e31307caf271da03fb8b62bc92de4b3\", \"installer_util_unittests\": \"ef2588bdc3cda21bd45b66ec64e3647c11d80419\", \"interactive_ui_tests\": \"dab8c07c961ce758ecd990db6340f35ef0340a4a\", \"ipc_tests\": \"7b289bd1aa994d02ff68311effbcf3979ae366ab\", \"jingle_unittests\": \"96ff9149040433e3a32fce9a661fec05bda673aa\", \"latency_unittests\": \"10bac7aea56c9ac9eee703a8a1c4435906a8bfbd\", \"libjingle_xmpp_unittests\": \"803e648f54682890edb35e31f33d53c868a5d057\", \"liburlpattern_unittests\": \"f644dfd4d2fe840a187b69958ec93fef753bee8e\", \"media_blink_unittests\": \"d10a232062a791ee6e484c2a524e5887779eda51\", \"media_unittests\": \"58235f9b8e4082615602d4911857ea81cd54474a\", \"message_center_unittests\": \"f266132cb2171a892974e3519d5e0b205c68cf98\", \"midi_unittests\": \"ff8813e0c9bce341e5a139d0a8c55dab93534a80\", \"mojo_core_unittests\": \"6ca02e14661d5c6225732caf588f4a0b77282d93\", \"mojo_unittests\": \"b589998602588bd3ff3bd69adcd2818333b2dc73\", \"nacl_loader_unittests\": \"70a9f0f969a25366188807ae43d6f7718f8981e8\", \"native_theme_unittests\": \"594771613c71522482d4aecd24f075de5f5c18cb\", \"net_unittests\": \"c3ff172b27262050a46abb28194941bc3b0492e3\", \"notification_helper_unittests\": \"f54cf268b9253f24fd1f4939fb91cd8f40d9ae30\", \"pdf_unittests\": \"c0a5d4e1f17b2546f536e5061d24e564c1d928be\", \"perfetto_unittests\": \"79a7216054bae3c5b949acb2525b83edc662ac67\", \"ppapi_unittests\": \"53758f9c57c46799cb5c7c78596dabfae65ec0ab\", \"printing_unittests\": \"e0378865d1c4534b8e280f377dc72e4b88e670c8\", \"remoting_unittests\": \"6a838bbe7fbd980b70acd8927441c2126967c297\", \"sbox_integration_tests\": \"76f0018b40f4b85c8b6a3e48f7b94387a9f2da07\", \"sbox_unittests\": \"01cc0322bb7c0a867d96511c153e5e1ac153fc31\", \"sbox_validation_tests\": \"1047e67c0c4c87f3586f6a895a85a1fa11fcd057\", \"service_manager_unittests\": \"47b4f2690494bf8259a0a2a5f5ed479c892ba351\", \"services_unittests\": \"d92f311870c4d0d28e1e3136c84e33dde20f4ac9\", \"setup_unittests\": \"b57b420f98b05ee74d72d313b456018f7d12b115\", \"shell_dialogs_unittests\": \"c028b01951efbf4e6be7a612cec4bd3ba338a2a3\", \"skia_unittests\": \"2446bda8e37c33afe5eeb56588a52c9303e60485\", \"snapshot_unittests\": \"7ac2a407f2ad2ee74d7127b7e095714abb79f033\", \"sql_unittests\": \"c67c81eaf736a771060d7b14124635778c7a7505\", \"storage_unittests\": \"f92423d4e5761f33c870190147e44901f0eeb6bd\", \"sync_integration_tests\": \"005a140a2da0fa4755f628a9345a173d60f651d5\", \"traffic_annotation_auditor_unittests\": \"155335fbfd2457a6e0b2aa2a933c54ddd432c29b\", \"ui_base_unittests\": \"2188804fe4b8855012b0cf56b399e72153ba6796\", \"ui_touch_selection_unittests\": \"a227154a707accd4a92b4c58f271f0be17817fae\", \"unit_tests\": \"d12586e3fa9d9904ebf65d201d55c641d8709037\", \"updater_tests\": \"bd318d5bf44131e4eefa396e49044b1778ebd68a\", \"url_unittests\": \"764ea6ba721f185e0992a1e3b494a7ddfbf92cc6\", \"views_unittests\": \"0cb1888eec1fd9e2a0c05db26fb061e3a76813fc\", \"viz_unittests\": \"ceb45d2dbb8cc6b012ac1a932812e4232bebe65b\", \"vr_common_unittests\": \"3f50d5cbe86a92e2b41203cde29c521bef3c5cc9\", \"vr_pixeltests\": \"089fb2ddd9e7e13781830e39bc11713149d082ba\", \"weblayer_browsertests\": \"9773988d715e3d5243f87f97ee5a4c3318d70003\", \"weblayer_unittests\": \"5f80de0e42086188a692b992f4abe15839c00675\", \"wm_unittests\": \"e651d623bb6883b656bf828e7534aa3a55622582\", \"wtf_unittests\": \"09a742123918c7b2cc02126c7c5a475c2e2fb0f1\", \"zlib_unittests\": \"fa40a4d2471197526ddf5074a54b85204aa142a1\", \"zucchini_unittests\": \"3da96dcd461bd3734a98a54162e0d85d3b781e89\"}}, \"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-2-jb1u\"], \"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\", \"2.7\", \"2.7.13\"], \"server_version\": [\"5776-cb9e157\"], \"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": "1616053355668720",
"status": "COMPLETED",
"status_changed_ts": "1616057010800550",
"tags": [
"build_address:luci.chromium.ci/win-asan/17848",
"builder:win-asan",
"buildset:commit/git/96b58f4953c122313e67f600624b6b56ffeabd93",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/96b58f4953c122313e67f600624b6b56ffeabd93",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017869018623094976",
"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/8852438956445034992/+/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:525d1ac167d47a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1616057010847084",
"url": "https://ci.chromium.org/b/8852438956445034992",
"utcnow_ts": "1616057012162417"
}
}
[08:43:32.171] Invocation finished in 1h1m1.739482275s with status SUCCEEDED