[12:26:28.748] New invocation is queued and will start shortly
[12:26:29.977] Starting the invocation (attempt 1)
[12:26:30.053] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:26:30.053] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:26:30.053] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029175773470060576",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"804f0bbd48a33b2666c2bb13a329be3414b3104a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029175773470060576",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/804f0bbd48a33b2666c2bb13a329be3414b3104a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/804f0bbd48a33b2666c2bb13a329be3414b3104a",
"gitiles_ref:refs/heads/master"
]
}
[12:26:31.466] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605270390283244",
"id": "8863745711197094032",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"804f0bbd48a33b2666c2bb13a329be3414b3104a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605270391344465",
"tags": [
"build_address:luci.chromium.ci/win-asan/15619",
"builder:win-asan",
"buildset:commit/git/804f0bbd48a33b2666c2bb13a329be3414b3104a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/804f0bbd48a33b2666c2bb13a329be3414b3104a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029175773470060576",
"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/8863745711197094032/+/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": "1605270391344577",
"url": "https://ci.chromium.org/b/8863745711197094032",
"utcnow_ts": "1605270391454775"
}
}
[12:26:31.466] Task URL: https://ci.chromium.org/b/8863745711197094032
[12:26:31.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:2:0) after 1m0s
[12:27:31.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:2:0)
[12:27:31.528] Timer tick, asking Buildbucket for the build status
[12:27:31.629] Build 8863745711197094032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:27:31.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:3:0) after 1m0s
[12:28:09.614] Received PubSub notification, asking Buildbucket for the build status
[12:28:09.809] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:31.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:3:0)
[12:28:31.779] Timer tick, asking Buildbucket for the build status
[12:28:31.883] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:31.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:5:0) after 1m0s
[12:29:32.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:5:0)
[12:29:32.133] Timer tick, asking Buildbucket for the build status
[12:29:32.276] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:32.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:6:0) after 1m0s
[12:30:32.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:6:0)
[12:30:32.673] Timer tick, asking Buildbucket for the build status
[12:30:33.437] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:33.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:7:0) after 1m0s
[12:31:33.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:7:0)
[12:31:33.491] Timer tick, asking Buildbucket for the build status
[12:31:34.277] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:34.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:8:0) after 1m0s
[12:32:34.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:8:0)
[12:32:34.432] Timer tick, asking Buildbucket for the build status
[12:32:34.816] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:34.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:9:0) after 1m0s
[12:33:34.924] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:9:0)
[12:33:34.924] Timer tick, asking Buildbucket for the build status
[12:33:35.008] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:35.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:10:0) after 1m0s
[12:34:35.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:10:0)
[12:34:35.168] Timer tick, asking Buildbucket for the build status
[12:34:35.411] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:35.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:11:0) after 1m0s
[12:35:35.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:11:0)
[12:35:35.513] Timer tick, asking Buildbucket for the build status
[12:35:35.738] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:35.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:12:0) after 1m0s
[12:36:35.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:12:0)
[12:36:35.780] Timer tick, asking Buildbucket for the build status
[12:36:36.139] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:36.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:13:0) after 1m0s
[12:37:36.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:13:0)
[12:37:36.414] Timer tick, asking Buildbucket for the build status
[12:37:36.596] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:36.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:14:0) after 1m0s
[12:38:36.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:14:0)
[12:38:36.954] Timer tick, asking Buildbucket for the build status
[12:38:37.223] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:37.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:15:0) after 1m0s
[12:39:37.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:15:0)
[12:39:37.322] Timer tick, asking Buildbucket for the build status
[12:39:37.403] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:37.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:16:0) after 1m0s
[12:40:37.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:16:0)
[12:40:37.497] Timer tick, asking Buildbucket for the build status
[12:40:37.638] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:37.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:17:0) after 1m0s
[12:41:37.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:17:0)
[12:41:37.727] Timer tick, asking Buildbucket for the build status
[12:41:38.122] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:38.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:18:0) after 1m0s
[12:42:38.191] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:18:0)
[12:42:38.191] Timer tick, asking Buildbucket for the build status
[12:42:38.279] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:38.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:19:0) after 1m0s
[12:43:38.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:19:0)
[12:43:38.316] Timer tick, asking Buildbucket for the build status
[12:43:38.683] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:38.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:20:0) after 1m0s
[12:44:38.834] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:20:0)
[12:44:38.834] Timer tick, asking Buildbucket for the build status
[12:44:39.222] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:39.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:21:0) after 1m0s
[12:45:39.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:21:0)
[12:45:39.328] Timer tick, asking Buildbucket for the build status
[12:45:41.667] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:41.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:22:0) after 1m0s
[12:46:41.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:22:0)
[12:46:41.718] Timer tick, asking Buildbucket for the build status
[12:46:41.840] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:41.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:23:0) after 1m0s
[12:47:41.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:23:0)
[12:47:41.870] Timer tick, asking Buildbucket for the build status
[12:47:41.977] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:41.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:24:0) after 1m0s
[12:48:42.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:24:0)
[12:48:42.044] Timer tick, asking Buildbucket for the build status
[12:48:42.403] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:42.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:25:0) after 1m0s
[12:49:42.412] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:25:0)
[12:49:42.412] Timer tick, asking Buildbucket for the build status
[12:49:42.515] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:42.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:26:0) after 1m0s
[12:50:42.642] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:26:0)
[12:50:42.642] Timer tick, asking Buildbucket for the build status
[12:50:42.932] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:42.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:27:0) after 1m0s
[12:51:42.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:27:0)
[12:51:42.969] Timer tick, asking Buildbucket for the build status
[12:51:43.099] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:43.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:28:0) after 1m0s
[12:52:43.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:28:0)
[12:52:43.159] Timer tick, asking Buildbucket for the build status
[12:52:43.323] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:43.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:29:0) after 1m0s
[12:53:43.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:29:0)
[12:53:43.351] Timer tick, asking Buildbucket for the build status
[12:53:43.745] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:43.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:30:0) after 1m0s
[12:54:43.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:30:0)
[12:54:43.805] Timer tick, asking Buildbucket for the build status
[12:54:44.015] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:44.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:31:0) after 1m0s
[12:55:44.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:31:0)
[12:55:44.056] Timer tick, asking Buildbucket for the build status
[12:55:44.435] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:44.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:32:0) after 1m0s
[12:56:44.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:32:0)
[12:56:44.438] Timer tick, asking Buildbucket for the build status
[12:56:44.633] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:44.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:33:0) after 1m0s
[12:57:44.650] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:33:0)
[12:57:44.650] Timer tick, asking Buildbucket for the build status
[12:57:44.766] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:44.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:34:0) after 1m0s
[12:58:44.862] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:34:0)
[12:58:44.862] Timer tick, asking Buildbucket for the build status
[12:58:45.357] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:45.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:35:0) after 1m0s
[12:59:45.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:35:0)
[12:59:45.422] Timer tick, asking Buildbucket for the build status
[12:59:45.552] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:45.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:36:0) after 1m0s
[13:00:45.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:36:0)
[13:00:45.592] Timer tick, asking Buildbucket for the build status
[13:00:45.818] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:45.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:37:0) after 1m0s
[13:01:45.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:37:0)
[13:01:45.849] Timer tick, asking Buildbucket for the build status
[13:01:46.085] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:46.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:38:0) after 1m0s
[13:02:46.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:38:0)
[13:02:46.131] Timer tick, asking Buildbucket for the build status
[13:02:46.780] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:46.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:39:0) after 1m0s
[13:03:46.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:39:0)
[13:03:46.831] Timer tick, asking Buildbucket for the build status
[13:03:46.990] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:46.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:40:0) after 1m0s
[13:04:47.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:40:0)
[13:04:47.035] Timer tick, asking Buildbucket for the build status
[13:04:47.109] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:47.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:41:0) after 1m0s
[13:05:47.163] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:41:0)
[13:05:47.163] Timer tick, asking Buildbucket for the build status
[13:05:48.776] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:48.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:42:0) after 1m0s
[13:06:48.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:42:0)
[13:06:48.843] Timer tick, asking Buildbucket for the build status
[13:06:49.299] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:49.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:43:0) after 1m0s
[13:07:49.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:43:0)
[13:07:49.428] Timer tick, asking Buildbucket for the build status
[13:07:49.597] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:49.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:44:0) after 1m0s
[13:08:49.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:44:0)
[13:08:49.624] Timer tick, asking Buildbucket for the build status
[13:08:52.035] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:52.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:45:0) after 1m0s
[13:09:52.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:45:0)
[13:09:52.076] Timer tick, asking Buildbucket for the build status
[13:09:52.957] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:52.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:46:0) after 1m0s
[13:10:52.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:46:0)
[13:10:52.974] Timer tick, asking Buildbucket for the build status
[13:10:53.163] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:53.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:47:0) after 1m0s
[13:11:53.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:47:0)
[13:11:53.217] Timer tick, asking Buildbucket for the build status
[13:11:53.364] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:53.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:48:0) after 1m0s
[13:12:53.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:48:0)
[13:12:53.348] Timer tick, asking Buildbucket for the build status
[13:12:53.426] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:53.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:49:0) after 1m0s
[13:13:53.431] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:49:0)
[13:13:53.431] Timer tick, asking Buildbucket for the build status
[13:13:53.607] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:53.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:50:0) after 1m0s
[13:14:53.650] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:50:0)
[13:14:53.650] Timer tick, asking Buildbucket for the build status
[13:14:53.821] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:53.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:51:0) after 1m0s
[13:15:53.864] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:51:0)
[13:15:53.864] Timer tick, asking Buildbucket for the build status
[13:15:53.971] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:53.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:52:0) after 1m0s
[13:16:53.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:52:0)
[13:16:53.948] Timer tick, asking Buildbucket for the build status
[13:16:54.054] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:54.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:53:0) after 1m0s
[13:17:54.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:53:0)
[13:17:54.037] Timer tick, asking Buildbucket for the build status
[13:17:54.173] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:54.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:54:0) after 1m0s
[13:18:54.221] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:54:0)
[13:18:54.221] Timer tick, asking Buildbucket for the build status
[13:18:54.391] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:54.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:55:0) after 1m0s
[13:19:54.468] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:55:0)
[13:19:54.468] Timer tick, asking Buildbucket for the build status
[13:19:54.549] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:54.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:56:0) after 1m0s
[13:20:54.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:56:0)
[13:20:54.607] Timer tick, asking Buildbucket for the build status
[13:20:54.831] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:54.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:57:0) after 1m0s
[13:21:54.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:57:0)
[13:21:54.886] Timer tick, asking Buildbucket for the build status
[13:21:55.058] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:55.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:58:0) after 1m0s
[13:22:55.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:58:0)
[13:22:55.122] Timer tick, asking Buildbucket for the build status
[13:22:55.238] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:55.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:59:0) after 1m0s
[13:23:55.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:59:0)
[13:23:55.251] Timer tick, asking Buildbucket for the build status
[13:23:55.334] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:55.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:60:0) after 1m0s
[13:24:55.431] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:60:0)
[13:24:55.431] Timer tick, asking Buildbucket for the build status
[13:24:55.525] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:55.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:61:0) after 1m0s
[13:25:55.559] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:61:0)
[13:25:55.559] Timer tick, asking Buildbucket for the build status
[13:25:55.700] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:55.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:62:0) after 1m0s
[13:26:55.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:62:0)
[13:26:55.717] Timer tick, asking Buildbucket for the build status
[13:26:56.003] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:56.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:63:0) after 1m0s
[13:27:56.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:63:0)
[13:27:56.039] Timer tick, asking Buildbucket for the build status
[13:27:56.199] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:56.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:64:0) after 1m0s
[13:28:56.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:64:0)
[13:28:56.260] Timer tick, asking Buildbucket for the build status
[13:28:56.349] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:56.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:65:0) after 1m0s
[13:29:56.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:65:0)
[13:29:56.400] Timer tick, asking Buildbucket for the build status
[13:29:56.491] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:56.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:66:0) after 1m0s
[13:30:56.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:66:0)
[13:30:56.498] Timer tick, asking Buildbucket for the build status
[13:30:56.592] Build 8863745711197094032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:56.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029175773470060576:67:0) after 1m0s
[13:31:43.136] Received PubSub notification, asking Buildbucket for the build status
[13:31:43.358] Build 8863745711197094032: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:31:43.358] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605274028717231",
"created_by": "project:chromium",
"created_ts": "1605270390283244",
"id": "8863745711197094032",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"804f0bbd48a33b2666c2bb13a329be3414b3104a\"}}",
"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\": \"804f0bbd48a33b2666c2bb13a329be3414b3104a\", \"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-0-4yp8\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15619, \"clang_revision\": \"llvmorg-12-init-11462-g418f18c6-1\", \"got_angle_revision\": \"67eb26054d84566d477cd05ab0b4dcccc8a8ca48\", \"got_dawn_revision\": \"b6f4d53126d3f0ad522df5fc77da4ac919ea43f6\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"804f0bbd48a33b2666c2bb13a329be3414b3104a\", \"got_revision_cp\": \"refs/heads/master@{#827230}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"e4c1a25cc6797a47043fc415c53b8edcd7b3e37e\", \"got_v8_revision\": \"2dbcdc105b963ee2501c82139eef7e0603977ff0\", \"got_v8_revision_cp\": \"refs/heads/8.8.278@{#1}\", \"got_webrtc_revision\": \"90e958b4cd20f13a1f2615c2f75dd3ab5172afb8\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32601}\", \"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\": \"804f0bbd48a33b2666c2bb13a329be3414b3104a\", \"swarm_hashes_refs/heads/master(at){#827230}_without_patch\": {\"absl_hardening_tests\": \"031b581f9f62ab6e39aa40acfcdd05d5a4dd8752\", \"accessibility_unittests\": \"35d76f922db49ad916b9c96dba988c25fd9ebe3f\", \"angle_unittests\": \"e459e3f8e370c8225829762c28f0015701ffd36a\", \"app_shell_unittests\": \"4d2238e1250d205f01dbcd6b7056ff214aaf2ff5\", \"aura_unittests\": \"5e271bf6b13acf544897b119fcaa729aee8872fc\", \"base_unittests\": \"7a3e8793387468a4027b9c51d75d5d801be7acdf\", \"base_util_unittests\": \"503da7bc173204b02efe0cc1b121911ac8fffbf8\", \"blink_common_unittests\": \"d9bbe8ea84d24eb3cdaefad2d8e1991bf008e7b8\", \"blink_fuzzer_unittests\": \"8288e16bc7a6f8b028361851fe4a978f4d15e62c\", \"blink_heap_unittests\": \"acbb735fa3b70bce37b18b6f8c3467e3dfe2a1d8\", \"blink_platform_unittests\": \"9420d3da5fce32093e7e05f4d684bcfcb8dee214\", \"blink_unittests\": \"6a7c73b0a3f9f9c541418626e11b1755b33db015\", \"boringssl_crypto_tests\": \"ac15cfe4d1b96a4a72fd92ec7cfa49affa5e406b\", \"boringssl_ssl_tests\": \"099d047a4106e5c0b66709bbd5f6f35799cf0138\", \"browser_switcher_bho_unittests\": \"fe7d476ad95c3abb4f69a1c3a7249f39009686be\", \"browser_tests\": \"15ae9d1f18f274951e51fc45959b87e708e1aca1\", \"capture_unittests\": \"599111fdcb5923ed6976570400b1bf873ae9a00a\", \"cast_unittests\": \"3240b0eeeb258d0559d8fa900d2d8de3dbf542f1\", \"cc_unittests\": \"c9a9633746d41c07787d7e6dd395ed247d19939e\", \"chrome_app_unittests\": \"b95ee23b164b794e085535e03017f02b40ede686\", \"chrome_cleaner_unittests\": \"dbe07adb4f5affaa7036e4029876e77c29ec834c\", \"chrome_elf_unittests\": \"80e9280e0c9d4950596ffb53d71852f891b5c223\", \"chromedriver_unittests\": \"7075bf7ecfbefcb3c3b2d7154e3b8733ee80dba4\", \"components_browsertests\": \"0b3fc1ca75683eea6f4aa3b48bcc1ce981b9f0a3\", \"components_unittests\": \"9832ab6ac5a07a67be3a0be59752c219965547b8\", \"compositor_unittests\": \"4138e555602b2771c3e7f71b43aa6a118a41d2bc\", \"content_browsertests\": \"babf590b60a4c7d19e372394ac954e486bf4df7a\", \"content_unittests\": \"8a27875d4b6c4e51c83aef63f4f8cfb2292c7d70\", \"courgette_unittests\": \"b04521e2e94868a8b3f3431869bf8bc2b7495408\", \"crashpad_tests\": \"b08770a44ac12ad4434e4597f20fc0b8673b93e9\", \"cronet_tests\": \"0c5547e17ed71fac121dde993dbde5e02d5a3272\", \"cronet_unittests\": \"ed36ae82ca7d5a097593c241370a71202d0897a8\", \"crypto_unittests\": \"4da431705650e44d3b1fcdbc19937b729a246ee1\", \"delayloads_unittests\": \"a238adca49db59611dc2fd884742fccf534f29f3\", \"device_unittests\": \"395bda5ddccf2e220614e40920a0e8f13d459469\", \"display_unittests\": \"f85207d9aa2b849fe5bd00f55f4c6e11c61474de\", \"elevation_service_unittests\": \"c186d1fd4fb0f98d1d6d56b481c4899307563a13\", \"events_unittests\": \"bb116ff9ad4bd23c593bb1d7bc96b2b8a2e05c05\", \"extensions_browsertests\": \"25899e0fac842df75e6c791b1ff407bb55b358eb\", \"extensions_unittests\": \"a32ee74240f935e8be7638847c0559505c5a9983\", \"filesystem_service_unittests\": \"865a64b166cf27e685e525fc74983ce5040f5f53\", \"gcm_unit_tests\": \"cb0969a6495ad672b19be30d66c1ed1872e399d5\", \"gcp_unittests\": \"d4c444e962647006be50eac39234f9e813ea2cd4\", \"gfx_unittests\": \"575f4c408f910d820ebdb087c4d19daeb08b959d\", \"gin_unittests\": \"ebda4a4ca629f0a82cdcec9e49dc4cad813af1a0\", \"google_apis_unittests\": \"58c581184518d51c00c0dfabc93ad40e72f22958\", \"gpu_unittests\": \"8b11d8a3ed42246c4d6497f841aa87bb2efda69d\", \"gwp_asan_unittests\": \"d828b6174303603c8848c3a387004ea004d799ac\", \"headless_browsertests\": \"c1cf0b0c2d7c46d2c2d70b3ffe8f472fa14b8b7d\", \"headless_unittests\": \"0f6c1e326d7074cb1c4f5872af437392cb3e0137\", \"install_static_unittests\": \"2f762cc22b08dc104572434bbd7143193595a152\", \"installer_util_unittests\": \"997e0eb374d2782382953f31561532e5a9158580\", \"interactive_ui_tests\": \"19e9425da9cf94901b4c5e4bccae72467ed5b6a0\", \"ipc_tests\": \"3c41e09d1fd2259161f7452378c52b73da7a29ed\", \"jingle_unittests\": \"0d77d55452e6eea6e1cf147b1ebda391089fcf79\", \"latency_unittests\": \"6ef20ea6bf4549a551d0c77101bcba5e09581196\", \"libjingle_xmpp_unittests\": \"dd8ab94a5fcc31c3e5f2647b9e5f01ace65cc3ce\", \"liburlpattern_unittests\": \"65f8b6ffd6be9d3f00a9d5b069e8fa287f241fb9\", \"media_blink_unittests\": \"22295ea6e3c0c00052a43c27cd64d87b926e28f2\", \"media_unittests\": \"a27135333313242b9c9cb9222c7c458384cc5ac0\", \"message_center_unittests\": \"46a62c3c8e1da3d635045248c1b447c6c845bef1\", \"midi_unittests\": \"d42f26529293d95dab73145264ce4ec07a858255\", \"mojo_core_unittests\": \"9058aeeb717b2d20c935cc1fefbb1d6ea4b535dc\", \"mojo_unittests\": \"ae66f2656bc77cb3c01ff4493be906b18acce994\", \"nacl_loader_unittests\": \"3908129e11dbb14cac60b31f4de408bb8413c72d\", \"native_theme_unittests\": \"7744f3e58902f9238eb2815db0d61025fbb19e85\", \"net_unittests\": \"7e6845fefda4245d7b0da354350163706bbe5030\", \"notification_helper_unittests\": \"f975b79b512ad4e2b651df19ac55bee6051de760\", \"pdf_unittests\": \"14e950992b0a488a18fa5747a5156da7aa2af99a\", \"perfetto_unittests\": \"554bb6bcb9f48fb76dff5a30928aa18df83e1ddb\", \"ppapi_unittests\": \"f18be66fe654373f176016720606fdb39f692ffe\", \"printing_unittests\": \"b783986dbcc46c61bfef6eb594a9a5ebb7443ee5\", \"remoting_unittests\": \"a63e4e8f1d785907e52bae21a0153e2a0d901cf1\", \"sbox_integration_tests\": \"71c79e991385d9e7a82f039e66306e665a693dc9\", \"sbox_unittests\": \"5ae9cbbe6dc490466cb431c509e90948e2526e6c\", \"sbox_validation_tests\": \"46fb30a077c05168e78fe84cddaa7e264e1fc1ee\", \"service_manager_unittests\": \"37ec2e0321fd84f67cd8d5b838ffbc255a30b867\", \"services_unittests\": \"53e61b2090d84c902ddba028b6924edcd6d240cf\", \"setup_unittests\": \"f3bd9b7a5f153c29fdd0d903774282d739dd5e60\", \"shell_dialogs_unittests\": \"96f9555efe7cbf8219f8cb20130135765931bcc7\", \"skia_unittests\": \"b30f8ac9db22f1d601dd593133a255a227433306\", \"snapshot_unittests\": \"1090e4fcff79f4ab9d660a5f5ce4d52290ce7619\", \"sql_unittests\": \"9ff8e221ccd55dc73ef0caa50965ca08d9ca9616\", \"storage_unittests\": \"fc617b94ef4d8208cabe9431a5847f9317887534\", \"sync_integration_tests\": \"9687bda1e2a435e30bde398e9907fd9ce8d6e3dc\", \"traffic_annotation_auditor_unittests\": \"2c4c312a279e7bc767e4d28ed34160f53e4fd5fb\", \"ui_base_unittests\": \"a0499144231053efd330f3a9156d839e69e7b1d3\", \"ui_touch_selection_unittests\": \"e4da270eaaa0e0acd6f7e104001f583fb89b0813\", \"unit_tests\": \"65ed036d37f07b8e99062f5bb92910ee9970b00a\", \"updater_tests\": \"3b678be57d737261579d6d30e9e669e7e4c9ef69\", \"url_unittests\": \"85220e5402da1bf426dec10eff1883a6d54cdcbd\", \"views_unittests\": \"301f911719c80e68c0aea028f98f82d60e7a6083\", \"viz_unittests\": \"522e21f65ddc7b146b7c0603bcbf3ccfdeed5c3f\", \"vr_common_unittests\": \"ff56f7a0a3086727bb90ec2e94219efb3c71ce40\", \"vr_pixeltests\": \"c15275a3ac94389d8fbb2f53bc7affd7d56c7174\", \"weblayer_browsertests\": \"d71935060322bf4a6f53836dbaddd0fef5be0b6d\", \"weblayer_unittests\": \"d55fa9e6fd1d91be5848ea8de6d4858f24bf7b03\", \"wm_unittests\": \"41b2356ee4aec265328b8b60d4484ed96516fb9c\", \"wtf_unittests\": \"88a89efa0211acda4c9b2c0560dd043cdba089db\", \"zlib_unittests\": \"aeeb15751296f5729ae84d73ad460690e0d2bea2\", \"zucchini_unittests\": \"39e63085384cc9ff2e3072fdc21f67b7f08c6080\"}}, \"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-0-4yp8\"], \"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\": [\"5626-39642e9\"], \"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": "1605270422126043",
"status": "COMPLETED",
"status_changed_ts": "1605274300884686",
"tags": [
"build_address:luci.chromium.ci/win-asan/15619",
"builder:win-asan",
"buildset:commit/git/804f0bbd48a33b2666c2bb13a329be3414b3104a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/804f0bbd48a33b2666c2bb13a329be3414b3104a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029175773470060576",
"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/8863745711197094032/+/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:4fda63d3179f1110",
"user_agent:luci-scheduler"
],
"updated_ts": "1605274300998064",
"url": "https://ci.chromium.org/b/8863745711197094032",
"utcnow_ts": "1605274303334988"
}
}
[13:31:43.358] Invocation finished in 1h5m14.625589823s with status SUCCEEDED