[02:00:27.460] New invocation is queued and will start shortly
[02:00:28.756] Starting the invocation (attempt 1)
[02:00:28.851] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:00:28.851] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:00:28.851] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027493816976924656",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"374ddfcf6b7ef3b43a9eed041732d179bd8bc4b8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027493816976924656",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/374ddfcf6b7ef3b43a9eed041732d179bd8bc4b8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/374ddfcf6b7ef3b43a9eed041732d179bd8bc4b8",
"gitiles_ref:refs/heads/master"
]
}
[02:00:30.503] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606874429161064",
"id": "8862063754526209632",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"374ddfcf6b7ef3b43a9eed041732d179bd8bc4b8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606874430227855",
"tags": [
"build_address:luci.chromium.ci/win-asan/15976",
"builder:win-asan",
"buildset:commit/git/374ddfcf6b7ef3b43a9eed041732d179bd8bc4b8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/374ddfcf6b7ef3b43a9eed041732d179bd8bc4b8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027493816976924656",
"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/8862063754526209632/+/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": "1606874430228013",
"url": "https://ci.chromium.org/b/8862063754526209632",
"utcnow_ts": "1606874430448413"
}
}
[02:00:30.503] Task URL: https://ci.chromium.org/b/8862063754526209632
[02:00:30.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:2:0) after 1m0s
[02:00:34.384] Received PubSub notification, asking Buildbucket for the build status
[02:00:34.472] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:30.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:2:0)
[02:01:30.623] Timer tick, asking Buildbucket for the build status
[02:01:30.737] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:30.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:4:0) after 1m0s
[02:02:30.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:4:0)
[02:02:30.895] Timer tick, asking Buildbucket for the build status
[02:02:31.187] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:31.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:5:0) after 1m0s
[02:03:31.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:5:0)
[02:03:31.388] Timer tick, asking Buildbucket for the build status
[02:03:31.941] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:31.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:6:0) after 1m0s
[02:04:31.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:6:0)
[02:04:31.947] Timer tick, asking Buildbucket for the build status
[02:04:32.558] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:32.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:7:0) after 1m0s
[02:05:32.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:7:0)
[02:05:32.583] Timer tick, asking Buildbucket for the build status
[02:05:32.736] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:32.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:8:0) after 1m0s
[02:06:32.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:8:0)
[02:06:32.827] Timer tick, asking Buildbucket for the build status
[02:06:32.926] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:32.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:9:0) after 1m0s
[02:07:32.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:9:0)
[02:07:32.997] Timer tick, asking Buildbucket for the build status
[02:07:33.300] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:33.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:10:0) after 1m0s
[02:08:33.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:10:0)
[02:08:33.428] Timer tick, asking Buildbucket for the build status
[02:08:33.527] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:33.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:11:0) after 1m0s
[02:09:33.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:11:0)
[02:09:33.568] Timer tick, asking Buildbucket for the build status
[02:09:33.642] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:33.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:12:0) after 1m0s
[02:10:33.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:12:0)
[02:10:33.760] Timer tick, asking Buildbucket for the build status
[02:10:33.880] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:33.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:13:0) after 1m0s
[02:11:33.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:13:0)
[02:11:33.909] Timer tick, asking Buildbucket for the build status
[02:11:34.014] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:34.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:14:0) after 1m0s
[02:12:34.071] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:14:0)
[02:12:34.071] Timer tick, asking Buildbucket for the build status
[02:12:34.152] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:34.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:15:0) after 1m0s
[02:13:34.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:15:0)
[02:13:34.162] Timer tick, asking Buildbucket for the build status
[02:13:34.230] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:34.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:16:0) after 1m0s
[02:14:34.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:16:0)
[02:14:34.263] Timer tick, asking Buildbucket for the build status
[02:14:34.352] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:34.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:17:0) after 1m0s
[02:15:34.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:17:0)
[02:15:34.414] Timer tick, asking Buildbucket for the build status
[02:15:34.606] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:34.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:18:0) after 1m0s
[02:16:34.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:18:0)
[02:16:34.648] Timer tick, asking Buildbucket for the build status
[02:16:34.729] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:34.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:19:0) after 1m0s
[02:17:34.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:19:0)
[02:17:34.892] Timer tick, asking Buildbucket for the build status
[02:17:34.988] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:34.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:20:0) after 1m0s
[02:18:35.024] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:20:0)
[02:18:35.024] Timer tick, asking Buildbucket for the build status
[02:18:35.084] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:35.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:21:0) after 1m0s
[02:19:35.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:21:0)
[02:19:35.119] Timer tick, asking Buildbucket for the build status
[02:19:35.176] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:35.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:22:0) after 1m0s
[02:20:35.170] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:22:0)
[02:20:35.170] Timer tick, asking Buildbucket for the build status
[02:20:35.274] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:35.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:23:0) after 1m0s
[02:21:35.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:23:0)
[02:21:35.796] Timer tick, asking Buildbucket for the build status
[02:21:35.931] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:35.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:24:0) after 1m0s
[02:22:36.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:24:0)
[02:22:36.267] Timer tick, asking Buildbucket for the build status
[02:22:36.354] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:36.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:25:0) after 1m0s
[02:23:36.464] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:25:0)
[02:23:36.464] Timer tick, asking Buildbucket for the build status
[02:23:36.677] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:36.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:26:0) after 1m0s
[02:24:36.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:26:0)
[02:24:36.839] Timer tick, asking Buildbucket for the build status
[02:24:36.989] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:36.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:27:0) after 1m0s
[02:25:37.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:27:0)
[02:25:37.003] Timer tick, asking Buildbucket for the build status
[02:25:37.140] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:37.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:28:0) after 1m0s
[02:26:37.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:28:0)
[02:26:37.127] Timer tick, asking Buildbucket for the build status
[02:26:37.484] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:37.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:29:0) after 1m0s
[02:27:37.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:29:0)
[02:27:37.540] Timer tick, asking Buildbucket for the build status
[02:27:37.666] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:37.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:30:0) after 1m0s
[02:28:37.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:30:0)
[02:28:37.706] Timer tick, asking Buildbucket for the build status
[02:28:37.846] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:37.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:31:0) after 1m0s
[02:29:37.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:31:0)
[02:29:37.877] Timer tick, asking Buildbucket for the build status
[02:29:38.011] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:38.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:32:0) after 1m0s
[02:30:38.047] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:32:0)
[02:30:38.047] Timer tick, asking Buildbucket for the build status
[02:30:38.386] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:38.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:33:0) after 1m0s
[02:31:38.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:33:0)
[02:31:38.420] Timer tick, asking Buildbucket for the build status
[02:31:38.574] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:38.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:34:0) after 1m0s
[02:32:38.641] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:34:0)
[02:32:38.641] Timer tick, asking Buildbucket for the build status
[02:32:38.758] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:38.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:35:0) after 1m0s
[02:33:38.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:35:0)
[02:33:38.877] Timer tick, asking Buildbucket for the build status
[02:33:39.035] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:39.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:36:0) after 1m0s
[02:34:39.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:36:0)
[02:34:39.079] Timer tick, asking Buildbucket for the build status
[02:34:39.381] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:39.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:37:0) after 1m0s
[02:35:39.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:37:0)
[02:35:39.433] Timer tick, asking Buildbucket for the build status
[02:35:39.562] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:39.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:38:0) after 1m0s
[02:36:39.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:38:0)
[02:36:39.578] Timer tick, asking Buildbucket for the build status
[02:36:39.655] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:39.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:39:0) after 1m0s
[02:37:39.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:39:0)
[02:37:39.706] Timer tick, asking Buildbucket for the build status
[02:37:39.779] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:39.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:40:0) after 1m0s
[02:38:39.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:40:0)
[02:38:39.877] Timer tick, asking Buildbucket for the build status
[02:38:39.978] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:39.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:41:0) after 1m0s
[02:39:40.029] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:41:0)
[02:39:40.029] Timer tick, asking Buildbucket for the build status
[02:39:40.140] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:40.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:42:0) after 1m0s
[02:40:40.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:42:0)
[02:40:40.246] Timer tick, asking Buildbucket for the build status
[02:40:40.363] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:40.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:43:0) after 1m0s
[02:41:40.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:43:0)
[02:41:40.457] Timer tick, asking Buildbucket for the build status
[02:41:40.583] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:40.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:44:0) after 1m0s
[02:42:40.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:44:0)
[02:42:40.703] Timer tick, asking Buildbucket for the build status
[02:42:40.838] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:40.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:45:0) after 1m0s
[02:43:40.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:45:0)
[02:43:40.919] Timer tick, asking Buildbucket for the build status
[02:43:41.192] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:41.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:46:0) after 1m0s
[02:44:41.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:46:0)
[02:44:41.353] Timer tick, asking Buildbucket for the build status
[02:44:41.463] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:41.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:47:0) after 1m0s
[02:45:41.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:47:0)
[02:45:41.588] Timer tick, asking Buildbucket for the build status
[02:45:42.214] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:42.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:48:0) after 1m0s
[02:46:42.307] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:48:0)
[02:46:42.307] Timer tick, asking Buildbucket for the build status
[02:46:42.803] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:42.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:49:0) after 1m0s
[02:47:42.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:49:0)
[02:47:42.873] Timer tick, asking Buildbucket for the build status
[02:47:43.112] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:43.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:50:0) after 1m0s
[02:48:43.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:50:0)
[02:48:43.241] Timer tick, asking Buildbucket for the build status
[02:48:43.372] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:43.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:51:0) after 1m0s
[02:49:43.466] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:51:0)
[02:49:43.466] Timer tick, asking Buildbucket for the build status
[02:49:43.685] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:43.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:52:0) after 1m0s
[02:50:43.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:52:0)
[02:50:43.797] Timer tick, asking Buildbucket for the build status
[02:50:44.502] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:44.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:53:0) after 1m0s
[02:51:44.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:53:0)
[02:51:44.581] Timer tick, asking Buildbucket for the build status
[02:51:44.729] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:44.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:54:0) after 1m0s
[02:52:44.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:54:0)
[02:52:44.749] Timer tick, asking Buildbucket for the build status
[02:52:44.900] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:44.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:55:0) after 1m0s
[02:53:44.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:55:0)
[02:53:44.944] Timer tick, asking Buildbucket for the build status
[02:53:45.178] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:45.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:56:0) after 1m0s
[02:54:45.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:56:0)
[02:54:45.214] Timer tick, asking Buildbucket for the build status
[02:54:46.264] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:46.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:57:0) after 1m0s
[02:55:46.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:57:0)
[02:55:46.337] Timer tick, asking Buildbucket for the build status
[02:55:47.007] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:47.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:58:0) after 1m0s
[02:56:47.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:58:0)
[02:56:47.143] Timer tick, asking Buildbucket for the build status
[02:56:47.271] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:47.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:59:0) after 1m0s
[02:57:47.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:59:0)
[02:57:47.358] Timer tick, asking Buildbucket for the build status
[02:57:47.472] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:47.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:60:0) after 1m0s
[02:58:47.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:60:0)
[02:58:47.578] Timer tick, asking Buildbucket for the build status
[02:58:47.747] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:47.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:61:0) after 1m0s
[02:59:47.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:61:0)
[02:59:47.794] Timer tick, asking Buildbucket for the build status
[02:59:47.939] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:47.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:62:0) after 1m0s
[03:00:48.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:62:0)
[03:00:48.062] Timer tick, asking Buildbucket for the build status
[03:00:48.296] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:48.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:63:0) after 1m0s
[03:01:48.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:63:0)
[03:01:48.314] Timer tick, asking Buildbucket for the build status
[03:01:48.401] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:48.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:64:0) after 1m0s
[03:02:48.488] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:64:0)
[03:02:48.488] Timer tick, asking Buildbucket for the build status
[03:02:49.251] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:49.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:65:0) after 1m0s
[03:03:49.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:65:0)
[03:03:49.508] Timer tick, asking Buildbucket for the build status
[03:03:49.593] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:49.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:66:0) after 1m0s
[03:04:49.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:66:0)
[03:04:49.898] Timer tick, asking Buildbucket for the build status
[03:04:50.115] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:50.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:67:0) after 1m0s
[03:05:50.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:67:0)
[03:05:50.206] Timer tick, asking Buildbucket for the build status
[03:05:50.486] Build 8862063754526209632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:50.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027493816976924656:68:0) after 1m0s
[03:06:47.040] Received PubSub notification, asking Buildbucket for the build status
[03:06:47.132] Build 8862063754526209632: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:06:47.132] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606878405545550",
"created_by": "project:chromium",
"created_ts": "1606874429161064",
"id": "8862063754526209632",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"374ddfcf6b7ef3b43a9eed041732d179bd8bc4b8\"}}",
"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\": \"374ddfcf6b7ef3b43a9eed041732d179bd8bc4b8\", \"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-9-v8md\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15976, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"0c47015b57a03230e0cab10532c3deed80604512\", \"got_dawn_revision\": \"6f5151f6572bf22c88d0b3152bebeaa460ac4723\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"374ddfcf6b7ef3b43a9eed041732d179bd8bc4b8\", \"got_revision_cp\": \"refs/heads/master@{#832623}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"bfb211914759cc5deffbe9419cd1c2cae0573488\", \"got_v8_revision\": \"ac41b8da5f1de6f65eea381b67341c7c32acb05f\", \"got_v8_revision_cp\": \"refs/heads/8.9.73@{#1}\", \"got_webrtc_revision\": \"449a78b1e20ea85b11f967cf3a184ee610ce21c3\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32734}\", \"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\": \"374ddfcf6b7ef3b43a9eed041732d179bd8bc4b8\", \"swarm_hashes_refs/heads/master(at){#832623}_without_patch\": {\"absl_hardening_tests\": \"e681b10b6475bb2e77e11818f0c8cb756e47a35f\", \"accessibility_unittests\": \"567127f90cda53300e0213e816a6b7caafe3925d\", \"angle_unittests\": \"988656a898b29d116aa957daad404086b5048ea9\", \"app_shell_unittests\": \"601617a71bac88f038d785558d7c58e3ef402079\", \"aura_unittests\": \"678524baa5c469cd3355b248fcbc63a47c5fce56\", \"base_unittests\": \"ba0b7b965e8c16935d7477338852839236f265fa\", \"base_util_unittests\": \"17b7ddf77c7fcf629efe2a1c36c09650a9ea623c\", \"blink_common_unittests\": \"96ae7ee724880d6aecb58dd74a263d409eae76d2\", \"blink_fuzzer_unittests\": \"c69fb1e4a317c2a1c33670478439bbb74d93f9e3\", \"blink_heap_unittests\": \"dcf80eafb70f48a5cc9e5f0c64f900dc0612c4a0\", \"blink_platform_unittests\": \"b99dc5ced2d3da84fa72f254898a501b78e545be\", \"blink_unittests\": \"d9c37075710366e773e0cae3478c4691b9b26f36\", \"boringssl_crypto_tests\": \"f2daf57956fbff490de91a20a9d7bc53cc1918aa\", \"boringssl_ssl_tests\": \"5c3a062cd1bc30cb6258109ba3c5c484cb8e6411\", \"browser_switcher_bho_unittests\": \"319659d2615ba1f5de7872552ef923236d3c037e\", \"browser_tests\": \"b81e0f6396fedfdfbbefaa6a66b043b48f69d24b\", \"capture_unittests\": \"3b6fac7c5acd7644bf3db1e68387943a63378042\", \"cast_unittests\": \"144b2a24e1facbb3c834f58b5eefbd8751407afd\", \"cc_unittests\": \"870760544d5228e22a671a81bcbf2558771f43ba\", \"chrome_app_unittests\": \"aebf99b817daf9b2683f28ab51adc80777d39280\", \"chrome_cleaner_unittests\": \"22913b79afcd5955b5a353770ca6fb63a6040982\", \"chrome_elf_unittests\": \"c68a67e02b0393299d2fa3a00f690cea187edb6e\", \"chromedriver_unittests\": \"245a45a00e28834b4eab5cbb71ac65c270a3daad\", \"components_browsertests\": \"1558944ddc29e7c802f73b1dc3bdb36f3478178d\", \"components_unittests\": \"925da64ab8ac199d938b25e642e0f6805ecdf024\", \"compositor_unittests\": \"e2bb7ad82654f3819be5ba86e16dad0af7a696e0\", \"content_browsertests\": \"77813bb4767e7fe000b0be509fcca496ca8ba180\", \"content_unittests\": \"4d9dfa9b59c4c37690da8d4f9187e9e5dd36f00c\", \"courgette_unittests\": \"27da330c0ffc6e50f623a4f2f63f32eb94b951b0\", \"crashpad_tests\": \"e892ad184a8b7756175e59c243400ffa6ce1062e\", \"cronet_tests\": \"c9c0b1d6fe3c16edd0f38acc83adb57f0ec09664\", \"cronet_unittests\": \"42bd0734c38480a503716c224713c22aee974a53\", \"crypto_unittests\": \"3ee659816db222fb8c50154d278abd48f83a9d88\", \"delayloads_unittests\": \"969f1df7f5a441aefae95718a50c0d4cb04da6c6\", \"device_unittests\": \"2642b76aa59c6ed256327aec4afa1fb3871e2e82\", \"display_unittests\": \"6e51c8270f9587b849426dca469f8fbf972bec2b\", \"elevation_service_unittests\": \"6682dec9e063d7a7a3a3df3fc8207f27ea7a1b7f\", \"events_unittests\": \"6d96c2ac7e5b13bf3e71fc64ea3cabc58bd8b6c9\", \"extensions_browsertests\": \"300314c2fc4b8d53cfacadd7fb2f505b2ac56adc\", \"extensions_unittests\": \"a18a2f7bb760efbc7e7179748344757190c9389a\", \"filesystem_service_unittests\": \"3675a70caef90d0b795b78a7f5b4495ae8f2c578\", \"gcm_unit_tests\": \"4e7ba6d69677cead264f52f8c955bf4a32bec340\", \"gcp_unittests\": \"d448bce23c467251f61cd4a88b58eddb4cf935ed\", \"gfx_unittests\": \"aab0f3973b07cd4c7422c5f2f163aa1b85374cfa\", \"gin_unittests\": \"4cb7e4f96eae3af5eb3d2a6ef87a949eea856682\", \"google_apis_unittests\": \"8f3b628923e4e42ac7ebdf9a6afc5b8e4705de8e\", \"gpu_unittests\": \"8462220164af2bd7109e88fb6a407bc3f81dd4f3\", \"gwp_asan_unittests\": \"0ad829f565fd9f0bb217451b27e495adff0703ac\", \"headless_browsertests\": \"c25078f30a6c328e927ba2458e7b9f997799ea1f\", \"headless_unittests\": \"c1ce0e0c1b9c20b504eaa6d1063d4c27c4344270\", \"install_static_unittests\": \"7a95a216c1772640ffee47c39bf202de787bb7bf\", \"installer_util_unittests\": \"aed7a521e8b918bfccd9c8368bcb4fa4b771413b\", \"interactive_ui_tests\": \"3fe0afb184059a2bcaa9aef727d575f1fffd3292\", \"ipc_tests\": \"9f0d01e401a503fc93f1fa5cb78172a409dc3d4f\", \"jingle_unittests\": \"e4f6e304e9dadb7af038b0f4f6011e80bfe7afc9\", \"latency_unittests\": \"7ac51ab6ab12a1ae6ce9d02622064edfc1206671\", \"libjingle_xmpp_unittests\": \"34677994326cadedf8e8496c7a4919523113b2a2\", \"liburlpattern_unittests\": \"2d6ca48c43a6347025eab7db37a67b8a3ae71b8f\", \"media_blink_unittests\": \"b7e5495741f7d867ee21b9d300d5f0e0f7bd0087\", \"media_unittests\": \"53b553d0735452588a3a1d4af90d2c928007ead9\", \"message_center_unittests\": \"38876a6c48d5a5bff5dc4e3f0e2b569333971d6f\", \"midi_unittests\": \"e19531b5627bb8bcc87c8aef18d04647f9b227fb\", \"mojo_core_unittests\": \"56a7ac5e3f1ca1d65c4731697818e828096d18eb\", \"mojo_unittests\": \"7c1b94c49856559ff035c3882e638b5a6637f421\", \"nacl_loader_unittests\": \"ffcd3fb8e9f0a593d53e0679b6bb3fd422c88d9c\", \"native_theme_unittests\": \"ed81296926d13cce84b496b6ef73205f78d5e13f\", \"net_unittests\": \"bc119840228af52a74a2c288bfb2210ca13cfd5b\", \"notification_helper_unittests\": \"6a5043a1da26e21a7b9bb3043d93f5fa4468aafa\", \"pdf_unittests\": \"ad44da801bd7df2118d25078a7ec79e02a06e60c\", \"perfetto_unittests\": \"f06882f55cc5668660bd7ca4be56378d7b7ac829\", \"ppapi_unittests\": \"ec2e6b8bc62160a65675167b28443283cc73bb5e\", \"printing_unittests\": \"ef488a5e17a261316bbea036536631ba10f0aa50\", \"remoting_unittests\": \"738a80795147fa6373e06fec8f0948600abd2d37\", \"sbox_integration_tests\": \"0fbfbfeb1df2df929df50a8b500d7aeb29cfb028\", \"sbox_unittests\": \"10a1669af9362a1076cef670ebd05df43d44017f\", \"sbox_validation_tests\": \"676d05f42038b65ed81131834a16dacbd7a06529\", \"service_manager_unittests\": \"ec48e8aed917e906ab306c923d2535e95afc8d9a\", \"services_unittests\": \"f67a9c7e6f5c819657dde9993f1caeb1270e1566\", \"setup_unittests\": \"e191399cf668d563d1155f52deb33544d6515102\", \"shell_dialogs_unittests\": \"753e6a24cd9c6158cc9b6a565b0f9eba786a84b2\", \"skia_unittests\": \"13d3b186167fc9b6bfc2f7759f2c6c5846f79644\", \"snapshot_unittests\": \"8bb33267f2c22523d4f2ed50d1cb9cb39c4275bc\", \"sql_unittests\": \"6ca19195f4fc3b133ea2940b1f6fce984824bd45\", \"storage_unittests\": \"7bb8e4d6eb66ce96c4b48a68336012adf98f4b3a\", \"sync_integration_tests\": \"d07c0f3252a3d4aa440a5f3ad38d4094ed138dec\", \"traffic_annotation_auditor_unittests\": \"1b779445027c83b988f5f7bb25243dbc2b207364\", \"ui_base_unittests\": \"5afe31cc3a5a1a4d8961eef2d0c95aff37b0f5d5\", \"ui_touch_selection_unittests\": \"a5b979dca4055e3c6efa1acc2c3b2c6eecac4ab6\", \"unit_tests\": \"151f9ad6e66dedd2599caee835cf2f0203f09572\", \"updater_tests\": \"926402300e32de85af13d020942ccd482575aac3\", \"url_unittests\": \"e64b07d1411e79dfd4ad000923490e48b22bb94a\", \"views_unittests\": \"2db5eccea2da138469d4a9654d81bfcba12b13ce\", \"viz_unittests\": \"ef32ba1426e3943f621a4abf95bf871034015f93\", \"vr_common_unittests\": \"c5484f914670d535d2720e551ed2d4be6c8a624b\", \"vr_pixeltests\": \"fa0fabdc8c255d75bc271aaf72cd218baf3e2ccc\", \"weblayer_browsertests\": \"196f1857b58e141fcbdad73263cc4cd4fdfbb6b0\", \"weblayer_unittests\": \"9c93f6334e96086b727846d5d1ebc8a8366443b2\", \"wm_unittests\": \"906f11b3f467e4bc100a390c30c2c57bcc69fb3b\", \"wtf_unittests\": \"e201538f6e17cadfbcc0685d7c4f270e6ef45abb\", \"zlib_unittests\": \"84c41be20e49585cf7e19386fe632c40be704ef3\", \"zucchini_unittests\": \"5e8d4c239254fa9a0e29bd29ff35b258d4efdb8d\"}}, \"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-9-v8md\"], \"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\": [\"5640-d0eb544\"], \"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": "1606874433572809",
"status": "COMPLETED",
"status_changed_ts": "1606878406498185",
"tags": [
"build_address:luci.chromium.ci/win-asan/15976",
"builder:win-asan",
"buildset:commit/git/374ddfcf6b7ef3b43a9eed041732d179bd8bc4b8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/374ddfcf6b7ef3b43a9eed041732d179bd8bc4b8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027493816976924656",
"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/8862063754526209632/+/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:5039ff8476e3bd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1606878406522521",
"url": "https://ci.chromium.org/b/8862063754526209632",
"utcnow_ts": "1606878407123559"
}
}
[03:06:47.132] Invocation finished in 1h6m19.691855218s with status SUCCEEDED