[04:29:55.415] New invocation is queued and will start shortly
[04:29:56.566] Starting the invocation (attempt 1)
[04:29:56.605] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:29:56.605] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:29:56.605] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023769937768907536",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"bc3324be35b7c6ff2ba48984bf4c370e5072f413\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023769937768907536",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/bc3324be35b7c6ff2ba48984bf4c370e5072f413",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bc3324be35b7c6ff2ba48984bf4c370e5072f413",
"gitiles_ref:refs/heads/master"
]
}
[04:29:57.644] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610425796718606",
"id": "8858339875738403104",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bc3324be35b7c6ff2ba48984bf4c370e5072f413\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610425797522789",
"tags": [
"build_address:luci.chromium.ci/win-asan/16769",
"builder:win-asan",
"buildset:commit/git/bc3324be35b7c6ff2ba48984bf4c370e5072f413",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bc3324be35b7c6ff2ba48984bf4c370e5072f413",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023769937768907536",
"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/8858339875738403104/+/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": "1610425797522900",
"url": "https://ci.chromium.org/b/8858339875738403104",
"utcnow_ts": "1610425797634838"
}
}
[04:29:57.644] Task URL: https://ci.chromium.org/b/8858339875738403104
[04:29:57.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:2:0) after 1m0s
[04:30:03.928] Received PubSub notification, asking Buildbucket for the build status
[04:30:04.341] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:57.667] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:2:0)
[04:30:57.667] Timer tick, asking Buildbucket for the build status
[04:30:57.730] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:57.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:4:0) after 1m0s
[04:31:57.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:4:0)
[04:31:57.822] Timer tick, asking Buildbucket for the build status
[04:31:57.927] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:57.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:5:0) after 1m0s
[04:32:57.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:5:0)
[04:32:57.949] Timer tick, asking Buildbucket for the build status
[04:32:58.077] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:58.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:6:0) after 1m0s
[04:33:58.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:6:0)
[04:33:58.118] Timer tick, asking Buildbucket for the build status
[04:33:58.287] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:58.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:7:0) after 1m0s
[04:34:58.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:7:0)
[04:34:58.310] Timer tick, asking Buildbucket for the build status
[04:34:58.482] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:58.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:8:0) after 1m0s
[04:35:58.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:8:0)
[04:35:58.529] Timer tick, asking Buildbucket for the build status
[04:35:58.901] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:58.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:9:0) after 1m0s
[04:36:58.935] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:9:0)
[04:36:58.935] Timer tick, asking Buildbucket for the build status
[04:36:59.316] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:59.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:10:0) after 1m0s
[04:37:59.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:10:0)
[04:37:59.348] Timer tick, asking Buildbucket for the build status
[04:37:59.449] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:59.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:11:0) after 1m0s
[04:38:59.463] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:11:0)
[04:38:59.464] Timer tick, asking Buildbucket for the build status
[04:38:59.526] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:59.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:12:0) after 1m0s
[04:39:59.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:12:0)
[04:39:59.571] Timer tick, asking Buildbucket for the build status
[04:39:59.655] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:59.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:13:0) after 1m0s
[04:40:59.773] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:13:0)
[04:40:59.773] Timer tick, asking Buildbucket for the build status
[04:40:59.934] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:59.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:14:0) after 1m0s
[04:41:59.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:14:0)
[04:41:59.942] Timer tick, asking Buildbucket for the build status
[04:42:00.132] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:00.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:15:0) after 1m0s
[04:43:00.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:15:0)
[04:43:00.177] Timer tick, asking Buildbucket for the build status
[04:43:00.257] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:00.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:16:0) after 1m0s
[04:44:00.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:16:0)
[04:44:00.556] Timer tick, asking Buildbucket for the build status
[04:44:00.797] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:00.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:17:0) after 1m0s
[04:45:01.463] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:17:0)
[04:45:01.463] Timer tick, asking Buildbucket for the build status
[04:45:01.628] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:01.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:18:0) after 1m0s
[04:46:01.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:18:0)
[04:46:01.929] Timer tick, asking Buildbucket for the build status
[04:46:03.954] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:03.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:19:0) after 1m0s
[04:47:03.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:19:0)
[04:47:03.978] Timer tick, asking Buildbucket for the build status
[04:47:04.051] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:04.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:20:0) after 1m0s
[04:48:04.096] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:20:0)
[04:48:04.096] Timer tick, asking Buildbucket for the build status
[04:48:04.403] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:04.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:21:0) after 1m0s
[04:49:04.423] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:21:0)
[04:49:04.423] Timer tick, asking Buildbucket for the build status
[04:49:04.629] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:04.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:22:0) after 1m0s
[04:50:04.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:22:0)
[04:50:04.675] Timer tick, asking Buildbucket for the build status
[04:50:04.844] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:04.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:23:0) after 1m0s
[04:51:04.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:23:0)
[04:51:04.852] Timer tick, asking Buildbucket for the build status
[04:51:05.078] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:05.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:24:0) after 1m0s
[04:52:05.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:24:0)
[04:52:05.124] Timer tick, asking Buildbucket for the build status
[04:52:05.688] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:05.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:25:0) after 1m0s
[04:53:05.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:25:0)
[04:53:05.797] Timer tick, asking Buildbucket for the build status
[04:53:06.663] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:06.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:26:0) after 1m0s
[04:54:06.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:26:0)
[04:54:06.771] Timer tick, asking Buildbucket for the build status
[04:54:07.045] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:07.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:27:0) after 1m0s
[04:55:07.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:27:0)
[04:55:07.121] Timer tick, asking Buildbucket for the build status
[04:55:07.550] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:07.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:28:0) after 1m0s
[04:56:07.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:28:0)
[04:56:07.569] Timer tick, asking Buildbucket for the build status
[04:56:07.714] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:07.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:29:0) after 1m0s
[04:57:07.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:29:0)
[04:57:07.751] Timer tick, asking Buildbucket for the build status
[04:57:07.905] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:07.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:30:0) after 1m0s
[04:58:07.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:30:0)
[04:58:07.928] Timer tick, asking Buildbucket for the build status
[04:58:08.167] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:08.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:31:0) after 1m0s
[04:59:08.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:31:0)
[04:59:08.214] Timer tick, asking Buildbucket for the build status
[04:59:09.924] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:09.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:32:0) after 1m0s
[05:00:10.009] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:32:0)
[05:00:10.009] Timer tick, asking Buildbucket for the build status
[05:00:10.151] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:10.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:33:0) after 1m0s
[05:01:10.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:33:0)
[05:01:10.248] Timer tick, asking Buildbucket for the build status
[05:01:10.394] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:10.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:34:0) after 1m0s
[05:02:10.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:34:0)
[05:02:10.413] Timer tick, asking Buildbucket for the build status
[05:02:10.536] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:10.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:35:0) after 1m0s
[05:03:10.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:35:0)
[05:03:10.561] Timer tick, asking Buildbucket for the build status
[05:03:10.668] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:10.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:36:0) after 1m0s
[05:04:10.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:36:0)
[05:04:10.673] Timer tick, asking Buildbucket for the build status
[05:04:10.824] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:10.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:37:0) after 1m0s
[05:05:10.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:37:0)
[05:05:10.917] Timer tick, asking Buildbucket for the build status
[05:05:11.365] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:11.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:38:0) after 1m0s
[05:06:11.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:38:0)
[05:06:11.386] Timer tick, asking Buildbucket for the build status
[05:06:11.515] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:11.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:39:0) after 1m0s
[05:07:11.537] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:39:0)
[05:07:11.537] Timer tick, asking Buildbucket for the build status
[05:07:11.625] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:11.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:40:0) after 1m0s
[05:08:11.799] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:40:0)
[05:08:11.799] Timer tick, asking Buildbucket for the build status
[05:08:12.366] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:12.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:41:0) after 1m0s
[05:09:12.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:41:0)
[05:09:12.509] Timer tick, asking Buildbucket for the build status
[05:09:12.667] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:12.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:42:0) after 1m0s
[05:10:12.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:42:0)
[05:10:12.861] Timer tick, asking Buildbucket for the build status
[05:10:12.989] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:12.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:43:0) after 1m0s
[05:11:13.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:43:0)
[05:11:13.195] Timer tick, asking Buildbucket for the build status
[05:11:13.279] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:13.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:44:0) after 1m0s
[05:12:13.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:44:0)
[05:12:13.296] Timer tick, asking Buildbucket for the build status
[05:12:13.389] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:13.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:45:0) after 1m0s
[05:13:13.412] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:45:0)
[05:13:13.412] Timer tick, asking Buildbucket for the build status
[05:13:13.649] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:13.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:46:0) after 1m0s
[05:14:13.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:46:0)
[05:14:13.665] Timer tick, asking Buildbucket for the build status
[05:14:13.782] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:13.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:47:0) after 1m0s
[05:15:13.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:47:0)
[05:15:13.831] Timer tick, asking Buildbucket for the build status
[05:15:13.979] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:13.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:48:0) after 1m0s
[05:16:14.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:48:0)
[05:16:14.037] Timer tick, asking Buildbucket for the build status
[05:16:14.339] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:14.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:49:0) after 1m0s
[05:17:14.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:49:0)
[05:17:14.363] Timer tick, asking Buildbucket for the build status
[05:17:14.625] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:14.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:50:0) after 1m0s
[05:18:14.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:50:0)
[05:18:14.761] Timer tick, asking Buildbucket for the build status
[05:18:14.888] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:14.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:51:0) after 1m0s
[05:19:14.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:51:0)
[05:19:14.895] Timer tick, asking Buildbucket for the build status
[05:19:15.191] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:15.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:52:0) after 1m0s
[05:20:15.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:52:0)
[05:20:15.190] Timer tick, asking Buildbucket for the build status
[05:20:15.332] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:15.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:53:0) after 1m0s
[05:21:15.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:53:0)
[05:21:15.358] Timer tick, asking Buildbucket for the build status
[05:21:15.775] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:15.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:54:0) after 1m0s
[05:22:15.800] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:54:0)
[05:22:15.800] Timer tick, asking Buildbucket for the build status
[05:22:15.949] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:15.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:55:0) after 1m0s
[05:23:15.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:55:0)
[05:23:15.942] Timer tick, asking Buildbucket for the build status
[05:23:16.178] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:16.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:56:0) after 1m0s
[05:24:16.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:56:0)
[05:24:16.257] Timer tick, asking Buildbucket for the build status
[05:24:16.623] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:16.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:57:0) after 1m0s
[05:25:16.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:57:0)
[05:25:16.646] Timer tick, asking Buildbucket for the build status
[05:25:16.836] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:16.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:58:0) after 1m0s
[05:26:16.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:58:0)
[05:26:16.851] Timer tick, asking Buildbucket for the build status
[05:26:16.952] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:16.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:59:0) after 1m0s
[05:27:17.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:59:0)
[05:27:17.053] Timer tick, asking Buildbucket for the build status
[05:27:17.349] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:17.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:60:0) after 1m0s
[05:28:17.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:60:0)
[05:28:17.375] Timer tick, asking Buildbucket for the build status
[05:28:17.459] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:17.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:61:0) after 1m0s
[05:29:17.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:61:0)
[05:29:17.496] Timer tick, asking Buildbucket for the build status
[05:29:17.655] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:17.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:62:0) after 1m0s
[05:30:17.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:62:0)
[05:30:17.711] Timer tick, asking Buildbucket for the build status
[05:30:17.957] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:17.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:63:0) after 1m0s
[05:31:17.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:63:0)
[05:31:17.992] Timer tick, asking Buildbucket for the build status
[05:31:18.068] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:18.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:64:0) after 1m0s
[05:32:18.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:64:0)
[05:32:18.110] Timer tick, asking Buildbucket for the build status
[05:32:18.405] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:18.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:65:0) after 1m0s
[05:33:18.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:65:0)
[05:33:18.556] Timer tick, asking Buildbucket for the build status
[05:33:18.659] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:18.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:66:0) after 1m0s
[05:34:18.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:66:0)
[05:34:18.751] Timer tick, asking Buildbucket for the build status
[05:34:18.950] Build 8858339875738403104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:18.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023769937768907536:67:0) after 1m0s
[05:35:08.391] Received PubSub notification, asking Buildbucket for the build status
[05:35:08.656] Build 8858339875738403104: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:35:08.656] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610429706839846",
"created_by": "project:chromium",
"created_ts": "1610425796718606",
"id": "8858339875738403104",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bc3324be35b7c6ff2ba48984bf4c370e5072f413\"}}",
"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\": \"bc3324be35b7c6ff2ba48984bf4c370e5072f413\", \"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-us-central1-b-32-1-ervs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16769, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"de32c3d2de006a2c8d2acfa829634fcf2d1564ab\", \"got_dawn_revision\": \"ea26a8ce553fe2efa94830f3d7326072487bde3f\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"bc3324be35b7c6ff2ba48984bf4c370e5072f413\", \"got_revision_cp\": \"refs/heads/master@{#842370}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"3549479dc4ccd92ef3b9e179080dcec1a430b01d\", \"got_v8_revision\": \"43ed322b6a782285f8fcfbaadd3926c371c3a60b\", \"got_v8_revision_cp\": \"refs/heads/8.9.241@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bc3324be35b7c6ff2ba48984bf4c370e5072f413\", \"swarm_hashes_refs/heads/master(at){#842370}_without_patch\": {\"absl_hardening_tests\": \"39947578cad4b4944d238c2e290f470ecd715e06\", \"accessibility_unittests\": \"4bf9528bab2a0f8692c8cac53f006ae9a3a45734\", \"angle_unittests\": \"f5eb3d212383bffadfcc008c3362cae1e24ce389\", \"app_shell_unittests\": \"9f4ba8ad8827b32dbc3828fc9755fb6039899479\", \"aura_unittests\": \"e353f7656227117115ada785b0d0d1364b270fd2\", \"base_unittests\": \"a870141fecfddb6bf082f4e0a75db9e60061f6a5\", \"base_util_unittests\": \"800ba2cc0e6a277e72c249fe37a7d999f3929c7e\", \"blink_common_unittests\": \"13d2bc5a86e9dbdd73d1df0804fcc63d01795633\", \"blink_fuzzer_unittests\": \"0cd593f99f523249e3ee71893e2033f92a41c700\", \"blink_heap_unittests\": \"e3015ccbf98458acd0e94b579e5558344aa16328\", \"blink_platform_unittests\": \"5879e0ce44593749bbd0819cfd46068e05005e1f\", \"blink_unittests\": \"a2da93818766be8a8167f3bbecd01523afdf89f5\", \"boringssl_crypto_tests\": \"170dbbe5a69c12612507a65ff9eab6a6d9a94e6e\", \"boringssl_ssl_tests\": \"846d68e10ac80200edc8c8161b0f05e14a1cedb5\", \"browser_switcher_bho_unittests\": \"ebba3240b94c1ff09a05e3d559f51d8d76023665\", \"browser_tests\": \"b11d385ec36934b8b3d77cb171fad38adfcd68cd\", \"capture_unittests\": \"3045b8f25201f73eb158866c78a55872c622adbb\", \"cast_unittests\": \"b37164830008f328f8daa9404f9f6d3f1f8eb441\", \"cc_unittests\": \"fa0dc3b6531792d20e11e9176c2f02618e3c61f2\", \"chrome_app_unittests\": \"78f3c51f6dc2e722160f76395e123c7dcc68da8b\", \"chrome_cleaner_unittests\": \"ecdd754989daf95fb3fbd2d41847177e5acc48fe\", \"chrome_elf_unittests\": \"f7160c3cbbd8b0aa91fb96b7ae4aa6592576ae53\", \"chromedriver_unittests\": \"a33c90a9e047549434fd767b29c5255c4a4bb67f\", \"components_browsertests\": \"185d48a1c43a4a55a6e39fa4e756a43794bb8c16\", \"components_unittests\": \"849c737cbdb875376ad4ab706dbd3e497dd463c4\", \"compositor_unittests\": \"2bf14b8a6c2f84afddfa66bf99e50cdfa89b5af1\", \"content_browsertests\": \"f84ce7481c8f43f5f6efc08417452e0c29106244\", \"content_unittests\": \"ad0652dca50d6abd7bc67fcccbc3b32b79b1a5aa\", \"courgette_unittests\": \"762ca72eb01e26642a15fac137d2d1da13646abd\", \"crashpad_tests\": \"ab26af0352985d1a4f58c4afb2b8ba2026f2d6ec\", \"cronet_tests\": \"9bc6eaa48525f9d27cc3ed44686b8f663ffea037\", \"cronet_unittests\": \"f60e5861ebcea4e9ad889e56de0848a3b50c0687\", \"crypto_unittests\": \"a5fca417f6faef9bc3ccaee3d292f3e62a371e55\", \"delayloads_unittests\": \"c5c0a448fe563fb40fb371043bb81332779a82d3\", \"device_unittests\": \"613b5980f82575a67ee240084760ae8c23a15f6b\", \"display_unittests\": \"d30cfb787b99b8490be098c058f76de62558b567\", \"elevation_service_unittests\": \"b73691d6c2a1d5bf4facfaed7b876b7ad13fa8e0\", \"events_unittests\": \"6c6f5216de368a071d575971f87fa23f06964064\", \"extensions_browsertests\": \"728ffab362328045606fbc26cd34c1f25633ad75\", \"extensions_unittests\": \"f58992542318669f9835bb0a285b0f11b4f3b2d7\", \"filesystem_service_unittests\": \"149fded819ea8a085e99d2c39c5b0c0de7a7a7fa\", \"gcm_unit_tests\": \"8f836a10e6a3e40b7d2641c45dbdedf99a6a1449\", \"gcp_unittests\": \"712c6fc54b188a598620b0392cec7a6c01aaff29\", \"gfx_unittests\": \"1723364c44d4d24b114e36e4cfaf787df741ee2c\", \"gin_unittests\": \"26ffbf640a58915ed59565d5f0a01e5f55cc4392\", \"google_apis_unittests\": \"15a90aa250a429deff58705348e2881182d5053a\", \"gpu_unittests\": \"7dc7d5a4684cf9ee92572125753b8d29de9ba9f0\", \"gwp_asan_unittests\": \"b5335895f33b4abb0188adec793609987f65d04b\", \"headless_browsertests\": \"6d1df169c3352dfc0a1816b7ff902b0d0ac81fa5\", \"headless_unittests\": \"fdb8abed279fa17d2b7f438ed8d235839e32bc9a\", \"install_static_unittests\": \"9e64838934cecf142619169de6faa581ec4ded18\", \"installer_util_unittests\": \"df17b2e9d5868927e3f32d68a002a4758641ce72\", \"interactive_ui_tests\": \"04c05febf3cde40a56d505cce6ddb105e6697a0d\", \"ipc_tests\": \"86c7479f2d11d8da5e4bba64a751350491b3ccaf\", \"jingle_unittests\": \"bc78a7f70f693dd575d38634239a411ad31edd18\", \"latency_unittests\": \"ab2e440ae6974553fbe95be81d46ee5b90eaf5ea\", \"libjingle_xmpp_unittests\": \"52de022dc22a287bc1ed2839b2e23b879e0022ab\", \"liburlpattern_unittests\": \"75ba19a0dea304bd6eb561e6f0a96c023b8b2cce\", \"media_blink_unittests\": \"ff9ed6c1010b0407b7ca0c5ce799c56ca9f5c8d5\", \"media_unittests\": \"b6af8ae21ba9b644b5391305b7c3aa690519929a\", \"message_center_unittests\": \"75a2ae6efe33df1fb2f9389ef0a5cad5d9d33710\", \"midi_unittests\": \"f3b308cd75a8b85fac5f83f0fbc4ada6d7883933\", \"mojo_core_unittests\": \"fa9f3e30eb7f093b0e37f82e20b063d2c535648a\", \"mojo_unittests\": \"8fb59da59eb5f3fdd5714a169f277f9a76c1874e\", \"nacl_loader_unittests\": \"09f3e919b171864c1a35e963d5dc238c5a5d8e45\", \"native_theme_unittests\": \"7b54e6eaf0fc09f22ba69c41c9cbaf57dc73635f\", \"net_unittests\": \"d7262afb5142ebc1167e2927bb02d2f8bcb128ab\", \"notification_helper_unittests\": \"17ca7ee8ba2313147292d7db42bdc31963fc5f85\", \"pdf_unittests\": \"eb472423fb5ea7d7f81b9fb966182002d5fc62ed\", \"perfetto_unittests\": \"d742e30d9bd4c65c866853c91ac11c62feb159f3\", \"ppapi_unittests\": \"0b3131adccf737ec12ff782581b73d1ed0d0ba47\", \"printing_unittests\": \"b8620cbaf5d1abc47ce15e045abe28bfcd17c041\", \"remoting_unittests\": \"cb98a331c78dd377f49b2e179d5aaea57a3452bc\", \"sbox_integration_tests\": \"af187132fcc3e20595faa3ffd11695ff956baa5a\", \"sbox_unittests\": \"fdc8c01dae39594e6d62e1ebeff3ca88215207e0\", \"sbox_validation_tests\": \"c68bb41cf5722446eaf3229b3283b967ae6e7a82\", \"service_manager_unittests\": \"f0895428ff8d6d04d69be8565ac50b67976c5afe\", \"services_unittests\": \"82d3372156ec5f022c2dfc7fd6c30f623a5d9ad0\", \"setup_unittests\": \"9b2e2983b948a492aaa9ea88f681b8c5a1b047b1\", \"shell_dialogs_unittests\": \"3947e576a23ec8bff7ab293c099333c14612280b\", \"skia_unittests\": \"93a96fcb2c86887dc3314c0e2647cafc01f4228f\", \"snapshot_unittests\": \"35a989c725e9d5e00a391534ac7e0181bccdaa62\", \"sql_unittests\": \"574b2fae6b73b55f822c7ba09b50ee3cf35565db\", \"storage_unittests\": \"4f415c1edb02a3ab109d0fa4988dc738b7d8f32f\", \"sync_integration_tests\": \"7d91b7f90a9986b73027796db2f4e2ac8658bd0e\", \"traffic_annotation_auditor_unittests\": \"b17b353112748e4e6f24fe8631b52dffeb90082b\", \"ui_base_unittests\": \"847a07dde11f5d11e3a3d30542adbada13189461\", \"ui_touch_selection_unittests\": \"2b03e51b070864d1830eb6c33257aaadd0ae08d8\", \"unit_tests\": \"4ca357c73e05fc1a181672177d9f0ca0b02967f3\", \"updater_tests\": \"219809f00723b80f8e4a4c4da98de762f411756e\", \"url_unittests\": \"3cf39c8679f37c7a057460d382d26ee9a28b17a1\", \"views_unittests\": \"da536dec991257d07472274d43aa8890c76fc981\", \"viz_unittests\": \"2e8b9925d8b1ec7561375ea1837a816afcc276cc\", \"vr_common_unittests\": \"896ede693d28bffc59f6b3f79f6de264ab0c612d\", \"vr_pixeltests\": \"6304e0cd932353ce3d12aa1e90594f17ad4d91fd\", \"weblayer_browsertests\": \"d1e33c64ae4f95e666107e09b4fe3fa42190556d\", \"weblayer_unittests\": \"4fcd4fdc7df4419a152d1e9fe10e034d445f9096\", \"wm_unittests\": \"aebf97ddceb2394e068a8cbc9d83c0dfedf39ebb\", \"wtf_unittests\": \"c6e7b7242bd55d12b5bb791709efdf9bb854121c\", \"zlib_unittests\": \"d327ec5ddfe03de3724d7af6cbca38cb5bcad0de\", \"zucchini_unittests\": \"5b4a35d76a5156d73f0269d42d294a2eb211fcfd\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-ervs\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610425802883274",
"status": "COMPLETED",
"status_changed_ts": "1610429707477854",
"tags": [
"build_address:luci.chromium.ci/win-asan/16769",
"builder:win-asan",
"buildset:commit/git/bc3324be35b7c6ff2ba48984bf4c370e5072f413",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bc3324be35b7c6ff2ba48984bf4c370e5072f413",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023769937768907536",
"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/8858339875738403104/+/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:510dad1afe4b5110",
"user_agent:luci-scheduler"
],
"updated_ts": "1610429707498479",
"url": "https://ci.chromium.org/b/8858339875738403104",
"utcnow_ts": "1610429708643477"
}
}
[05:35:08.656] Invocation finished in 1h5m13.258030878s with status SUCCEEDED