[19:18:54.686] New invocation is queued and will start shortly
[19:18:55.814] Starting the invocation (attempt 1)
[19:18:55.846] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:18:55.846] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:18:55.846] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023895201423106480",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3c8956c58490a0c6807f3a4cdbd4377e73983f8d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023895201423106480",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/3c8956c58490a0c6807f3a4cdbd4377e73983f8d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c8956c58490a0c6807f3a4cdbd4377e73983f8d",
"gitiles_ref:refs/heads/master"
]
}
[19:18:57.112] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610306335985888",
"id": "8858465139396336880",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3c8956c58490a0c6807f3a4cdbd4377e73983f8d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610306336876964",
"tags": [
"build_address:luci.chromium.ci/win-asan/16742",
"builder:win-asan",
"buildset:commit/git/3c8956c58490a0c6807f3a4cdbd4377e73983f8d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c8956c58490a0c6807f3a4cdbd4377e73983f8d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023895201423106480",
"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/8858465139396336880/+/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": "1610306336877279",
"url": "https://ci.chromium.org/b/8858465139396336880",
"utcnow_ts": "1610306337099200"
}
}
[19:18:57.112] Task URL: https://ci.chromium.org/b/8858465139396336880
[19:18:57.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:2:0) after 1m0s
[19:18:59.186] Received PubSub notification, asking Buildbucket for the build status
[19:18:59.270] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:57.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:2:0)
[19:19:57.128] Timer tick, asking Buildbucket for the build status
[19:19:57.408] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:57.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:4:0) after 1m0s
[19:20:57.473] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:4:0)
[19:20:57.473] Timer tick, asking Buildbucket for the build status
[19:20:57.598] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:57.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:5:0) after 1m0s
[19:21:57.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:5:0)
[19:21:57.617] Timer tick, asking Buildbucket for the build status
[19:21:57.793] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:57.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:6:0) after 1m0s
[19:22:57.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:6:0)
[19:22:57.801] Timer tick, asking Buildbucket for the build status
[19:22:57.920] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:57.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:7:0) after 1m0s
[19:23:57.943] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:7:0)
[19:23:57.943] Timer tick, asking Buildbucket for the build status
[19:23:58.062] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:58.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:8:0) after 1m0s
[19:24:58.083] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:8:0)
[19:24:58.083] Timer tick, asking Buildbucket for the build status
[19:24:58.312] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:58.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:9:0) after 1m0s
[19:25:58.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:9:0)
[19:25:58.352] Timer tick, asking Buildbucket for the build status
[19:25:58.407] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:58.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:10:0) after 1m0s
[19:26:58.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:10:0)
[19:26:58.428] Timer tick, asking Buildbucket for the build status
[19:26:58.589] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:58.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:11:0) after 1m0s
[19:27:58.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:11:0)
[19:27:58.605] Timer tick, asking Buildbucket for the build status
[19:27:58.715] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:58.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:12:0) after 1m0s
[19:28:58.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:12:0)
[19:28:58.789] Timer tick, asking Buildbucket for the build status
[19:28:58.924] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:58.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:13:0) after 1m0s
[19:29:58.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:13:0)
[19:29:58.995] Timer tick, asking Buildbucket for the build status
[19:29:59.135] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:59.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:14:0) after 1m0s
[19:30:59.158] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:14:0)
[19:30:59.158] Timer tick, asking Buildbucket for the build status
[19:30:59.601] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:59.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:15:0) after 1m0s
[19:31:59.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:15:0)
[19:31:59.757] Timer tick, asking Buildbucket for the build status
[19:31:59.871] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:59.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:16:0) after 1m0s
[19:32:59.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:16:0)
[19:32:59.854] Timer tick, asking Buildbucket for the build status
[19:33:00.273] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:00.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:17:0) after 1m0s
[19:34:00.423] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:17:0)
[19:34:00.423] Timer tick, asking Buildbucket for the build status
[19:34:00.685] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:00.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:18:0) after 1m0s
[19:35:01.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:18:0)
[19:35:01.166] Timer tick, asking Buildbucket for the build status
[19:35:01.302] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:01.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:19:0) after 1m0s
[19:36:02.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:19:0)
[19:36:02.124] Timer tick, asking Buildbucket for the build status
[19:36:02.226] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:02.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:20:0) after 1m0s
[19:37:02.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:20:0)
[19:37:02.322] Timer tick, asking Buildbucket for the build status
[19:37:02.508] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:02.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:21:0) after 1m0s
[19:38:02.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:21:0)
[19:38:02.617] Timer tick, asking Buildbucket for the build status
[19:38:03.289] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:03.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:22:0) after 1m0s
[19:39:03.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:22:0)
[19:39:03.388] Timer tick, asking Buildbucket for the build status
[19:39:03.557] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:03.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:23:0) after 1m0s
[19:40:03.596] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:23:0)
[19:40:03.596] Timer tick, asking Buildbucket for the build status
[19:40:04.013] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:04.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:24:0) after 1m0s
[19:41:04.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:24:0)
[19:41:04.051] Timer tick, asking Buildbucket for the build status
[19:41:04.602] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:04.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:25:0) after 1m0s
[19:42:04.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:25:0)
[19:42:04.709] Timer tick, asking Buildbucket for the build status
[19:42:04.825] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:04.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:26:0) after 1m0s
[19:43:04.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:26:0)
[19:43:04.849] Timer tick, asking Buildbucket for the build status
[19:43:05.033] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:05.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:27:0) after 1m0s
[19:44:05.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:27:0)
[19:44:05.046] Timer tick, asking Buildbucket for the build status
[19:44:07.483] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:07.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:28:0) after 1m0s
[19:45:07.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:28:0)
[19:45:07.569] Timer tick, asking Buildbucket for the build status
[19:45:07.720] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:07.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:29:0) after 1m0s
[19:46:07.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:29:0)
[19:46:07.778] Timer tick, asking Buildbucket for the build status
[19:46:07.904] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:07.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:30:0) after 1m0s
[19:47:07.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:30:0)
[19:47:07.915] Timer tick, asking Buildbucket for the build status
[19:47:07.983] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:07.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:31:0) after 1m0s
[19:48:08.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:31:0)
[19:48:08.077] Timer tick, asking Buildbucket for the build status
[19:48:08.228] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:08.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:32:0) after 1m0s
[19:49:08.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:32:0)
[19:49:08.247] Timer tick, asking Buildbucket for the build status
[19:49:08.375] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:08.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:33:0) after 1m0s
[19:50:08.443] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:33:0)
[19:50:08.443] Timer tick, asking Buildbucket for the build status
[19:50:08.598] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:08.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:34:0) after 1m0s
[19:51:08.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:34:0)
[19:51:08.588] Timer tick, asking Buildbucket for the build status
[19:51:08.723] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:08.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:35:0) after 1m0s
[19:52:08.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:35:0)
[19:52:08.718] Timer tick, asking Buildbucket for the build status
[19:52:08.848] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:08.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:36:0) after 1m0s
[19:53:08.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:36:0)
[19:53:08.848] Timer tick, asking Buildbucket for the build status
[19:53:08.916] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:08.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:37:0) after 1m0s
[19:54:09.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:37:0)
[19:54:09.012] Timer tick, asking Buildbucket for the build status
[19:54:09.143] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:09.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:38:0) after 1m0s
[19:55:09.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:38:0)
[19:55:09.203] Timer tick, asking Buildbucket for the build status
[19:55:09.797] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:09.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:39:0) after 1m0s
[19:56:09.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:39:0)
[19:56:09.771] Timer tick, asking Buildbucket for the build status
[19:56:09.861] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:09.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:40:0) after 1m0s
[19:57:09.908] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:40:0)
[19:57:09.908] Timer tick, asking Buildbucket for the build status
[19:57:09.969] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:09.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:41:0) after 1m0s
[19:58:10.093] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:41:0)
[19:58:10.094] Timer tick, asking Buildbucket for the build status
[19:58:10.164] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:10.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:42:0) after 1m0s
[19:59:10.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:42:0)
[19:59:10.229] Timer tick, asking Buildbucket for the build status
[19:59:10.361] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:10.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:43:0) after 1m0s
[20:00:10.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:43:0)
[20:00:10.504] Timer tick, asking Buildbucket for the build status
[20:00:10.643] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:10.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:44:0) after 1m0s
[20:01:10.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:44:0)
[20:01:10.802] Timer tick, asking Buildbucket for the build status
[20:01:10.923] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:10.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:45:0) after 1m0s
[20:02:11.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:45:0)
[20:02:11.152] Timer tick, asking Buildbucket for the build status
[20:02:11.547] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:11.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:46:0) after 1m0s
[20:03:11.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:46:0)
[20:03:11.643] Timer tick, asking Buildbucket for the build status
[20:03:11.729] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:11.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:47:0) after 1m0s
[20:04:11.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:47:0)
[20:04:11.933] Timer tick, asking Buildbucket for the build status
[20:04:12.062] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:12.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:48:0) after 1m0s
[20:05:12.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:48:0)
[20:05:12.284] Timer tick, asking Buildbucket for the build status
[20:05:12.454] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:12.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:49:0) after 1m0s
[20:06:12.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:49:0)
[20:06:12.665] Timer tick, asking Buildbucket for the build status
[20:06:12.736] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:12.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:50:0) after 1m0s
[20:07:12.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:50:0)
[20:07:12.954] Timer tick, asking Buildbucket for the build status
[20:07:13.385] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:13.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:51:0) after 1m0s
[20:08:13.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:51:0)
[20:08:13.607] Timer tick, asking Buildbucket for the build status
[20:08:13.678] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:13.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:52:0) after 1m0s
[20:09:13.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:52:0)
[20:09:13.788] Timer tick, asking Buildbucket for the build status
[20:09:13.906] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:13.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:53:0) after 1m0s
[20:10:14.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:53:0)
[20:10:14.118] Timer tick, asking Buildbucket for the build status
[20:10:14.196] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:14.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:54:0) after 1m0s
[20:11:14.474] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:54:0)
[20:11:14.474] Timer tick, asking Buildbucket for the build status
[20:11:14.551] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:14.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:55:0) after 1m0s
[20:12:14.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:55:0)
[20:12:14.783] Timer tick, asking Buildbucket for the build status
[20:12:14.968] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:14.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:56:0) after 1m0s
[20:13:15.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:56:0)
[20:13:15.498] Timer tick, asking Buildbucket for the build status
[20:13:15.614] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:15.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:57:0) after 1m0s
[20:14:15.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:57:0)
[20:14:15.748] Timer tick, asking Buildbucket for the build status
[20:14:15.897] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:15.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:58:0) after 1m0s
[20:15:18.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:58:0)
[20:15:18.712] Timer tick, asking Buildbucket for the build status
[20:15:18.835] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:18.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:59:0) after 1m0s
[20:16:18.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:59:0)
[20:16:18.888] Timer tick, asking Buildbucket for the build status
[20:16:18.968] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:18.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:60:0) after 1m0s
[20:17:18.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:60:0)
[20:17:18.984] Timer tick, asking Buildbucket for the build status
[20:17:19.066] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:19.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:61:0) after 1m0s
[20:18:19.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:61:0)
[20:18:19.435] Timer tick, asking Buildbucket for the build status
[20:18:19.510] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:19.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:62:0) after 1m0s
[20:19:19.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:62:0)
[20:19:19.676] Timer tick, asking Buildbucket for the build status
[20:19:19.794] Build 8858465139396336880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:19.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023895201423106480:63:0) after 1m0s
[20:19:59.200] Received PubSub notification, asking Buildbucket for the build status
[20:19:59.281] Build 8858465139396336880: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:19:59.281] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610309998017275",
"created_by": "project:chromium",
"created_ts": "1610306335985888",
"id": "8858465139396336880",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3c8956c58490a0c6807f3a4cdbd4377e73983f8d\"}}",
"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\": \"3c8956c58490a0c6807f3a4cdbd4377e73983f8d\", \"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-unho\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16742, \"clang_revision\": \"llvmorg-12-init-15676-g5c951623-1\", \"got_angle_revision\": \"0b90eef09f607336330f318bc8e65fa981634160\", \"got_dawn_revision\": \"3a0405597374c9ae12fb93e33aac35826455a958\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"3c8956c58490a0c6807f3a4cdbd4377e73983f8d\", \"got_revision_cp\": \"refs/heads/master@{#841893}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"348ba200aa29d5b3a36d696d48d8f619e055219f\", \"got_v8_revision\": \"5d60a181de0863d8d00ae42ec0ee3521f51b501a\", \"got_v8_revision_cp\": \"refs/heads/8.9.229@{#1}\", \"got_webrtc_revision\": \"42082f9045e74e65b9e3a66b51093e0dc2f9375a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32929}\", \"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\": \"3c8956c58490a0c6807f3a4cdbd4377e73983f8d\", \"swarm_hashes_refs/heads/master(at){#841893}_without_patch\": {\"absl_hardening_tests\": \"263a2bb1f83e7e3466c0b288cc1d2659da464fa3\", \"accessibility_unittests\": \"22e63dbc06b80829b08339357908a88b47d8b2bc\", \"angle_unittests\": \"915f84af2f71a9f12f648dfed609c440ebc1b97d\", \"app_shell_unittests\": \"81e844bd893bc49b7223db52cf80e476c9772c07\", \"aura_unittests\": \"e7c657ed2f822c94759f3f3220989f08a37e7b14\", \"base_unittests\": \"918d47eab7f5258d7f810806a622148e4af87a6f\", \"base_util_unittests\": \"cf51485a34fa795fc8724b63d7ab7d89d13eb817\", \"blink_common_unittests\": \"4098acdaf50f26d7f789483c5abef48ea04769e1\", \"blink_fuzzer_unittests\": \"4d8e38bce18eff602181e555343cb275798fab78\", \"blink_heap_unittests\": \"8b1c6479e24c9f637651e5fd20bddbaa93d41ec7\", \"blink_platform_unittests\": \"687b9c5dc5c43d0183e44ba930c0a2e01c6b4653\", \"blink_unittests\": \"60c3df9e19a9fbca2c9c3971f932da39f74dcb44\", \"boringssl_crypto_tests\": \"7ce3b570ffdbdf77e800d94b7e4eb4f2fc2e6dce\", \"boringssl_ssl_tests\": \"b7fa7f4d2c184bb8778f54111768d535187d006b\", \"browser_switcher_bho_unittests\": \"13203f7ff96092eaa32a638298cc9cc849de9e64\", \"browser_tests\": \"173394f8ce2ea7a376dcf50d9f8b5a26cf994fe1\", \"capture_unittests\": \"78f645a379f0a02b20573485c2a686724e46905d\", \"cast_unittests\": \"a30e8f3633bd6d5445285429447dfaa3aea8013a\", \"cc_unittests\": \"b31b94bae5c62bb629793f6080d79d6974b5f5f2\", \"chrome_app_unittests\": \"322ddb420170a644eea94c8cb7c25e611b207132\", \"chrome_cleaner_unittests\": \"832091c5fcc578f40cb1a2540b843107d410e264\", \"chrome_elf_unittests\": \"e4b8310bcdee741e6992c880e1be76ef96a5e55a\", \"chromedriver_unittests\": \"185cb985a490d8706992614ad14609d60e3573ef\", \"components_browsertests\": \"191136d9748bbc4eaed67a5b7541519f1905146d\", \"components_unittests\": \"41ba99d8b58c08ddcd29a5357b4b3264bf6d1012\", \"compositor_unittests\": \"a1e3a5a6f886d42471ea08751d5d18bf65b2197f\", \"content_browsertests\": \"8131bc3eb08194dda23aab703345de7149ab6909\", \"content_unittests\": \"44066705654fa88d29e87756b73c675211f569fd\", \"courgette_unittests\": \"4187f2d13b5f861ae1d1b51514badda04ade0ed8\", \"crashpad_tests\": \"d502bcec8b6a598327c5385e6bf6e118c8f779ef\", \"cronet_tests\": \"7123ae3277f1a37c864790af33dbf24eb59c08ff\", \"cronet_unittests\": \"ab57de9555d3e19d003406e7ebd8f41135b19331\", \"crypto_unittests\": \"57026856aad1fbb82ab97a70a6fb43468277bc09\", \"delayloads_unittests\": \"5c806533a0bd7982073f8fd613bb4db35d557d04\", \"device_unittests\": \"860a9ff806b73caf9e5261aee5f3c33fde67b623\", \"display_unittests\": \"dd7e5dd916b3ef27809f0746165e0592f55716c4\", \"elevation_service_unittests\": \"be568a09642e4aab2e5fa587ac05c8af9820223d\", \"events_unittests\": \"b78b3a7d3588cf785bde3f545e923b3f1364725b\", \"extensions_browsertests\": \"b5dce389cd7914031a5eab1551fe69295347cb4a\", \"extensions_unittests\": \"d442b64bc5d7a3c0bc24441ec74d203275d2db18\", \"filesystem_service_unittests\": \"1ca0ea8d55c20beb3ae6b4e48ab09f3ec1e2a651\", \"gcm_unit_tests\": \"225f2c5c516974e59c73bee1002927c678d3098a\", \"gcp_unittests\": \"4e40844ef845891373d6f35175b40165894072f9\", \"gfx_unittests\": \"c06da246fde7da2022dd44d204724b7946bbc048\", \"gin_unittests\": \"3b7aebbd9e419f002a6b10290f3aa8332baf4c19\", \"google_apis_unittests\": \"7df4e22be522699236c530aa8e1135c178f9b9c7\", \"gpu_unittests\": \"90349a780ca4fad596a8539a90be6fb289f4f1d3\", \"gwp_asan_unittests\": \"c532943201f95285ffff105b15098c14aba5a9a0\", \"headless_browsertests\": \"7e45cad243399d7a6302920cb57bac3ddf98ea2a\", \"headless_unittests\": \"b5f70f626c253e8febc3c6768232c3ad254054f0\", \"install_static_unittests\": \"665d59413c0859a7b535b75b6a70438b5a201644\", \"installer_util_unittests\": \"0116409f581bfebb01833656a4dd2b6f1bc3af58\", \"interactive_ui_tests\": \"ba242b6faa118a39ee422611d75798e86480d989\", \"ipc_tests\": \"23db75503de726520d1edf35673649aacf8bda5d\", \"jingle_unittests\": \"5ca18edcf3fd130c4a160b9c288141b34d0d1224\", \"latency_unittests\": \"9e3c872a9769cab94a1285954e55c1b5cd3715f7\", \"libjingle_xmpp_unittests\": \"951a8696c0ce92c1a9bfffb15adccd6391095199\", \"liburlpattern_unittests\": \"c873b6028142a0327d2a6b995de111159dbf54df\", \"media_blink_unittests\": \"7572c2222494885fe81b79fe3376c76885efc1b5\", \"media_unittests\": \"e87b4bf19578bbb70ce45dba081b7b5d898c107f\", \"message_center_unittests\": \"0c9e033e8077e7a89778f3383d075d68704744cb\", \"midi_unittests\": \"f63929d9edcc17808621281e80048a4248ec1008\", \"mojo_core_unittests\": \"61fdd7110655a1bd4e8e3a7b4d4a9238db7eb6bc\", \"mojo_unittests\": \"8744c043471ab521b3f923714e4dbc1db231f7d3\", \"nacl_loader_unittests\": \"be7d0a28943de31da5e008157794abaccef4c6f7\", \"native_theme_unittests\": \"2fc380bcfc887940a07369c0a0c73b93d909b739\", \"net_unittests\": \"22d1bfd47a1b93d0fb3a120a133c4485f1380572\", \"notification_helper_unittests\": \"1d0e87fcfd659b3827c6f924c9172476dc2f1d8a\", \"pdf_unittests\": \"8beebbc03de566701163d0c948cb21a0a336df0d\", \"perfetto_unittests\": \"d956693d0cab28655fdd0ccff1cd2d952921a9bb\", \"ppapi_unittests\": \"c38c67b9caed5536fc22f751aa029afe80b1a8bc\", \"printing_unittests\": \"56500350115fbfde2decb89bb9b7ad90d6ea0b73\", \"remoting_unittests\": \"5cf504f0a06ac69951c95e1b213a1edf2155f617\", \"sbox_integration_tests\": \"1638ef3ea4e52bbafba3ba62b0e5fc8c708a9cfa\", \"sbox_unittests\": \"327163edbf124562c39110e07862fa88299ac951\", \"sbox_validation_tests\": \"7af29d15a8af9a0045e2da0349b0d275ba013016\", \"service_manager_unittests\": \"aaa77c679c7c70aee9d8de2aad75fa7302a1ad72\", \"services_unittests\": \"66ed7ce13d0b95e13e7d75d8693c7d9c3e689bae\", \"setup_unittests\": \"70410d503fd5341ca3adb137b1ca847fe8807ca0\", \"shell_dialogs_unittests\": \"3827543ad809aa901c2b18b7317260e3e9c7c289\", \"skia_unittests\": \"49c8b39eb161cd8d97e6a6093f4ad8bfb7f1da36\", \"snapshot_unittests\": \"64307f82b740f93dcde314f6f2c273b0643b6390\", \"sql_unittests\": \"b5c57c6691b922b73882152601541fcd9b1a411d\", \"storage_unittests\": \"654941301ad7ec4a3aa9d12a00cf6fb7053e67c2\", \"sync_integration_tests\": \"13260bd1a5a4cd59acdf725e696038dc3cc1b537\", \"traffic_annotation_auditor_unittests\": \"e5fe4906589f5ffed060838cb5fdca5f283d8abc\", \"ui_base_unittests\": \"d95d2242c117b7a33f8bc3574138d0156b8a7dd1\", \"ui_touch_selection_unittests\": \"edb2f367eb0a8b630d74fe0b5d1dba6ef036a43d\", \"unit_tests\": \"c7b1c2236c20cf215112b0cf369d9c6291fc1172\", \"updater_tests\": \"60306827fb9fff85453df3179241e2667ba3afb7\", \"url_unittests\": \"7b7cadd7054df84738e2d3945782ccccdb27787f\", \"views_unittests\": \"db5a536e938b7dec01a048d5ca29e09c5aa813da\", \"viz_unittests\": \"2b0ad241aac4443ec5fcaf82decc0b2e34f432c6\", \"vr_common_unittests\": \"9e2e78a9c8cda2117122985e81a053a9b038928f\", \"vr_pixeltests\": \"6d39624ef778168d0aa58674ca593f190507331c\", \"weblayer_browsertests\": \"dafe71548fdd6db86e64690b7f342d9df45aed84\", \"weblayer_unittests\": \"af881d5f545d5f64b2dc2d51741a7b550b2cda11\", \"wm_unittests\": \"d25eb458a82f2652ec6ab3dc7235787cd4b5e122\", \"wtf_unittests\": \"8c570e4471cf120905789ab40b1f50fc8172e0a1\", \"zlib_unittests\": \"830d0834bbb812f50e90c5b7c59d5ad437289cf9\", \"zucchini_unittests\": \"00ebe21a752a5d428de2a77cdb979ead2f61ca14\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"builder_f77c1c08af9d0a4522ad60c9181aa925a8ae87fd7ec8443f284fb40f3b6bc27b_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-unho\"], \"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-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610306338622546",
"status": "COMPLETED",
"status_changed_ts": "1610309998702646",
"tags": [
"build_address:luci.chromium.ci/win-asan/16742",
"builder:win-asan",
"buildset:commit/git/3c8956c58490a0c6807f3a4cdbd4377e73983f8d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c8956c58490a0c6807f3a4cdbd4377e73983f8d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023895201423106480",
"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/8858465139396336880/+/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:51068e41877f7d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1610309998721508",
"url": "https://ci.chromium.org/b/8858465139396336880",
"utcnow_ts": "1610309999271973"
}
}
[20:19:59.281] Invocation finished in 1h1m4.612030339s with status SUCCEEDED