[20:20:23.458] New invocation is queued and will start shortly
[20:20:24.553] Starting the invocation (attempt 1)
[20:20:24.595] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:20:24.599] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:20:24.599] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022169991104783760",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"243cd9ce359093db9c3006264a5fb8a18fad04a5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022169991104783760",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/243cd9ce359093db9c3006264a5fb8a18fad04a5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/243cd9ce359093db9c3006264a5fb8a18fad04a5",
"gitiles_ref:refs/heads/master"
]
}
[20:20:26.314] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611951624769690",
"id": "8856739929064160400",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"243cd9ce359093db9c3006264a5fb8a18fad04a5\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611951625971281",
"tags": [
"build_address:luci.chromium.ci/win-asan/17073",
"builder:win-asan",
"buildset:commit/git/243cd9ce359093db9c3006264a5fb8a18fad04a5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/243cd9ce359093db9c3006264a5fb8a18fad04a5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022169991104783760",
"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/8856739929064160400/+/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": "1611951625971464",
"url": "https://ci.chromium.org/b/8856739929064160400",
"utcnow_ts": "1611951626299477"
}
}
[20:20:26.314] Task URL: https://ci.chromium.org/b/8856739929064160400
[20:20:26.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:2:0) after 1m0s
[20:20:29.087] Received PubSub notification, asking Buildbucket for the build status
[20:20:29.240] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:26.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:2:0)
[20:21:26.361] Timer tick, asking Buildbucket for the build status
[20:21:26.710] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:26.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:4:0) after 1m0s
[20:22:26.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:4:0)
[20:22:26.780] Timer tick, asking Buildbucket for the build status
[20:22:26.962] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:26.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:5:0) after 1m0s
[20:23:26.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:5:0)
[20:23:26.972] Timer tick, asking Buildbucket for the build status
[20:23:27.117] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:27.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:6:0) after 1m0s
[20:24:27.137] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:6:0)
[20:24:27.137] Timer tick, asking Buildbucket for the build status
[20:24:27.228] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:27.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:7:0) after 1m0s
[20:25:27.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:7:0)
[20:25:27.265] Timer tick, asking Buildbucket for the build status
[20:25:27.371] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:27.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:8:0) after 1m0s
[20:26:27.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:8:0)
[20:26:27.499] Timer tick, asking Buildbucket for the build status
[20:26:27.647] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:27.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:9:0) after 1m0s
[20:27:27.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:9:0)
[20:27:27.662] Timer tick, asking Buildbucket for the build status
[20:27:27.780] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:27.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:10:0) after 1m0s
[20:28:28.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:10:0)
[20:28:28.059] Timer tick, asking Buildbucket for the build status
[20:28:28.347] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:28.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:11:0) after 1m0s
[20:29:28.417] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:11:0)
[20:29:28.417] Timer tick, asking Buildbucket for the build status
[20:29:28.522] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:28.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:12:0) after 1m0s
[20:30:28.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:12:0)
[20:30:28.673] Timer tick, asking Buildbucket for the build status
[20:30:28.899] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:28.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:13:0) after 1m0s
[20:31:28.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:13:0)
[20:31:28.936] Timer tick, asking Buildbucket for the build status
[20:31:29.140] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:29.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:14:0) after 1m0s
[20:32:29.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:14:0)
[20:32:29.164] Timer tick, asking Buildbucket for the build status
[20:32:29.297] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:29.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:15:0) after 1m0s
[20:33:29.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:15:0)
[20:33:29.382] Timer tick, asking Buildbucket for the build status
[20:33:29.494] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:29.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:16:0) after 1m0s
[20:34:29.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:16:0)
[20:34:29.509] Timer tick, asking Buildbucket for the build status
[20:34:29.807] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:29.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:17:0) after 1m0s
[20:35:29.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:17:0)
[20:35:29.830] Timer tick, asking Buildbucket for the build status
[20:35:29.925] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:29.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:18:0) after 1m0s
[20:36:29.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:18:0)
[20:36:29.952] Timer tick, asking Buildbucket for the build status
[20:36:30.041] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:30.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:19:0) after 1m0s
[20:37:30.167] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:19:0)
[20:37:30.167] Timer tick, asking Buildbucket for the build status
[20:37:30.360] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:30.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:20:0) after 1m0s
[20:38:30.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:20:0)
[20:38:30.472] Timer tick, asking Buildbucket for the build status
[20:38:30.703] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:30.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:21:0) after 1m0s
[20:39:30.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:21:0)
[20:39:30.722] Timer tick, asking Buildbucket for the build status
[20:39:31.359] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:31.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:22:0) after 1m0s
[20:40:31.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:22:0)
[20:40:31.389] Timer tick, asking Buildbucket for the build status
[20:40:31.539] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:31.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:23:0) after 1m0s
[20:41:31.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:23:0)
[20:41:31.660] Timer tick, asking Buildbucket for the build status
[20:41:31.823] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:31.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:24:0) after 1m0s
[20:42:31.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:24:0)
[20:42:31.880] Timer tick, asking Buildbucket for the build status
[20:42:32.004] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:32.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:25:0) after 1m0s
[20:43:32.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:25:0)
[20:43:32.087] Timer tick, asking Buildbucket for the build status
[20:43:32.329] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:32.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:26:0) after 1m0s
[20:44:32.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:26:0)
[20:44:32.396] Timer tick, asking Buildbucket for the build status
[20:44:32.506] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:32.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:27:0) after 1m0s
[20:45:33.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:27:0)
[20:45:33.134] Timer tick, asking Buildbucket for the build status
[20:45:33.347] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:33.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:28:0) after 1m0s
[20:46:33.464] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:28:0)
[20:46:33.464] Timer tick, asking Buildbucket for the build status
[20:46:33.586] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:33.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:29:0) after 1m0s
[20:47:33.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:29:0)
[20:47:33.899] Timer tick, asking Buildbucket for the build status
[20:47:34.645] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:34.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:30:0) after 1m0s
[20:48:34.807] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:30:0)
[20:48:34.807] Timer tick, asking Buildbucket for the build status
[20:48:35.024] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:35.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:31:0) after 1m0s
[20:49:35.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:31:0)
[20:49:35.152] Timer tick, asking Buildbucket for the build status
[20:49:35.240] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:35.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:32:0) after 1m0s
[20:50:35.391] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:32:0)
[20:50:35.391] Timer tick, asking Buildbucket for the build status
[20:50:35.515] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:35.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:33:0) after 1m0s
[20:51:35.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:33:0)
[20:51:35.544] Timer tick, asking Buildbucket for the build status
[20:51:35.665] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:35.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:34:0) after 1m0s
[20:52:35.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:34:0)
[20:52:35.759] Timer tick, asking Buildbucket for the build status
[20:52:36.108] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:36.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:35:0) after 1m0s
[20:53:36.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:35:0)
[20:53:36.258] Timer tick, asking Buildbucket for the build status
[20:53:36.339] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:36.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:36:0) after 1m0s
[20:54:36.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:36:0)
[20:54:36.410] Timer tick, asking Buildbucket for the build status
[20:54:37.120] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:37.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:37:0) after 1m0s
[20:55:37.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:37:0)
[20:55:37.227] Timer tick, asking Buildbucket for the build status
[20:55:37.846] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:37.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:38:0) after 1m0s
[20:56:38.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:38:0)
[20:56:38.022] Timer tick, asking Buildbucket for the build status
[20:56:38.501] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:38.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:39:0) after 1m0s
[20:57:38.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:39:0)
[20:57:38.522] Timer tick, asking Buildbucket for the build status
[20:57:38.638] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:38.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:40:0) after 1m0s
[20:58:38.730] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:40:0)
[20:58:38.730] Timer tick, asking Buildbucket for the build status
[20:58:38.968] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:38.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:41:0) after 1m0s
[20:59:39.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:41:0)
[20:59:39.057] Timer tick, asking Buildbucket for the build status
[20:59:39.194] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:39.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:42:0) after 1m0s
[21:00:39.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:42:0)
[21:00:39.248] Timer tick, asking Buildbucket for the build status
[21:00:40.306] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:40.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:43:0) after 1m0s
[21:01:40.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:43:0)
[21:01:40.294] Timer tick, asking Buildbucket for the build status
[21:01:40.862] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:40.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:44:0) after 1m0s
[21:02:40.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:44:0)
[21:02:40.897] Timer tick, asking Buildbucket for the build status
[21:02:41.085] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:41.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:45:0) after 1m0s
[21:03:41.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:45:0)
[21:03:41.187] Timer tick, asking Buildbucket for the build status
[21:03:41.320] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:41.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:46:0) after 1m0s
[21:04:41.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:46:0)
[21:04:41.359] Timer tick, asking Buildbucket for the build status
[21:04:41.495] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:41.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:47:0) after 1m0s
[21:05:41.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:47:0)
[21:05:41.527] Timer tick, asking Buildbucket for the build status
[21:05:41.659] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:41.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:48:0) after 1m0s
[21:06:41.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:48:0)
[21:06:41.680] Timer tick, asking Buildbucket for the build status
[21:06:41.817] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:41.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:49:0) after 1m0s
[21:07:41.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:49:0)
[21:07:41.898] Timer tick, asking Buildbucket for the build status
[21:07:42.211] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:42.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:50:0) after 1m0s
[21:08:42.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:50:0)
[21:08:42.298] Timer tick, asking Buildbucket for the build status
[21:08:42.487] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:42.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:51:0) after 1m0s
[21:09:42.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:51:0)
[21:09:42.565] Timer tick, asking Buildbucket for the build status
[21:09:42.736] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:42.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:52:0) after 1m0s
[21:10:42.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:52:0)
[21:10:42.757] Timer tick, asking Buildbucket for the build status
[21:10:42.981] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:42.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:53:0) after 1m0s
[21:11:42.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:53:0)
[21:11:42.993] Timer tick, asking Buildbucket for the build status
[21:11:43.398] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:43.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:54:0) after 1m0s
[21:12:43.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:54:0)
[21:12:43.494] Timer tick, asking Buildbucket for the build status
[21:12:43.951] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:43.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:55:0) after 1m0s
[21:13:43.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:55:0)
[21:13:43.979] Timer tick, asking Buildbucket for the build status
[21:13:44.242] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:44.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:56:0) after 1m0s
[21:14:44.307] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:56:0)
[21:14:44.307] Timer tick, asking Buildbucket for the build status
[21:14:44.917] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:44.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:57:0) after 1m0s
[21:15:44.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:57:0)
[21:15:44.984] Timer tick, asking Buildbucket for the build status
[21:15:45.609] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:45.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:58:0) after 1m0s
[21:16:45.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:58:0)
[21:16:45.585] Timer tick, asking Buildbucket for the build status
[21:16:45.684] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:45.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:59:0) after 1m0s
[21:17:45.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:59:0)
[21:17:45.745] Timer tick, asking Buildbucket for the build status
[21:17:46.008] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:46.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:60:0) after 1m0s
[21:18:46.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:60:0)
[21:18:46.056] Timer tick, asking Buildbucket for the build status
[21:18:46.161] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:46.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:61:0) after 1m0s
[21:19:46.174] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:61:0)
[21:19:46.174] Timer tick, asking Buildbucket for the build status
[21:19:46.319] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:46.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:62:0) after 1m0s
[21:20:46.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:62:0)
[21:20:46.413] Timer tick, asking Buildbucket for the build status
[21:20:46.555] Build 8856739929064160400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:46.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022169991104783760:63:0) after 1m0s
[21:20:47.844] Received PubSub notification, asking Buildbucket for the build status
[21:20:47.909] Build 8856739929064160400: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:20:47.909] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611955247206164",
"created_by": "project:chromium",
"created_ts": "1611951624769690",
"id": "8856739929064160400",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"243cd9ce359093db9c3006264a5fb8a18fad04a5\"}}",
"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\": \"243cd9ce359093db9c3006264a5fb8a18fad04a5\", \"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-7-2fjn\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17073, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"a29ef6129501a14774387f23574d30459b70f9e6\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"243cd9ce359093db9c3006264a5fb8a18fad04a5\", \"got_revision_cp\": \"refs/heads/master@{#848672}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"89b433d7c3defd04809fba9deb6f8885160044a4\", \"got_v8_revision_cp\": \"refs/heads/9.0.100@{#1}\", \"got_webrtc_revision\": \"54b925cfc2cd7c2f8f83e8e484f281aa87b92dfc\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33090}\", \"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\": \"243cd9ce359093db9c3006264a5fb8a18fad04a5\", \"swarm_hashes_refs/heads/master(at){#848672}_without_patch\": {\"absl_hardening_tests\": \"6c533ba35005b3337b20ca71654ffa1369bed92f\", \"accessibility_unittests\": \"a66677a6cb1054eb51b8b458cf6b980218c4197d\", \"angle_unittests\": \"fa37ca5f00a58a3e60c07cfa7b81b2eaaeb58882\", \"app_shell_unittests\": \"7ab5c5a348cf66bc347b41e9559d495a4f1f3844\", \"aura_unittests\": \"f9a2345be2da492df1d5e994fc7c9e42c5b1a053\", \"base_unittests\": \"f77de53d51ec8400a7f9d92167613c8800b97b9e\", \"base_util_unittests\": \"ccbf57b5232b08e17519ac552f0a9baee02c78c4\", \"blink_common_unittests\": \"461cbf92c1af04e803c88c0eaf655571cff93eac\", \"blink_fuzzer_unittests\": \"cd2f87e3cb28176461b331b682633a4ff8360af5\", \"blink_heap_unittests\": \"c0885b44943e4274b9ac4fbd5f06addb63ffc5a8\", \"blink_platform_unittests\": \"53cf1e597886aea3150b95fbfcc91386b0616d88\", \"blink_unittests\": \"31dedc13d47a7d803fdd88f120d61b8505f9c890\", \"boringssl_crypto_tests\": \"a5c3aef699f93a5889da64eb5db6ad427f607807\", \"boringssl_ssl_tests\": \"36b9e9a7aec5b0ac4735484a48eff6ab3176712b\", \"browser_switcher_bho_unittests\": \"fc7eef071e102361d6cec93895901a9b6a16a9ad\", \"browser_tests\": \"ff41b7e6e835b7475a6c0421c36224443bd856b9\", \"capture_unittests\": \"41c25bf97f79935f34a781ce392164a7e358a2e7\", \"cast_unittests\": \"2e764f971900256a7b7fe80758f4caaf224be0aa\", \"cc_unittests\": \"39ff1fea4be3b3c52060a55e74d64de5e0201d40\", \"chrome_app_unittests\": \"f7fc95fc6a0e446e36124fd9d57a16e731067654\", \"chrome_cleaner_unittests\": \"851e7e3952b9baf24bda278bc3e0ac530a0e1245\", \"chrome_elf_unittests\": \"495366e8e7719eb1b523f3618fa02235741b43d6\", \"chromedriver_unittests\": \"580cd6f1fd8e41c64db3f1bfbd2d0c3ead2f82a2\", \"components_browsertests\": \"d651479a5fc91ac086938be22cbfd6464210b9ed\", \"components_unittests\": \"a6d70d9dfbc04fcbc7ecb7114f9d2bb60c8aeaec\", \"compositor_unittests\": \"4477cb9022e24c0811457fa223311dadfca9babb\", \"content_browsertests\": \"8a589f44f1e481fff776a508ea275ff0224b11ed\", \"content_unittests\": \"bc943e3457f299bf7cc4cac54dea99e3d2227ce2\", \"courgette_unittests\": \"996af8504b53234da19864755ce9f542e59af54e\", \"crashpad_tests\": \"08d588b57a6b26af540aad2c986a334acd873788\", \"cronet_tests\": \"6290103b84473777de6e8ecc8ec1e8966df8cce0\", \"cronet_unittests\": \"686686eab6b5d5f39f6786b7737a453e26f2984e\", \"crypto_unittests\": \"e2385e741058ada1cc80f6c201f8c358cedfbf4d\", \"delayloads_unittests\": \"e162b59babc18b602dd8c900be1f4c5fb6c368ac\", \"device_unittests\": \"5441b92361fc13d24ca5933e566dca5bf17403ca\", \"display_unittests\": \"dec5b2848cf3622d3864beae64d4f59fdaf7bfb2\", \"elevation_service_unittests\": \"8f0034a2002875b6d9ed02db6fa4473a9dc21b4c\", \"events_unittests\": \"bb5c535a107d24167e486538473659f05d4e3be2\", \"extensions_browsertests\": \"11f2b559bb905bd3ded1047976882c046e1f0356\", \"extensions_unittests\": \"1d01ef3c6475d4229b36a9efc2cd08b789159463\", \"filesystem_service_unittests\": \"7f940af0df8fe44a5e48256756ce7a1538a89340\", \"gcm_unit_tests\": \"feced4ad2eb64a34bb7c2ab30008d1f09ab7ebc9\", \"gcp_unittests\": \"c782f0c42d648892486308a469e0fe72d91574ea\", \"gfx_unittests\": \"f1fefb263361ebbfb27b69918b76c4e7f23bd9c4\", \"gin_unittests\": \"7705edaecd4b8f2fbe9e5756cd36f0342cf1bc87\", \"google_apis_unittests\": \"e9dc7695a726c0100cabeb7c05b073f93f653556\", \"gpu_unittests\": \"1aed117bd227f05455b94eac9e070fdeea3c42bf\", \"gwp_asan_unittests\": \"890ede2c1a0f335fa073453f5ae4f8f93d70781a\", \"headless_browsertests\": \"aec25bf000b93a14ccf2ef09c01bfb4ee0cf2aa5\", \"headless_unittests\": \"b6ceb76f781f28013e36f28c7a8896491e681759\", \"install_static_unittests\": \"262e60fe7d97e088ca2652ec5add71109fd4dcf0\", \"installer_util_unittests\": \"3cd199155a9d59367ec680515a8606f08a027e8f\", \"interactive_ui_tests\": \"f67d2ff575597ad2903590be7a9eff6d01bf8790\", \"ipc_tests\": \"df977ba8a006ac57149249b940f6c36580c84258\", \"jingle_unittests\": \"4029bfee1cf1331e1b7df726e931bc138b3dd719\", \"latency_unittests\": \"e753553c07bd6ffb9a646416d3b568a759140907\", \"libjingle_xmpp_unittests\": \"27913d88a118638319999d2d4872af9b6dcf16f6\", \"liburlpattern_unittests\": \"bec103d3c683b69ce5f8d43e5f1782ac1ba93505\", \"media_blink_unittests\": \"81024468ff2120674800d149782f4a3acc38ba9f\", \"media_unittests\": \"db88ef765b11cdd25fa54b13d83b8a63aa0ae311\", \"message_center_unittests\": \"b57491762612a51e1ce5b8b59fefa278ea85e191\", \"midi_unittests\": \"c355c495e87bd1858281347568e42b5d07928f92\", \"mojo_core_unittests\": \"71659d0f1dd4b6b78caf5af31519bd7fb0b3d106\", \"mojo_unittests\": \"3b413003a719a26d51ea50b53c794d801899d956\", \"nacl_loader_unittests\": \"5be1c65ffdebd8ff05d654ea21e8857c9f3c7431\", \"native_theme_unittests\": \"f7537d75413c255517f3ffa12356adcd1708bb3d\", \"net_unittests\": \"399edaaca76c94d44515cc1e4e44f74a91825985\", \"notification_helper_unittests\": \"4b5823e0b81613939cfb029dc10436467162a945\", \"pdf_unittests\": \"738c2be6dce469796c4bf2cce1d38b2806c84385\", \"perfetto_unittests\": \"837d06abe7979bb269756f6744025502f0952ce5\", \"ppapi_unittests\": \"e95eadb911dc7d81a3f494b04d2b0b5b0e65ea84\", \"printing_unittests\": \"a10c9989322d32dbb9be9534aaa6bfdbddd472b3\", \"remoting_unittests\": \"0d5f4a7385a571cbb0d02485821d443e9a343514\", \"sbox_integration_tests\": \"594b2df5ef27938e99e316820b877ec2fc906182\", \"sbox_unittests\": \"1528ca5308514242ce3424f23129c0d7a68362b1\", \"sbox_validation_tests\": \"ffe48aafdb66a4de59c4315e2f535e8c74b4c37f\", \"service_manager_unittests\": \"a33bbf519495e68496d266afb742286fec3fda9a\", \"services_unittests\": \"4f94f23a0f0310ef39c7a6597b92c850e4925d50\", \"setup_unittests\": \"641c350f741348da2cfd5af1b5bf91a9c7acf4b4\", \"shell_dialogs_unittests\": \"ba111b9b1afd9d1731185261e7d6d59dd4e3f172\", \"skia_unittests\": \"a06b97d768968b1079b756e0dc1e4ed889fd5a80\", \"snapshot_unittests\": \"0b6346e6d24f17b1eacdf009f1c2877c05e4bf72\", \"sql_unittests\": \"b1f52b0277d2fc56e2a22447820382e48b8a3ce5\", \"storage_unittests\": \"13d11926d421d07861767645ddd5f4d83e51ae59\", \"sync_integration_tests\": \"88030654f204b6bad8dcf9aee271a4c2c5ecf6ea\", \"traffic_annotation_auditor_unittests\": \"9cd6f5e5f7344c4720ba43b56f2d0adf75fc5836\", \"ui_base_unittests\": \"889c0c4f3256d9af9c2c3e713f4d702b3f1bd058\", \"ui_touch_selection_unittests\": \"b78939a7c3b12e33ba59c0abaf622dbf64311bd3\", \"unit_tests\": \"db7cf144290dc89545cc694a853eab24431b95f1\", \"updater_tests\": \"a775f9f9d4dacc1f13ef24c88a093ea1417e9739\", \"url_unittests\": \"a6870580c6dc61a8591af7724d4fd30923b006e7\", \"views_unittests\": \"66f9bcdbf587f813ac1886cda6de77c3e49dfa82\", \"viz_unittests\": \"039ece1cc731479fb5bc1f444aaa78e1f4fa91e0\", \"vr_common_unittests\": \"85325bc7d63736660e4f8d0fed3e1d99ad731914\", \"vr_pixeltests\": \"89d906f532ef876948e17682444049cfa861eb41\", \"weblayer_browsertests\": \"fa635848b5e2078107a15b93f270770cb436a1f5\", \"weblayer_unittests\": \"f7b768fa43d3a23ec09e71ad7c2b9f783ab75c5a\", \"wm_unittests\": \"1f326042011cf14fa1942ed251abe6ff66ed7863\", \"wtf_unittests\": \"2c5f7af5b5b502849e5df3255737b3b09770f010\", \"zlib_unittests\": \"ec596367e56fc4a6b3fcfee86c7a7c23c5f4846d\", \"zucchini_unittests\": \"805eeb8f0649de4541fd6c70b353a041c9bcf3fd\"}}, \"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-7-2fjn\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5673-2413400\"], \"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": "1611951627858557",
"status": "COMPLETED",
"status_changed_ts": "1611955247546471",
"tags": [
"build_address:luci.chromium.ci/win-asan/17073",
"builder:win-asan",
"buildset:commit/git/243cd9ce359093db9c3006264a5fb8a18fad04a5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/243cd9ce359093db9c3006264a5fb8a18fad04a5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022169991104783760",
"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/8856739929064160400/+/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:51689f60bc1ad310",
"user_agent:luci-scheduler"
],
"updated_ts": "1611955247552768",
"url": "https://ci.chromium.org/b/8856739929064160400",
"utcnow_ts": "1611955247901059"
}
}
[21:20:47.909] Invocation finished in 1h0m24.468872816s with status SUCCEEDED