[01:47:38.074] New invocation is queued and will start shortly
[01:47:39.205] Starting the invocation (attempt 1)
[01:47:39.257] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:47:39.257] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:47:39.257] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023508357213583184",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"25baaa4b7d2b1a3315bab27765c29ac43a8b96ec\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023508357213583184",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/25baaa4b7d2b1a3315bab27765c29ac43a8b96ec",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/25baaa4b7d2b1a3315bab27765c29ac43a8b96ec",
"gitiles_ref:refs/heads/master"
]
}
[01:47:40.605] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610675259392241",
"id": "8858078295165870496",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"25baaa4b7d2b1a3315bab27765c29ac43a8b96ec\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610675260341687",
"tags": [
"build_address:luci.chromium.ci/win-asan/16827",
"builder:win-asan",
"buildset:commit/git/25baaa4b7d2b1a3315bab27765c29ac43a8b96ec",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/25baaa4b7d2b1a3315bab27765c29ac43a8b96ec",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023508357213583184",
"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/8858078295165870496/+/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": "1610675260341905",
"url": "https://ci.chromium.org/b/8858078295165870496",
"utcnow_ts": "1610675260595964"
}
}
[01:47:40.605] Task URL: https://ci.chromium.org/b/8858078295165870496
[01:47:40.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:2:0) after 1m0s
[01:47:46.723] Received PubSub notification, asking Buildbucket for the build status
[01:47:46.813] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:40.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:2:0)
[01:48:40.629] Timer tick, asking Buildbucket for the build status
[01:48:40.715] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:40.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:4:0) after 1m0s
[01:49:40.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:4:0)
[01:49:40.757] Timer tick, asking Buildbucket for the build status
[01:49:40.896] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:40.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:5:0) after 1m0s
[01:50:41.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:5:0)
[01:50:41.006] Timer tick, asking Buildbucket for the build status
[01:50:41.223] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:41.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:6:0) after 1m0s
[01:51:41.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:6:0)
[01:51:41.296] Timer tick, asking Buildbucket for the build status
[01:51:41.393] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:41.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:7:0) after 1m0s
[01:52:41.543] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:7:0)
[01:52:41.543] Timer tick, asking Buildbucket for the build status
[01:52:41.927] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:41.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:8:0) after 1m0s
[01:53:41.904] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:8:0)
[01:53:41.904] Timer tick, asking Buildbucket for the build status
[01:53:42.195] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:42.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:9:0) after 1m0s
[01:54:42.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:9:0)
[01:54:42.208] Timer tick, asking Buildbucket for the build status
[01:54:42.316] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:42.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:10:0) after 1m0s
[01:55:42.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:10:0)
[01:55:42.398] Timer tick, asking Buildbucket for the build status
[01:55:42.469] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:42.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:11:0) after 1m0s
[01:56:42.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:11:0)
[01:56:42.484] Timer tick, asking Buildbucket for the build status
[01:56:42.546] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:42.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:12:0) after 1m0s
[01:57:42.567] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:12:0)
[01:57:42.567] Timer tick, asking Buildbucket for the build status
[01:57:42.683] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:42.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:13:0) after 1m0s
[01:58:42.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:13:0)
[01:58:42.756] Timer tick, asking Buildbucket for the build status
[01:58:42.831] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:42.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:14:0) after 1m0s
[01:59:42.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:14:0)
[01:59:42.874] Timer tick, asking Buildbucket for the build status
[01:59:42.928] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:42.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:15:0) after 1m0s
[02:00:42.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:15:0)
[02:00:42.988] Timer tick, asking Buildbucket for the build status
[02:00:43.082] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:43.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:16:0) after 1m0s
[02:01:43.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:16:0)
[02:01:43.144] Timer tick, asking Buildbucket for the build status
[02:01:43.222] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:43.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:17:0) after 1m0s
[02:02:43.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:17:0)
[02:02:43.245] Timer tick, asking Buildbucket for the build status
[02:02:43.719] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:43.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:18:0) after 1m0s
[02:03:43.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:18:0)
[02:03:43.783] Timer tick, asking Buildbucket for the build status
[02:03:43.849] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:43.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:19:0) after 1m0s
[02:04:43.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:19:0)
[02:04:43.865] Timer tick, asking Buildbucket for the build status
[02:04:43.931] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:43.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:20:0) after 1m0s
[02:05:43.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:20:0)
[02:05:43.944] Timer tick, asking Buildbucket for the build status
[02:05:44.249] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:44.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:21:0) after 1m0s
[02:06:44.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:21:0)
[02:06:44.371] Timer tick, asking Buildbucket for the build status
[02:06:44.431] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:44.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:22:0) after 1m0s
[02:07:44.468] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:22:0)
[02:07:44.468] Timer tick, asking Buildbucket for the build status
[02:07:44.621] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:44.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:23:0) after 1m0s
[02:08:44.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:23:0)
[02:08:44.769] Timer tick, asking Buildbucket for the build status
[02:08:44.897] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:44.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:24:0) after 1m0s
[02:09:45.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:24:0)
[02:09:45.126] Timer tick, asking Buildbucket for the build status
[02:09:45.255] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:45.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:25:0) after 1m0s
[02:10:45.464] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:25:0)
[02:10:45.464] Timer tick, asking Buildbucket for the build status
[02:10:45.666] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:45.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:26:0) after 1m0s
[02:11:45.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:26:0)
[02:11:45.811] Timer tick, asking Buildbucket for the build status
[02:11:45.953] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:45.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:27:0) after 1m0s
[02:12:46.082] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:27:0)
[02:12:46.082] Timer tick, asking Buildbucket for the build status
[02:12:46.441] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:46.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:28:0) after 1m0s
[02:13:46.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:28:0)
[02:13:46.451] Timer tick, asking Buildbucket for the build status
[02:13:46.735] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:46.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:29:0) after 1m0s
[02:14:46.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:29:0)
[02:14:46.879] Timer tick, asking Buildbucket for the build status
[02:14:47.009] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:47.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:30:0) after 1m0s
[02:15:47.090] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:30:0)
[02:15:47.090] Timer tick, asking Buildbucket for the build status
[02:15:48.530] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:48.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:31:0) after 1m0s
[02:16:48.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:31:0)
[02:16:48.585] Timer tick, asking Buildbucket for the build status
[02:16:48.728] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:48.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:32:0) after 1m0s
[02:17:48.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:32:0)
[02:17:48.774] Timer tick, asking Buildbucket for the build status
[02:17:48.892] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:48.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:33:0) after 1m0s
[02:18:48.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:33:0)
[02:18:48.923] Timer tick, asking Buildbucket for the build status
[02:18:49.383] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:49.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:34:0) after 1m0s
[02:19:49.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:34:0)
[02:19:49.499] Timer tick, asking Buildbucket for the build status
[02:19:49.722] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:49.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:35:0) after 1m0s
[02:20:49.800] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:35:0)
[02:20:49.800] Timer tick, asking Buildbucket for the build status
[02:20:50.192] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:50.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:36:0) after 1m0s
[02:21:50.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:36:0)
[02:21:50.264] Timer tick, asking Buildbucket for the build status
[02:21:50.932] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:50.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:37:0) after 1m0s
[02:22:50.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:37:0)
[02:22:50.946] Timer tick, asking Buildbucket for the build status
[02:22:51.519] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:51.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:38:0) after 1m0s
[02:23:51.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:38:0)
[02:23:51.551] Timer tick, asking Buildbucket for the build status
[02:23:51.683] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:51.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:39:0) after 1m0s
[02:24:51.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:39:0)
[02:24:51.706] Timer tick, asking Buildbucket for the build status
[02:24:52.527] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:52.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:40:0) after 1m0s
[02:25:52.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:40:0)
[02:25:52.535] Timer tick, asking Buildbucket for the build status
[02:25:52.670] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:52.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:41:0) after 1m0s
[02:26:52.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:41:0)
[02:26:52.723] Timer tick, asking Buildbucket for the build status
[02:26:52.803] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:52.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:42:0) after 1m0s
[02:27:52.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:42:0)
[02:27:52.850] Timer tick, asking Buildbucket for the build status
[02:27:52.951] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:52.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:43:0) after 1m0s
[02:28:52.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:43:0)
[02:28:52.989] Timer tick, asking Buildbucket for the build status
[02:28:53.508] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:53.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:44:0) after 1m0s
[02:29:53.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:44:0)
[02:29:53.588] Timer tick, asking Buildbucket for the build status
[02:29:53.668] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:53.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:45:0) after 1m0s
[02:30:53.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:45:0)
[02:30:53.663] Timer tick, asking Buildbucket for the build status
[02:30:53.750] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:53.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:46:0) after 1m0s
[02:31:53.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:46:0)
[02:31:53.786] Timer tick, asking Buildbucket for the build status
[02:31:53.904] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:53.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:47:0) after 1m0s
[02:32:53.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:47:0)
[02:32:53.949] Timer tick, asking Buildbucket for the build status
[02:32:54.079] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:54.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:48:0) after 1m0s
[02:33:54.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:48:0)
[02:33:54.133] Timer tick, asking Buildbucket for the build status
[02:33:54.521] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:54.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:49:0) after 1m0s
[02:34:54.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:49:0)
[02:34:54.613] Timer tick, asking Buildbucket for the build status
[02:34:54.690] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:54.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:50:0) after 1m0s
[02:35:54.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:50:0)
[02:35:54.843] Timer tick, asking Buildbucket for the build status
[02:35:55.036] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:55.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:51:0) after 1m0s
[02:36:55.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:51:0)
[02:36:55.066] Timer tick, asking Buildbucket for the build status
[02:36:55.202] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:55.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:52:0) after 1m0s
[02:37:55.184] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:52:0)
[02:37:55.184] Timer tick, asking Buildbucket for the build status
[02:37:55.362] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:55.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:53:0) after 1m0s
[02:38:55.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:53:0)
[02:38:55.438] Timer tick, asking Buildbucket for the build status
[02:38:55.720] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:55.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:54:0) after 1m0s
[02:39:55.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:54:0)
[02:39:55.770] Timer tick, asking Buildbucket for the build status
[02:39:56.214] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:56.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:55:0) after 1m0s
[02:40:56.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:55:0)
[02:40:56.272] Timer tick, asking Buildbucket for the build status
[02:40:56.353] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:56.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:56:0) after 1m0s
[02:41:56.399] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:56:0)
[02:41:56.399] Timer tick, asking Buildbucket for the build status
[02:41:57.520] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:57.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:57:0) after 1m0s
[02:42:57.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:57:0)
[02:42:57.747] Timer tick, asking Buildbucket for the build status
[02:42:58.004] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:58.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:58:0) after 1m0s
[02:43:58.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:58:0)
[02:43:58.094] Timer tick, asking Buildbucket for the build status
[02:43:58.183] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:58.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:59:0) after 1m0s
[02:44:58.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:59:0)
[02:44:58.260] Timer tick, asking Buildbucket for the build status
[02:44:58.356] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:58.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:60:0) after 1m0s
[02:45:58.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:60:0)
[02:45:58.404] Timer tick, asking Buildbucket for the build status
[02:45:58.535] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:58.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:61:0) after 1m0s
[02:46:58.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:61:0)
[02:46:58.617] Timer tick, asking Buildbucket for the build status
[02:46:58.762] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:58.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:62:0) after 1m0s
[02:47:58.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:62:0)
[02:47:58.866] Timer tick, asking Buildbucket for the build status
[02:47:59.424] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:59.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:63:0) after 1m0s
[02:48:59.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:63:0)
[02:48:59.483] Timer tick, asking Buildbucket for the build status
[02:48:59.631] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:59.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:64:0) after 1m0s
[02:49:59.729] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:64:0)
[02:49:59.729] Timer tick, asking Buildbucket for the build status
[02:50:00.195] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:00.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:65:0) after 1m0s
[02:51:01.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:65:0)
[02:51:01.251] Timer tick, asking Buildbucket for the build status
[02:51:01.382] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:01.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:66:0) after 1m0s
[02:52:01.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:66:0)
[02:52:01.707] Timer tick, asking Buildbucket for the build status
[02:52:02.556] Build 8858078295165870496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:02.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023508357213583184:67:0) after 1m0s
[02:52:15.472] Received PubSub notification, asking Buildbucket for the build status
[02:52:15.564] Build 8858078295165870496: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:52:15.564] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610679134625308",
"created_by": "project:chromium",
"created_ts": "1610675259392241",
"id": "8858078295165870496",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"25baaa4b7d2b1a3315bab27765c29ac43a8b96ec\"}}",
"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\": \"25baaa4b7d2b1a3315bab27765c29ac43a8b96ec\", \"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-3-nb2x\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16827, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"c07ef602741b3b051ac4823b5a74327279ffc68b\", \"got_dawn_revision\": \"5a6fda1a6fcc99553bab1411e2159cf962b6a36d\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"25baaa4b7d2b1a3315bab27765c29ac43a8b96ec\", \"got_revision_cp\": \"refs/heads/master@{#843877}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"5255c465bbbab020b2fa98f818a705e764865869\", \"got_v8_revision\": \"16b9bbbd581c25391981aa03180b76aa60463a3e\", \"got_v8_revision_cp\": \"refs/heads/8.9.255@{#1}\", \"got_webrtc_revision\": \"7acc2d9fe3a6e3c4d8881d2bdfc9b8968a724cd5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32986}\", \"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\": \"25baaa4b7d2b1a3315bab27765c29ac43a8b96ec\", \"swarm_hashes_refs/heads/master(at){#843877}_without_patch\": {\"absl_hardening_tests\": \"e80e92a86c11a52a4cf3f675de43aec506c9e3ea\", \"accessibility_unittests\": \"90c72b6a77acb0b73707a14d7830a485d9c0d383\", \"angle_unittests\": \"3c5f0cd7e5670dc1274caf17011defee43fb7fc8\", \"app_shell_unittests\": \"457212227714175005ceac57c638f8249cf22c46\", \"aura_unittests\": \"b4cb00e612021f3b870a022745f8cf69f4d03da6\", \"base_unittests\": \"fd813985fe1bf9ab118b2302097b318957964fbe\", \"base_util_unittests\": \"531aa59189cf3c84d9310590aa31901cf8fab6be\", \"blink_common_unittests\": \"65599972878d3c30b7e8124b5e70b25e720038c6\", \"blink_fuzzer_unittests\": \"9734769992cca01ec0b03b5a26f1ccee4c3bea6e\", \"blink_heap_unittests\": \"958ecab881950669871895c66bb2c08cb0376cdc\", \"blink_platform_unittests\": \"95b34628a1aad34d6fa657a9be8e52054b54684e\", \"blink_unittests\": \"69907c913758c432808cd76bf27fac0b31c1337d\", \"boringssl_crypto_tests\": \"b995521b6f19b3b11577bfa9e47f07c1b5caaf53\", \"boringssl_ssl_tests\": \"cdd021a97166d08e7f1c5650219ec2c0451fd25b\", \"browser_switcher_bho_unittests\": \"d59b8f24bd6d2d6214b7eb96648da77ae91641d1\", \"browser_tests\": \"8909379004dfaec2e887a16aaf9d98ea66568c44\", \"capture_unittests\": \"906a38517f2e63be8c49e297815cb8129effdbe2\", \"cast_unittests\": \"98d0696fe4c9939375dc4edf4b8c62f07823c9c4\", \"cc_unittests\": \"4643106592247d04271249db461f76d4dbfb9876\", \"chrome_app_unittests\": \"849287deabff307757ca6128b58cd55bbe6f85b0\", \"chrome_cleaner_unittests\": \"8a1a2452b00d2400670971575a7407687769c99b\", \"chrome_elf_unittests\": \"d699b94833ac9c5164092887fda18ce44134b52f\", \"chromedriver_unittests\": \"ad48f1528e42544cc1df27ea6e0302b9b2160ba8\", \"components_browsertests\": \"8a65b94f5761d80f3d15f685b0e6e9b0d04c81d5\", \"components_unittests\": \"c55136f0e5fe9d62522483bc6df6ae7aa0a4e173\", \"compositor_unittests\": \"0cf0a70fe75489d9643e1614c61afd0d2d177b55\", \"content_browsertests\": \"27a1ded29abf808b9b5e17630608c4af6736fd23\", \"content_unittests\": \"d4e188c5fd603bb5ed7138dc3313f93d2e0d95b3\", \"courgette_unittests\": \"b8b6c92c7b06b4d764c063717d0a5955a98de60b\", \"crashpad_tests\": \"06220aa4edecc39e23a185493813b2c21b5b94fd\", \"cronet_tests\": \"7a60ea3d0672ecaa9fb73adbcec942c8f520828d\", \"cronet_unittests\": \"962b4e2c753483d011a6d3b9f33020a507732ac7\", \"crypto_unittests\": \"e2b0d161341807935eac59ec866a6fcea383d0ed\", \"delayloads_unittests\": \"2e6a9d32cca80b0abede50cf9b01211df69f6252\", \"device_unittests\": \"f544cc2e99bf237f2006951940c3753963a20aa7\", \"display_unittests\": \"9c722805aed7d61e93a26f68502fbdbe8199b455\", \"elevation_service_unittests\": \"5d7f90c871d08d40025de1a5abb79bb40358b96e\", \"events_unittests\": \"1a813c7b17c9a60797f27a48a0cb4330594ebfef\", \"extensions_browsertests\": \"0c7d266963e32b99ef1042e416f5ab857260b6ab\", \"extensions_unittests\": \"537bd3f4a658e22150b3b4fb520e31975268df2a\", \"filesystem_service_unittests\": \"de1375391a82c32086a9b940a42c70bb7f487603\", \"gcm_unit_tests\": \"ef9afff71849596576df90ba10072abde7f60b3e\", \"gcp_unittests\": \"45c139d8c3ab5560d6bb008d36ce2f91d4903d40\", \"gfx_unittests\": \"02e5454a804dc12f05d3e475824ba2be8af3fe47\", \"gin_unittests\": \"a36ae523b97dbe94b0fdea36d7b2f8f8f921d8c6\", \"google_apis_unittests\": \"d2dee37e2ca2bd758ee5fac59308f7bb3d4d0d3f\", \"gpu_unittests\": \"a54b77b7e4f7fa997e26f3078638140a0d41192d\", \"gwp_asan_unittests\": \"6d1b502b740bf4628309e3c928dcff3d0295e956\", \"headless_browsertests\": \"94ef3e53057ad19c87cb907b8d05cdfafbfd44bf\", \"headless_unittests\": \"085322dc58b72a98fe3559739adce8652bc45137\", \"install_static_unittests\": \"ac025ac6cc9fb66bcf7e448b1a59f5065ccac99b\", \"installer_util_unittests\": \"1c2c74e65b88f78eb0ed70303d26369c49ba6f06\", \"interactive_ui_tests\": \"25c1a91cab5d508c14a7b5108e1b307f1fbfdc8f\", \"ipc_tests\": \"b12d46fb37b339de67b3f3466e092cb9bbef67e0\", \"jingle_unittests\": \"8ad440532c8bd2e7c21dc3a093937e6fedeee333\", \"latency_unittests\": \"a6504f5cd52fd2eab064b660608ee7c60417f43a\", \"libjingle_xmpp_unittests\": \"195c09bcc503e019af953742f15d87e622df0ee4\", \"liburlpattern_unittests\": \"495c955f01e6fdcdefb5d15c755815bb921457ca\", \"media_blink_unittests\": \"91e623b3696a69849d4b0a3f0527a87b2e360ad6\", \"media_unittests\": \"7777b9235fc258f7e614bd10de1622fe8c7f06a5\", \"message_center_unittests\": \"9a51d9ed8f0c8cbb61e4b0bad7dcde2558236315\", \"midi_unittests\": \"71bacbad1282ac41ad256c87022cf5a4ac442738\", \"mojo_core_unittests\": \"f6710360f13b359ca507b66bfd8b784e8620216d\", \"mojo_unittests\": \"fe478a09fbca4492862740fe3b0270fc96a5eddd\", \"nacl_loader_unittests\": \"1a9e058b858b47b50677464a08f3a24053514925\", \"native_theme_unittests\": \"35e6efcebf7b38cb0af96c88ae760ebf777e7ae8\", \"net_unittests\": \"a399dd2d37a063a87dc1fb0ed51cdc7c8914d67b\", \"notification_helper_unittests\": \"6ceb1de6b75287c1eaf9604285835ac7566cd068\", \"pdf_unittests\": \"1f7458c1707c4a013b12b64daac5bcf097f8a25f\", \"perfetto_unittests\": \"624ce3abbe2f373f9756a323bfb69ca848cc380c\", \"ppapi_unittests\": \"3b8566de01e4871d5e6cfdaf129cdd16d422388c\", \"printing_unittests\": \"a4a0d389f60d9d52c7ac186aa16562c1641775ff\", \"remoting_unittests\": \"2613f13fde33d8b99b2d1be24ecb8ffd454531f1\", \"sbox_integration_tests\": \"b97a238235ba2e9b70ff904ee1cfcd5b6dca02dc\", \"sbox_unittests\": \"e6384c634732dc697d9968a7896a260ce5f4ba80\", \"sbox_validation_tests\": \"a445ad3b3d19612cdc2cf663ec2fb4c9a5646b8d\", \"service_manager_unittests\": \"8a50eb22cdec5a483ab437a7b18fdcb19296ff1f\", \"services_unittests\": \"b35eeaea475554c47a41dbba267af25b202cc741\", \"setup_unittests\": \"bf3ed7345828558820465d6c5d8cc16341b83a10\", \"shell_dialogs_unittests\": \"97c393374e62c5dcdc0361a7991a49d49e9ddaf2\", \"skia_unittests\": \"819b1ea362c95a27279001f2bee92b9bd1eb2aee\", \"snapshot_unittests\": \"40b3fc2543657cd2ce9800c05b9378f5ea2e6986\", \"sql_unittests\": \"e5adab62a9ed29fb63800cf9acc67796c9ed1425\", \"storage_unittests\": \"7e96c06ef400c90f9a6b418e173eca7e3bec1843\", \"sync_integration_tests\": \"523e6b70f7da5d87c4d22c5a5512847d63486dd2\", \"traffic_annotation_auditor_unittests\": \"8c8a787fe47b243c058062bfae9decec2050e5ec\", \"ui_base_unittests\": \"ad6755d28f697b35f50dafd0904f51c4dfbd54d4\", \"ui_touch_selection_unittests\": \"c8c13696217f91d8722ce07c14096467402cb151\", \"unit_tests\": \"38e6a3f2501282eb5f7e15dbaf75122a98bbfa1a\", \"updater_tests\": \"47a5f4762d236d28ead8ceb755e8f026317f2ece\", \"url_unittests\": \"92fbc6978ab0481ad3caedd825b58a5b424df16a\", \"views_unittests\": \"07d341d4102ecbcfa5f589471684c05cb3b0731b\", \"viz_unittests\": \"e95d63cd951856b3be5d60cd0ac99d6d8f3b6a2e\", \"vr_common_unittests\": \"d0862ad8a20148d1215d59cae65f453751070df8\", \"vr_pixeltests\": \"ee6a7391411e7a29ba4aeed2b4a9f8de1b30366d\", \"weblayer_browsertests\": \"6d2694edfb0caa2ab1b6048f37ed2d0665ee1fa4\", \"weblayer_unittests\": \"635699eb63d36bd6ab367ac9b8e98cd33e99da52\", \"wm_unittests\": \"6a69ca166232a83d365801c7862e375d6af7e3c6\", \"wtf_unittests\": \"f99fcc30c36a716795d4e03a66c29e62a631a0aa\", \"zlib_unittests\": \"2bac7ff559dcb01788a3ee11922da5c6ce43ca1e\", \"zucchini_unittests\": \"e4aae04e68d3e116a1918ef7332ae6230c43894f\"}}, \"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-3-nb2x\"], \"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\": [\"5666-679c9bd\"], \"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": "1610675266203738",
"status": "COMPLETED",
"status_changed_ts": "1610679134958256",
"tags": [
"build_address:luci.chromium.ci/win-asan/16827",
"builder:win-asan",
"buildset:commit/git/25baaa4b7d2b1a3315bab27765c29ac43a8b96ec",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/25baaa4b7d2b1a3315bab27765c29ac43a8b96ec",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023508357213583184",
"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/8858078295165870496/+/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:511c8b959960c910",
"user_agent:luci-scheduler"
],
"updated_ts": "1610679134962762",
"url": "https://ci.chromium.org/b/8858078295165870496",
"utcnow_ts": "1610679135551458"
}
}
[02:52:15.564] Invocation finished in 1h4m37.508173556s with status SUCCEEDED