[09:46:21.322] New invocation is queued and will start shortly
[09:46:22.425] Starting the invocation (attempt 1)
[09:46:22.464] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:46:22.464] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:46:22.464] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028008087022088704",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6957365356715241b1acc4afd8488586507bbb50\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028008087022088704",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/6957365356715241b1acc4afd8488586507bbb50",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6957365356715241b1acc4afd8488586507bbb50",
"gitiles_ref:refs/heads/master"
]
}
[09:46:23.234] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606383982567716",
"id": "8862578025053571808",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6957365356715241b1acc4afd8488586507bbb50\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606383983077783",
"tags": [
"build_address:luci.chromium.ci/win-asan/15860",
"builder:win-asan",
"buildset:commit/git/6957365356715241b1acc4afd8488586507bbb50",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6957365356715241b1acc4afd8488586507bbb50",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028008087022088704",
"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/8862578025053571808/+/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": "1606383983077909",
"url": "https://ci.chromium.org/b/8862578025053571808",
"utcnow_ts": "1606383983225042"
}
}
[09:46:23.234] Task URL: https://ci.chromium.org/b/8862578025053571808
[09:46:23.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:2:0) after 1m0s
[09:46:27.194] Received PubSub notification, asking Buildbucket for the build status
[09:46:27.267] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:23.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:2:0)
[09:47:23.236] Timer tick, asking Buildbucket for the build status
[09:47:23.360] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:23.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:4:0) after 1m0s
[09:48:23.380] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:4:0)
[09:48:23.380] Timer tick, asking Buildbucket for the build status
[09:48:23.488] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:23.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:5:0) after 1m0s
[09:49:23.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:5:0)
[09:49:23.509] Timer tick, asking Buildbucket for the build status
[09:49:23.628] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:23.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:6:0) after 1m0s
[09:50:23.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:6:0)
[09:50:23.625] Timer tick, asking Buildbucket for the build status
[09:50:24.109] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:24.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:7:0) after 1m0s
[09:51:24.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:7:0)
[09:51:24.132] Timer tick, asking Buildbucket for the build status
[09:51:24.231] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:24.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:8:0) after 1m0s
[09:52:24.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:8:0)
[09:52:24.449] Timer tick, asking Buildbucket for the build status
[09:52:24.587] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:24.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:9:0) after 1m0s
[09:53:24.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:9:0)
[09:53:24.655] Timer tick, asking Buildbucket for the build status
[09:53:24.714] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:24.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:10:0) after 1m0s
[09:54:24.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:10:0)
[09:54:24.750] Timer tick, asking Buildbucket for the build status
[09:54:24.886] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:24.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:11:0) after 1m0s
[09:55:24.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:11:0)
[09:55:24.907] Timer tick, asking Buildbucket for the build status
[09:55:25.227] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:25.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:12:0) after 1m0s
[09:56:25.249] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:12:0)
[09:56:25.249] Timer tick, asking Buildbucket for the build status
[09:56:25.427] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:25.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:13:0) after 1m0s
[09:57:25.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:13:0)
[09:57:25.456] Timer tick, asking Buildbucket for the build status
[09:57:25.582] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:25.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:14:0) after 1m0s
[09:58:25.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:14:0)
[09:58:25.617] Timer tick, asking Buildbucket for the build status
[09:58:25.710] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:25.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:15:0) after 1m0s
[09:59:25.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:15:0)
[09:59:25.789] Timer tick, asking Buildbucket for the build status
[09:59:25.936] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:25.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:16:0) after 1m0s
[10:00:25.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:16:0)
[10:00:25.992] Timer tick, asking Buildbucket for the build status
[10:00:26.378] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:26.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:17:0) after 1m0s
[10:01:26.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:17:0)
[10:01:26.442] Timer tick, asking Buildbucket for the build status
[10:01:26.751] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:26.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:18:0) after 1m0s
[10:02:26.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:18:0)
[10:02:26.809] Timer tick, asking Buildbucket for the build status
[10:02:27.015] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:27.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:19:0) after 1m0s
[10:03:27.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:19:0)
[10:03:27.034] Timer tick, asking Buildbucket for the build status
[10:03:27.158] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:27.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:20:0) after 1m0s
[10:04:27.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:20:0)
[10:04:27.135] Timer tick, asking Buildbucket for the build status
[10:04:27.828] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:27.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:21:0) after 1m0s
[10:05:27.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:21:0)
[10:05:27.848] Timer tick, asking Buildbucket for the build status
[10:05:27.985] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:27.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:22:0) after 1m0s
[10:06:28.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:22:0)
[10:06:28.007] Timer tick, asking Buildbucket for the build status
[10:06:28.127] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:28.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:23:0) after 1m0s
[10:07:28.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:23:0)
[10:07:28.126] Timer tick, asking Buildbucket for the build status
[10:07:28.255] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:28.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:24:0) after 1m0s
[10:08:28.293] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:24:0)
[10:08:28.293] Timer tick, asking Buildbucket for the build status
[10:08:28.915] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:28.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:25:0) after 1m0s
[10:09:29.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:25:0)
[10:09:29.028] Timer tick, asking Buildbucket for the build status
[10:09:29.106] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:29.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:26:0) after 1m0s
[10:10:29.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:26:0)
[10:10:29.183] Timer tick, asking Buildbucket for the build status
[10:10:29.360] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:29.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:27:0) after 1m0s
[10:11:29.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:27:0)
[10:11:29.460] Timer tick, asking Buildbucket for the build status
[10:11:30.039] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:30.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:28:0) after 1m0s
[10:12:30.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:28:0)
[10:12:30.053] Timer tick, asking Buildbucket for the build status
[10:12:30.265] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:30.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:29:0) after 1m0s
[10:13:30.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:29:0)
[10:13:30.269] Timer tick, asking Buildbucket for the build status
[10:13:30.463] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:30.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:30:0) after 1m0s
[10:14:30.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:30:0)
[10:14:30.483] Timer tick, asking Buildbucket for the build status
[10:14:30.591] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:30.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:31:0) after 1m0s
[10:15:30.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:31:0)
[10:15:30.613] Timer tick, asking Buildbucket for the build status
[10:15:30.688] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:30.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:32:0) after 1m0s
[10:16:30.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:32:0)
[10:16:30.726] Timer tick, asking Buildbucket for the build status
[10:16:31.083] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:31.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:33:0) after 1m0s
[10:17:31.083] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:33:0)
[10:17:31.083] Timer tick, asking Buildbucket for the build status
[10:17:31.163] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:31.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:34:0) after 1m0s
[10:18:31.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:34:0)
[10:18:31.190] Timer tick, asking Buildbucket for the build status
[10:18:31.933] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:31.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:35:0) after 1m0s
[10:19:31.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:35:0)
[10:19:31.936] Timer tick, asking Buildbucket for the build status
[10:19:32.332] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:32.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:36:0) after 1m0s
[10:20:32.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:36:0)
[10:20:32.389] Timer tick, asking Buildbucket for the build status
[10:20:32.461] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:32.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:37:0) after 1m0s
[10:21:32.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:37:0)
[10:21:32.483] Timer tick, asking Buildbucket for the build status
[10:21:32.620] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:32.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:38:0) after 1m0s
[10:22:32.637] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:38:0)
[10:22:32.637] Timer tick, asking Buildbucket for the build status
[10:22:32.744] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:32.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:39:0) after 1m0s
[10:23:32.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:39:0)
[10:23:32.788] Timer tick, asking Buildbucket for the build status
[10:23:32.891] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:32.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:40:0) after 1m0s
[10:24:32.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:40:0)
[10:24:32.955] Timer tick, asking Buildbucket for the build status
[10:24:33.678] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:33.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:41:0) after 1m0s
[10:25:33.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:41:0)
[10:25:33.701] Timer tick, asking Buildbucket for the build status
[10:25:33.772] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:33.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:42:0) after 1m0s
[10:26:33.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:42:0)
[10:26:33.824] Timer tick, asking Buildbucket for the build status
[10:26:34.197] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:34.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:43:0) after 1m0s
[10:27:34.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:43:0)
[10:27:34.252] Timer tick, asking Buildbucket for the build status
[10:27:34.355] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:34.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:44:0) after 1m0s
[10:28:34.416] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:44:0)
[10:28:34.416] Timer tick, asking Buildbucket for the build status
[10:28:34.871] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:34.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:45:0) after 1m0s
[10:29:34.908] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:45:0)
[10:29:34.908] Timer tick, asking Buildbucket for the build status
[10:29:35.254] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:35.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:46:0) after 1m0s
[10:30:35.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:46:0)
[10:30:35.311] Timer tick, asking Buildbucket for the build status
[10:30:35.460] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:35.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:47:0) after 1m0s
[10:31:35.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:47:0)
[10:31:35.483] Timer tick, asking Buildbucket for the build status
[10:31:35.773] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:35.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:48:0) after 1m0s
[10:32:35.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:48:0)
[10:32:35.751] Timer tick, asking Buildbucket for the build status
[10:32:36.143] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:36.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:49:0) after 1m0s
[10:33:36.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:49:0)
[10:33:36.208] Timer tick, asking Buildbucket for the build status
[10:33:36.276] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:36.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:50:0) after 1m0s
[10:34:36.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:50:0)
[10:34:36.312] Timer tick, asking Buildbucket for the build status
[10:34:36.407] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:36.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:51:0) after 1m0s
[10:35:36.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:51:0)
[10:35:36.398] Timer tick, asking Buildbucket for the build status
[10:35:36.476] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:36.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:52:0) after 1m0s
[10:36:36.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:52:0)
[10:36:36.534] Timer tick, asking Buildbucket for the build status
[10:36:36.679] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:36.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:53:0) after 1m0s
[10:37:36.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:53:0)
[10:37:36.676] Timer tick, asking Buildbucket for the build status
[10:37:36.777] Build 8862578025053571808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:36.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028008087022088704:54:0) after 1m0s
[10:38:27.163] Received PubSub notification, asking Buildbucket for the build status
[10:38:27.752] Build 8862578025053571808: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:38:27.752] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606387106307702",
"created_by": "project:chromium",
"created_ts": "1606383982567716",
"id": "8862578025053571808",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6957365356715241b1acc4afd8488586507bbb50\"}}",
"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\": \"6957365356715241b1acc4afd8488586507bbb50\", \"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-6-2gr3\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15860, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"5850c748b480ae13159323ce2b3a09a36355711b\", \"got_dawn_revision\": \"9175f00eec91c6c9b3c3e264d6d8ec7879881844\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"6957365356715241b1acc4afd8488586507bbb50\", \"got_revision_cp\": \"refs/heads/master@{#831291}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"43bb60e1fa119d80b449c6550dd2b72328b101b9\", \"got_v8_revision\": \"1166840df5216851f3f7d9f6e16f5d0ee3c08cbe\", \"got_v8_revision_cp\": \"refs/heads/8.9.54@{#1}\", \"got_webrtc_revision\": \"b6b599fc588e59142df343d2d2897203468aacea\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32695}\", \"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\": \"6957365356715241b1acc4afd8488586507bbb50\", \"swarm_hashes_refs/heads/master(at){#831291}_without_patch\": {\"absl_hardening_tests\": \"4918cbf3c3edab1c87acb76a873882c9292f27b4\", \"accessibility_unittests\": \"ed480a346a9b2744d8f5759369378657c4c341f6\", \"angle_unittests\": \"7ec223fbb3c8a3b555bbedfcf36a41eeb702bfa1\", \"app_shell_unittests\": \"f3900577d7bb95738b8ee5852fae0e0aa22122b2\", \"aura_unittests\": \"288915b40bdc3e196eba3731f67189cca3cc1685\", \"base_unittests\": \"f80780370bdf5921f2bd12680fde8ff65b675c1f\", \"base_util_unittests\": \"f2faad506e8960d05b5a556e4601ca50481753c0\", \"blink_common_unittests\": \"b1cc199dab1af0563c99b0fd1ed57cfa25d5a470\", \"blink_fuzzer_unittests\": \"b27b86a20523d85872ce2feba175b128d055af34\", \"blink_heap_unittests\": \"257539fe06a4f0060f3221b12c2c9a0d3fed8070\", \"blink_platform_unittests\": \"bf4e11943b047f453e4f059432c34a768fa99af6\", \"blink_unittests\": \"45957b3b21d1510964e30cff83fa189565ea05e5\", \"boringssl_crypto_tests\": \"0e827e55fc4e902fa13394e2f03e33411768509b\", \"boringssl_ssl_tests\": \"f5bafeaad5a9507e0f4a3d43b17cad33e6abed40\", \"browser_switcher_bho_unittests\": \"a5bb0313c3a39326e0b81dcd07d392ff5108f11d\", \"browser_tests\": \"4fac5ef24f07eecf5edf8cff33d64b316e76d4c2\", \"capture_unittests\": \"c660562fc5c55235d57faa867958403e3ccea5d1\", \"cast_unittests\": \"e16222dca1a737c4257352cda326bc9629fb2738\", \"cc_unittests\": \"4ec79a2a288f686ef4e2f251c81c41fdaafbc60f\", \"chrome_app_unittests\": \"53cf74185c60e1a537a3668ab45efeaff23660ed\", \"chrome_cleaner_unittests\": \"e7fec739ca8d4226646617c0d77a551c2524fa6a\", \"chrome_elf_unittests\": \"462049e6c3436eba44bf5660e9c54f4129b88469\", \"chromedriver_unittests\": \"25eb242ff52791ec81dfb345862c0e53ce86c9b3\", \"components_browsertests\": \"cd74063ee5f233f2e963c65de6deab077c33fa03\", \"components_unittests\": \"f99fa627a16fd76940e354422dbf972e0bfec42c\", \"compositor_unittests\": \"a9b3b07b25369213bb1432ee6570ce54ef3025c2\", \"content_browsertests\": \"c149187534d915e6cf3ef0d38cafd76f16352470\", \"content_unittests\": \"7187538e23bad04d896b9f49270b8446db550aaa\", \"courgette_unittests\": \"284223375255c857f179d5b5d744b7d8c7e0a7da\", \"crashpad_tests\": \"4911d21512ce5f37058f487327aac13a35b0f9b0\", \"cronet_tests\": \"54b472d33e8451fa4f3257aba70ce09fc49f8a87\", \"cronet_unittests\": \"1bf70783610d4292df3233518fc3519c004f4573\", \"crypto_unittests\": \"b60c8b269ab5cbd5d16515aed6b5c6eb80a9d8cf\", \"delayloads_unittests\": \"8af2195b5151b1f9920fb45910ee89c09fb785c1\", \"device_unittests\": \"351327eae48fa9928718836420b71e02b6e9b50d\", \"display_unittests\": \"5e54cc9caed28fc9023e9ccde9accfa16d4d354d\", \"elevation_service_unittests\": \"e7be7050c5abbc9a11e6d965d02df6c26876cb24\", \"events_unittests\": \"a464ee78e4f529acffbd9d6832b72d1e7dc00ec5\", \"extensions_browsertests\": \"b452178b24b9ee04dd40300a4066b91bef0184d4\", \"extensions_unittests\": \"4b375b100a9287ece97e624ce2fb72733358ac8f\", \"filesystem_service_unittests\": \"a9f9e7617bccdc1fd98da88c5146c0a3ab9e894e\", \"gcm_unit_tests\": \"bc7cd054857f9a705a6053b40bb727a19a17750a\", \"gcp_unittests\": \"3738dfa79c5b99c38f463063c77c59403684c5c7\", \"gfx_unittests\": \"063f02bd52d013f19789a390f6e10da7052d6347\", \"gin_unittests\": \"bdd822e9069b06ad317a92cfa993b2dc84f0f104\", \"google_apis_unittests\": \"3de730f93e899e46428742046a5727e0fb071e1a\", \"gpu_unittests\": \"050a2ffe503e6cc7b8af9569ae335bc40a1004ad\", \"gwp_asan_unittests\": \"442cc9d9695a33aa9e4b47cfe7691a62ac1cff6a\", \"headless_browsertests\": \"cecb3ceac293fe66bf48a4c3510ec87f82b476b0\", \"headless_unittests\": \"11636eed3acbdbf5ab65924eb01c0589ff048687\", \"install_static_unittests\": \"299d718239d35cbe735db999f57ae89df5fdd449\", \"installer_util_unittests\": \"7892bed5544f4e3109b7e729d0b858ea0b54d908\", \"interactive_ui_tests\": \"e981029916b115df2e07474a9cc4eaa6659a08db\", \"ipc_tests\": \"e77ce0d30e157e5ea79679b1aaf0fcce3ced4e53\", \"jingle_unittests\": \"4e0bec42abc6f20297ceeb5752a642c028962ac7\", \"latency_unittests\": \"92624af10b5bf05c18a0fcb15a587c31b8bf3da7\", \"libjingle_xmpp_unittests\": \"704cdaa97564de191c3e23a624cf82ec1b76436a\", \"liburlpattern_unittests\": \"addd143253a68b70bd267c328dc7b278ce399bdc\", \"media_blink_unittests\": \"04b8b7dfb5348d1cbdce4aef23c5aaaa51fc5145\", \"media_unittests\": \"b96dd55e1110227a06f9d6994bdb5f3f2f528354\", \"message_center_unittests\": \"d6eb344f857b9a5138320bbadda139c628623fa0\", \"midi_unittests\": \"a3ca27adf3bd4281c1d88b19df3887b3622adaaf\", \"mojo_core_unittests\": \"8f53b1c31ac6bb56abeae484e1c934caadfd284e\", \"mojo_unittests\": \"d24f47e5c7a79439ae484c97b183f7a25cbb89e6\", \"nacl_loader_unittests\": \"6a8f90ccfb2d94041fcfaf819e958bf943ce9216\", \"native_theme_unittests\": \"7305b15180256090e76491f618853cea8d8be96b\", \"net_unittests\": \"5308ab86ed24ecf29c35a2938281e119cc260b3a\", \"notification_helper_unittests\": \"513b3a7c0ed0191b3a36069ada4e2ed5ce1c6230\", \"pdf_unittests\": \"e5344db0e9be3fba576cfc1f669064a943f282cd\", \"perfetto_unittests\": \"06cc46c36cb35a66cef349ece22cb4a4df0ae921\", \"ppapi_unittests\": \"8df08ff45ac664c5b9a181f5889feae3a6331217\", \"printing_unittests\": \"28fc129a6b5c09a42c991de70a652e9933220ae3\", \"remoting_unittests\": \"089e9e48e631daf8b7b1473aee9fc4bec3efae72\", \"sbox_integration_tests\": \"3d05f8daa22b372a3695f6bba44e92f9c8ff2146\", \"sbox_unittests\": \"8ced15b44acad9236cb2cc4d34b0c9177a59a510\", \"sbox_validation_tests\": \"e774629355cfe75dc4a60b45f98235534d3c76ce\", \"service_manager_unittests\": \"b1d9f2101ea3e716470e8d4f07439fe47127bdb5\", \"services_unittests\": \"fb4c05f3771924e8e09157592bdbbc9d284e2ffb\", \"setup_unittests\": \"23f40427934e3705cb7810339e26ab71a53318f3\", \"shell_dialogs_unittests\": \"1cb5966bbe2451e1f00f013180231aa0eef54ee6\", \"skia_unittests\": \"0871cca4c158beff5fe65c914bea24f05fa03917\", \"snapshot_unittests\": \"ea3f763a35e92b780fd33d207b85f1e020b81c3b\", \"sql_unittests\": \"b8fc17e2e37ec3f190fc44c2997748515fe1d89e\", \"storage_unittests\": \"fa11bedde9ee70dcdd24d43877c18b4235463e3f\", \"sync_integration_tests\": \"c9e31128cccacdb6064a17081a8916616ca8c97b\", \"traffic_annotation_auditor_unittests\": \"722f4c7bbe420316c069fa9428bb68c1ee7218cc\", \"ui_base_unittests\": \"2dc96087474e3e4e3770748cd50cf082842a9630\", \"ui_touch_selection_unittests\": \"b4ff3c8a54b9060f81f3ab9e3bead298f8d09bd5\", \"unit_tests\": \"d67dae059a0428f0fe15403ae603fb34c4f9dbb6\", \"updater_tests\": \"72b405dee23f8815eff94e3e1802f6ce3a27d057\", \"url_unittests\": \"df7e2dad1293c323f2e18a0b339b99bbd2ec33f8\", \"views_unittests\": \"130dcf73eec9cf8cf79ba6e4ef4ee8507111f7e8\", \"viz_unittests\": \"efe7c47e49529e9f319786ecc2bc02aba2164b4b\", \"vr_common_unittests\": \"b0573273cac14985da8b975fa50a1e397ed2f61e\", \"vr_pixeltests\": \"035ba4eac2b407e580e07284a35fd271a8018507\", \"weblayer_browsertests\": \"d39e00686e9103d7252ce138c630db78444993bc\", \"weblayer_unittests\": \"932def27902ebb3782eaf2b90f96ced7cb0c0791\", \"wm_unittests\": \"2e73d4bf78c5f5491d27214d7cd7c671b1db128b\", \"wtf_unittests\": \"c8abb0c7c7efa3e0aed3dc8f13436c7bef97296b\", \"zlib_unittests\": \"ae081cdc6616c7d5495bc914ec29510c4239628f\", \"zucchini_unittests\": \"431fa5b1301e96b6cba4757387fc4406e0a98d86\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_v2\", \"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-6-2gr3\"], \"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": "1606383986457814",
"status": "COMPLETED",
"status_changed_ts": "1606387106575438",
"tags": [
"build_address:luci.chromium.ci/win-asan/15860",
"builder:win-asan",
"buildset:commit/git/6957365356715241b1acc4afd8488586507bbb50",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6957365356715241b1acc4afd8488586507bbb50",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028008087022088704",
"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/8862578025053571808/+/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:501cc3e3ee30fd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1606387106600129",
"url": "https://ci.chromium.org/b/8862578025053571808",
"utcnow_ts": "1606387107739782"
}
}
[10:38:27.752] Invocation finished in 52m6.444841089s with status SUCCEEDED