[21:05:41.986] New invocation is queued and will start shortly
[21:05:43.391] Starting the invocation (attempt 1)
[21:05:43.442] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:05:43.442] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:05:43.443] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027331167726453328",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6089b400ece07b2e778b8d3c9bb29636b7a75b48\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027331167726453328",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/6089b400ece07b2e778b8d3c9bb29636b7a75b48",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6089b400ece07b2e778b8d3c9bb29636b7a75b48",
"gitiles_ref:refs/heads/master"
]
}
[21:05:45.845] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1607029543608860",
"id": "8861901105240443136",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6089b400ece07b2e778b8d3c9bb29636b7a75b48\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607029545490150",
"tags": [
"build_address:luci.chromium.ci/win-asan/16011",
"builder:win-asan",
"buildset:commit/git/6089b400ece07b2e778b8d3c9bb29636b7a75b48",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6089b400ece07b2e778b8d3c9bb29636b7a75b48",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027331167726453328",
"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/8861901105240443136/+/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": "1607029545491159",
"url": "https://ci.chromium.org/b/8861901105240443136",
"utcnow_ts": "1607029545827357"
}
}
[21:05:45.845] Task URL: https://ci.chromium.org/b/8861901105240443136
[21:05:45.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:2:0) after 1m0s
[21:05:50.974] Received PubSub notification, asking Buildbucket for the build status
[21:05:51.134] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:45.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:2:0)
[21:06:45.852] Timer tick, asking Buildbucket for the build status
[21:06:45.932] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:45.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:4:0) after 1m0s
[21:07:46.017] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:4:0)
[21:07:46.017] Timer tick, asking Buildbucket for the build status
[21:07:46.286] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:46.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:5:0) after 1m0s
[21:08:47.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:5:0)
[21:08:47.075] Timer tick, asking Buildbucket for the build status
[21:08:47.581] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:47.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:6:0) after 1m0s
[21:09:47.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:6:0)
[21:09:47.986] Timer tick, asking Buildbucket for the build status
[21:09:48.348] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:48.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:7:0) after 1m0s
[21:10:48.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:7:0)
[21:10:48.866] Timer tick, asking Buildbucket for the build status
[21:10:49.058] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:49.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:8:0) after 1m0s
[21:11:49.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:8:0)
[21:11:49.177] Timer tick, asking Buildbucket for the build status
[21:11:49.284] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:49.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:9:0) after 1m0s
[21:12:49.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:9:0)
[21:12:49.556] Timer tick, asking Buildbucket for the build status
[21:12:49.618] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:49.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:10:0) after 1m0s
[21:13:50.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:10:0)
[21:13:50.481] Timer tick, asking Buildbucket for the build status
[21:13:51.687] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:51.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:11:0) after 1m0s
[21:14:52.167] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:11:0)
[21:14:52.167] Timer tick, asking Buildbucket for the build status
[21:14:52.292] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:52.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:12:0) after 1m0s
[21:15:52.341] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:12:0)
[21:15:52.341] Timer tick, asking Buildbucket for the build status
[21:15:52.544] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:52.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:13:0) after 1m0s
[21:16:52.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:13:0)
[21:16:52.626] Timer tick, asking Buildbucket for the build status
[21:16:52.716] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:52.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:14:0) after 1m0s
[21:17:52.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:14:0)
[21:17:52.898] Timer tick, asking Buildbucket for the build status
[21:17:53.047] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:53.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:15:0) after 1m0s
[21:18:53.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:15:0)
[21:18:53.251] Timer tick, asking Buildbucket for the build status
[21:18:53.342] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:53.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:16:0) after 1m0s
[21:19:53.391] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:16:0)
[21:19:53.391] Timer tick, asking Buildbucket for the build status
[21:19:53.527] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:53.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:17:0) after 1m0s
[21:20:53.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:17:0)
[21:20:53.646] Timer tick, asking Buildbucket for the build status
[21:20:53.811] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:53.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:18:0) after 1m0s
[21:21:53.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:18:0)
[21:21:53.865] Timer tick, asking Buildbucket for the build status
[21:21:55.202] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:55.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:19:0) after 1m0s
[21:22:55.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:19:0)
[21:22:55.279] Timer tick, asking Buildbucket for the build status
[21:22:55.721] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:55.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:20:0) after 1m0s
[21:23:55.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:20:0)
[21:23:55.804] Timer tick, asking Buildbucket for the build status
[21:23:55.898] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:55.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:21:0) after 1m0s
[21:24:56.071] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:21:0)
[21:24:56.071] Timer tick, asking Buildbucket for the build status
[21:24:56.186] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:56.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:22:0) after 1m0s
[21:25:56.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:22:0)
[21:25:56.429] Timer tick, asking Buildbucket for the build status
[21:25:56.614] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:56.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:23:0) after 1m0s
[21:26:56.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:23:0)
[21:26:56.654] Timer tick, asking Buildbucket for the build status
[21:26:56.772] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:56.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:24:0) after 1m0s
[21:27:56.821] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:24:0)
[21:27:56.821] Timer tick, asking Buildbucket for the build status
[21:27:56.990] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:56.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:25:0) after 1m0s
[21:28:56.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:25:0)
[21:28:56.995] Timer tick, asking Buildbucket for the build status
[21:28:57.554] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:57.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:26:0) after 1m0s
[21:29:57.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:26:0)
[21:29:57.612] Timer tick, asking Buildbucket for the build status
[21:29:57.895] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:57.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:27:0) after 1m0s
[21:30:58.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:27:0)
[21:30:58.032] Timer tick, asking Buildbucket for the build status
[21:30:58.319] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:58.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:28:0) after 1m0s
[21:31:58.344] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:28:0)
[21:31:58.344] Timer tick, asking Buildbucket for the build status
[21:31:58.562] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:58.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:29:0) after 1m0s
[21:32:58.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:29:0)
[21:32:58.675] Timer tick, asking Buildbucket for the build status
[21:32:58.866] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:58.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:30:0) after 1m0s
[21:33:58.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:30:0)
[21:33:58.952] Timer tick, asking Buildbucket for the build status
[21:33:59.123] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:59.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:31:0) after 1m0s
[21:34:59.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:31:0)
[21:34:59.169] Timer tick, asking Buildbucket for the build status
[21:34:59.280] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:59.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:32:0) after 1m0s
[21:35:59.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:32:0)
[21:35:59.308] Timer tick, asking Buildbucket for the build status
[21:35:59.506] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:59.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:33:0) after 1m0s
[21:36:59.559] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:33:0)
[21:36:59.559] Timer tick, asking Buildbucket for the build status
[21:36:59.687] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:59.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:34:0) after 1m0s
[21:37:59.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:34:0)
[21:37:59.719] Timer tick, asking Buildbucket for the build status
[21:37:59.931] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:59.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:35:0) after 1m0s
[21:38:59.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:35:0)
[21:38:59.936] Timer tick, asking Buildbucket for the build status
[21:39:00.213] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:00.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:36:0) after 1m0s
[21:40:00.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:36:0)
[21:40:00.251] Timer tick, asking Buildbucket for the build status
[21:40:00.382] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:00.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:37:0) after 1m0s
[21:41:00.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:37:0)
[21:41:00.397] Timer tick, asking Buildbucket for the build status
[21:41:00.882] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:00.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:38:0) after 1m0s
[21:42:00.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:38:0)
[21:42:00.910] Timer tick, asking Buildbucket for the build status
[21:42:01.277] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:01.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:39:0) after 1m0s
[21:43:01.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:39:0)
[21:43:01.323] Timer tick, asking Buildbucket for the build status
[21:43:01.412] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:01.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:40:0) after 1m0s
[21:44:01.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:40:0)
[21:44:01.435] Timer tick, asking Buildbucket for the build status
[21:44:02.327] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:02.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:41:0) after 1m0s
[21:45:02.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:41:0)
[21:45:02.870] Timer tick, asking Buildbucket for the build status
[21:45:03.002] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:03.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:42:0) after 1m0s
[21:46:03.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:42:0)
[21:46:03.094] Timer tick, asking Buildbucket for the build status
[21:46:04.114] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:04.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:43:0) after 1m0s
[21:47:04.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:43:0)
[21:47:04.146] Timer tick, asking Buildbucket for the build status
[21:47:04.229] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:04.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:44:0) after 1m0s
[21:48:04.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:44:0)
[21:48:04.387] Timer tick, asking Buildbucket for the build status
[21:48:04.608] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:04.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:45:0) after 1m0s
[21:49:04.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:45:0)
[21:49:04.613] Timer tick, asking Buildbucket for the build status
[21:49:04.793] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:04.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:46:0) after 1m0s
[21:50:04.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:46:0)
[21:50:04.884] Timer tick, asking Buildbucket for the build status
[21:50:07.458] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:07.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:47:0) after 1m0s
[21:51:07.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:47:0)
[21:51:07.521] Timer tick, asking Buildbucket for the build status
[21:51:07.687] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:07.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:48:0) after 1m0s
[21:52:07.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:48:0)
[21:52:07.715] Timer tick, asking Buildbucket for the build status
[21:52:07.896] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:07.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:49:0) after 1m0s
[21:53:07.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:49:0)
[21:53:07.959] Timer tick, asking Buildbucket for the build status
[21:53:08.096] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:08.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:50:0) after 1m0s
[21:54:08.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:50:0)
[21:54:08.183] Timer tick, asking Buildbucket for the build status
[21:54:08.307] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:08.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:51:0) after 1m0s
[21:55:08.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:51:0)
[21:55:08.360] Timer tick, asking Buildbucket for the build status
[21:55:10.063] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:10.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:52:0) after 1m0s
[21:56:10.103] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:52:0)
[21:56:10.103] Timer tick, asking Buildbucket for the build status
[21:56:10.374] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:10.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:53:0) after 1m0s
[21:57:10.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:53:0)
[21:57:10.411] Timer tick, asking Buildbucket for the build status
[21:57:11.582] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:11.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:54:0) after 1m0s
[21:58:11.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:54:0)
[21:58:11.623] Timer tick, asking Buildbucket for the build status
[21:58:11.933] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:11.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:55:0) after 1m0s
[21:59:12.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:55:0)
[21:59:12.043] Timer tick, asking Buildbucket for the build status
[21:59:12.158] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:12.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:56:0) after 1m0s
[22:00:12.563] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:56:0)
[22:00:12.563] Timer tick, asking Buildbucket for the build status
[22:00:12.700] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:12.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:57:0) after 1m0s
[22:01:12.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:57:0)
[22:01:12.978] Timer tick, asking Buildbucket for the build status
[22:01:13.415] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:13.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:58:0) after 1m0s
[22:02:13.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:58:0)
[22:02:13.465] Timer tick, asking Buildbucket for the build status
[22:02:13.557] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:13.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:59:0) after 1m0s
[22:03:13.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:59:0)
[22:03:13.575] Timer tick, asking Buildbucket for the build status
[22:03:13.682] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:13.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:60:0) after 1m0s
[22:04:13.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:60:0)
[22:04:13.734] Timer tick, asking Buildbucket for the build status
[22:04:13.819] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:13.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:61:0) after 1m0s
[22:05:13.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:61:0)
[22:05:13.841] Timer tick, asking Buildbucket for the build status
[22:05:13.916] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:13.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:62:0) after 1m0s
[22:06:13.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:62:0)
[22:06:13.954] Timer tick, asking Buildbucket for the build status
[22:06:14.326] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:14.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:63:0) after 1m0s
[22:07:14.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:63:0)
[22:07:14.433] Timer tick, asking Buildbucket for the build status
[22:07:14.544] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:14.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:64:0) after 1m0s
[22:08:14.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:64:0)
[22:08:14.587] Timer tick, asking Buildbucket for the build status
[22:08:15.011] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:15.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:65:0) after 1m0s
[22:09:15.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:65:0)
[22:09:15.046] Timer tick, asking Buildbucket for the build status
[22:09:15.178] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:15.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:66:0) after 1m0s
[22:10:15.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:66:0)
[22:10:15.267] Timer tick, asking Buildbucket for the build status
[22:10:15.499] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:15.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:67:0) after 1m0s
[22:11:15.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:67:0)
[22:11:15.528] Timer tick, asking Buildbucket for the build status
[22:11:15.704] Build 8861901105240443136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:15.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027331167726453328:68:0) after 1m0s
[22:11:33.141] Received PubSub notification, asking Buildbucket for the build status
[22:11:33.226] Build 8861901105240443136: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:11:33.226] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1607033491748852",
"created_by": "project:chromium",
"created_ts": "1607029543608860",
"id": "8861901105240443136",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6089b400ece07b2e778b8d3c9bb29636b7a75b48\"}}",
"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\": \"6089b400ece07b2e778b8d3c9bb29636b7a75b48\", \"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\": 16011, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"19a302fca592e3825998b78b7d7d075c51f3c4f6\", \"got_dawn_revision\": \"5d8a071433779a3da828e5e8577be8258ef957c3\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"6089b400ece07b2e778b8d3c9bb29636b7a75b48\", \"got_revision_cp\": \"refs/heads/master@{#833440}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"bae138de13ac876cbca0e30a40b89dc808e2b29d\", \"got_v8_revision\": \"a6237ff2be9be3c84f26f23ddcd6e15c857672ab\", \"got_v8_revision_cp\": \"refs/heads/8.9.93@{#1}\", \"got_webrtc_revision\": \"f65a003f7f2b442023a3431e6170a744202579c3\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32759}\", \"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\": \"6089b400ece07b2e778b8d3c9bb29636b7a75b48\", \"swarm_hashes_refs/heads/master(at){#833440}_without_patch\": {\"absl_hardening_tests\": \"bf8bfbfe230562a2ad13751c274d271764beb192\", \"accessibility_unittests\": \"4a974aff72935b4c6cc5fe9f4a21482cc341364e\", \"angle_unittests\": \"6b81db2cd6d2fdd1052e07eda37fc916ab9d64b9\", \"app_shell_unittests\": \"9d15ef56f23e9f9934b0525f4f4e959b08101efc\", \"aura_unittests\": \"1aaf9df2f664d81c4ea4329b909a91c6c9942ca7\", \"base_unittests\": \"5df78f24bf4c687cb6173957b379f687f7ab652c\", \"base_util_unittests\": \"1af0489723491f0f397041178fec933c3786a276\", \"blink_common_unittests\": \"e1523d739ccf82e41512e1e7d37b4352bd6f1149\", \"blink_fuzzer_unittests\": \"a077ecb9c9607b3047582da48f421c0fec99356a\", \"blink_heap_unittests\": \"ab8c0c7043d172d05ce416d37d6470cd1e3a0b27\", \"blink_platform_unittests\": \"1654cd324e8e85bc81b7a549411e09557d117df1\", \"blink_unittests\": \"7e87d256cd76e7847f9afa675ccd4fe901321b7f\", \"boringssl_crypto_tests\": \"9cf4b66be095450cd6037e5930cf8ef06ff26b4e\", \"boringssl_ssl_tests\": \"9052a5b7048765989452ba4b3dfaf06c40a2d25c\", \"browser_switcher_bho_unittests\": \"7534635bc77d6abc7f338f5678d60d76b29995dd\", \"browser_tests\": \"eee0a543359fe6af1f17b2d4eb3de66d4ce14017\", \"capture_unittests\": \"e3ab1e85f45eaede8133b025c94d77b5ae57ef35\", \"cast_unittests\": \"db132a4edf954a53f201a027718bc742eaf519f6\", \"cc_unittests\": \"2946648768bc55443355e26d6cc1582476216605\", \"chrome_app_unittests\": \"8b441c49d45c0cffa3263c7025bc5f2f6fe63553\", \"chrome_cleaner_unittests\": \"87280369811806b29a8f5d003788aa0bb59c5e40\", \"chrome_elf_unittests\": \"f61a65aac0cf74b5725336ae446ce7dee1f8aaf4\", \"chromedriver_unittests\": \"3f8c3277ab98bc9e1344aa6a7eb17bd7e8a4fc68\", \"components_browsertests\": \"c07350a7229920085fa4cb460e0e4ff543c52316\", \"components_unittests\": \"6ac3e10463cfc7739055fb59ee525293df6c19d2\", \"compositor_unittests\": \"61ef0c25ee7c4d9127914acab7fed7d4109b1272\", \"content_browsertests\": \"13d1c44ae9cda00b116416659cd4734352b599ae\", \"content_unittests\": \"c7d36b8b841e699574cbb7bc240d4ca02dd637c1\", \"courgette_unittests\": \"0a2ab0d882c55d248eed2dc08c38ef83bf231608\", \"crashpad_tests\": \"0eeec9c4e1cac2d8d4c6a9198f1a832225ea5a64\", \"cronet_tests\": \"e8727d21408626136a9fcd0d3f76cffcdc23b40f\", \"cronet_unittests\": \"ffe5c4d3cf1cf1ca392d9e36f5922bf91e60e84e\", \"crypto_unittests\": \"6257caa49eedfaef42639ef6fdf065c4e0a7047b\", \"delayloads_unittests\": \"53c39452cbf1452cb2bb18bbf6896b64a57dbaef\", \"device_unittests\": \"72f04dc989ff34135df685392f2551a83c6d0ed8\", \"display_unittests\": \"953b3c007ea2dedc0f414a0150292f18f86c64aa\", \"elevation_service_unittests\": \"66c6c3f48c798315e74fcccfd9afce3c6608e949\", \"events_unittests\": \"fca985451cb7f08a193b9f1ab4ee9d2f1ea42d2a\", \"extensions_browsertests\": \"fb987573446e378d909b4c02f5998a2f66c201d4\", \"extensions_unittests\": \"352791384898be882144ffa7d72270f595c0e764\", \"filesystem_service_unittests\": \"abd15b89da471079e3bf181ff469158b17374039\", \"gcm_unit_tests\": \"351d523ae83b0d03c94e8d73d9f72d2f0eb2f775\", \"gcp_unittests\": \"d66b7aca40aa5f14635ec26a18b422b6eacf9028\", \"gfx_unittests\": \"fc0ec2cef395ffe60a4e3b2653a599a355306460\", \"gin_unittests\": \"7db60a22e5d27d493b2b264936b36f08d7b52eb6\", \"google_apis_unittests\": \"b8e873ddc25af80e1edd0502b6eaa62df620362a\", \"gpu_unittests\": \"e56ede6e71f14dc5a0331589fda626dc3fd0de6b\", \"gwp_asan_unittests\": \"c3f83a56b29ce6b0ec4a0bd335865c6f44dac582\", \"headless_browsertests\": \"de35e9846831acb0d9aecdb3590ce86b64086a8d\", \"headless_unittests\": \"a8ef90c3b65060a5f1570a0167738040e050c232\", \"install_static_unittests\": \"799eadcd92e0e8a58c5da26c87dd4bbdef9be180\", \"installer_util_unittests\": \"5d7edc79c2dec302244994600516e409b2373b4d\", \"interactive_ui_tests\": \"24f9ac4366d39e120030160c1091851a0719c004\", \"ipc_tests\": \"b9cd1a628b57517f497e93aa1ffda62c91a6105b\", \"jingle_unittests\": \"9fbbb6fd2407eea3d7835293b66906b9253f9fe6\", \"latency_unittests\": \"90569061792346f7cbc7d7b4a22302f81b6412c3\", \"libjingle_xmpp_unittests\": \"2de831d26e80cad7e8912ac6806666f56c548833\", \"liburlpattern_unittests\": \"6ca2ec1d3b26b16cb3a457276ca4d8ca3f38e01a\", \"media_blink_unittests\": \"22b46e758a4eb21fbc2d23902f830e81226b8448\", \"media_unittests\": \"61a9b041aa4a9ee7fb2763c348ac4b36dad51420\", \"message_center_unittests\": \"31bed9e3ffeab39d372fb5ce453c65424fd067e0\", \"midi_unittests\": \"5e75fccbe2ce13f070575f8e7c02fbf198ecf016\", \"mojo_core_unittests\": \"9b9ab6c87fbe915628cfd1249eff2f433d0e0a28\", \"mojo_unittests\": \"f712647481fc5194d159a7d95c8be792e9c7747b\", \"nacl_loader_unittests\": \"62eb8c785cb0bc1040dd62a23550a87b4944be94\", \"native_theme_unittests\": \"3630bbbda9e6d97f6b1ed09acca4421196c6362e\", \"net_unittests\": \"878083064f0072fc96a4e868219cc7349b6a3099\", \"notification_helper_unittests\": \"acf74d98f112a468ec0f0ac6025c07e551399af8\", \"pdf_unittests\": \"c71fd752468deb28d1c87809f3bca1c8e229bd15\", \"perfetto_unittests\": \"ccbd1ff3698f53f1fba9a3905c858525b8234017\", \"ppapi_unittests\": \"cd7549e632bd08659e69106968c86ce3e5f80812\", \"printing_unittests\": \"b4defdb94f788195966f9b2918754bf43ffa40ad\", \"remoting_unittests\": \"f6d91c0cab14ec813bc736c100547c30a9a174cc\", \"sbox_integration_tests\": \"8e02cae08b163680996b065ef6cf8d3dca2db72c\", \"sbox_unittests\": \"edaf225a2c199ea670d574abbcb4b7981abce1b4\", \"sbox_validation_tests\": \"00a766e315004d473c081f7a9bceeee3432ae781\", \"service_manager_unittests\": \"0eb1c1a65c25731b9cec8617fb4e694007b6d152\", \"services_unittests\": \"d23f88b429e980d570ecf04ab1716df5f4eda6da\", \"setup_unittests\": \"4fd4888029c34f736d9b79d5f521e5cfe7c1a22e\", \"shell_dialogs_unittests\": \"6e5fea11759f5542aa9eac830797038f1b6029b7\", \"skia_unittests\": \"de12443bd4801426f355ba8e26cce27147a7a028\", \"snapshot_unittests\": \"67b3aa1c3d92e0fb0989b1df39751599bec94d37\", \"sql_unittests\": \"c05ae8a82f579c090eedc83135193e35c6212b12\", \"storage_unittests\": \"45703b0c66bedc5c0ff42aca2ce1257249d42128\", \"sync_integration_tests\": \"a482cdca08ae909818187bb9e6cc9ae6b5d777f2\", \"traffic_annotation_auditor_unittests\": \"471dea7ac7cfb3826693916c137e55ab48213c44\", \"ui_base_unittests\": \"ed130743b153c13873112c7d9b6f29749366b2fc\", \"ui_touch_selection_unittests\": \"7a8a9e587448c9581a26f956565d39565a09a67c\", \"unit_tests\": \"29a6d92d7cd35cb4ac63801280442b0624d7392a\", \"updater_tests\": \"747c0782b7c6d94262eb44c3ff1c76618e093ed3\", \"url_unittests\": \"6475427e1048d5128dd0d8511c4e7e62f05692ac\", \"views_unittests\": \"aa23484efa47075bf1e3218e12bd7412b72e7cbb\", \"viz_unittests\": \"d2e3ca95d532d489b043336ec5182d7f5d85a36a\", \"vr_common_unittests\": \"37a11780b3d9f2355678d70a7854e80b53b7b6d3\", \"vr_pixeltests\": \"d7daa20b3ac5755584e3b07229e4bca24e7d3da7\", \"weblayer_browsertests\": \"849db0fea1ee56bd0df81211d5d1bd22c7d2e441\", \"weblayer_unittests\": \"8a5e3ed47cbe7958d6ab7077c552599f4a18bf2c\", \"wm_unittests\": \"551951bf0520a7d71c63032150cadce6d3502283\", \"wtf_unittests\": \"19e7ac0b4a0a812e2ed1c90e4308c6df27791ede\", \"zlib_unittests\": \"f0960a3cb107753b02575aa438b154de7e6925c6\", \"zucchini_unittests\": \"6d551068f00ec3602e0038e99990f85d45ad98bd\"}}, \"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": "1607029549796021",
"status": "COMPLETED",
"status_changed_ts": "1607033492079224",
"tags": [
"build_address:luci.chromium.ci/win-asan/16011",
"builder:win-asan",
"buildset:commit/git/6089b400ece07b2e778b8d3c9bb29636b7a75b48",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6089b400ece07b2e778b8d3c9bb29636b7a75b48",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027331167726453328",
"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/8861901105240443136/+/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:50433e68f0e62a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1607033492091421",
"url": "https://ci.chromium.org/b/8861901105240443136",
"utcnow_ts": "1607033493215504"
}
}
[22:11:33.226] Invocation finished in 1h5m51.372865452s with status SUCCEEDED