[15:59:36.820] New invocation is queued and will start shortly
[15:59:37.956] Starting the invocation (attempt 1)
[15:59:37.996] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:59:37.998] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:59:37.998] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025719679482042672",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e21e514dbabae72361cf1aba9400280d01d2a60a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9025719679482042672",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/e21e514dbabae72361cf1aba9400280d01d2a60a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e21e514dbabae72361cf1aba9400280d01d2a60a",
"gitiles_ref:refs/heads/master"
]
}
[15:59:39.094] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608566378084092",
"id": "8860289617492409184",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e21e514dbabae72361cf1aba9400280d01d2a60a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608566378811855",
"tags": [
"build_address:luci.chromium.ci/win-asan/16365",
"builder:win-asan",
"buildset:commit/git/e21e514dbabae72361cf1aba9400280d01d2a60a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e21e514dbabae72361cf1aba9400280d01d2a60a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025719679482042672",
"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/8860289617492409184/+/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": "1608566378812180",
"url": "https://ci.chromium.org/b/8860289617492409184",
"utcnow_ts": "1608566379079122"
}
}
[15:59:39.094] Task URL: https://ci.chromium.org/b/8860289617492409184
[15:59:39.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:2:0) after 1m0s
[15:59:41.374] Received PubSub notification, asking Buildbucket for the build status
[15:59:41.552] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:39.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:2:0)
[16:00:39.142] Timer tick, asking Buildbucket for the build status
[16:00:39.298] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:39.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:4:0) after 1m0s
[16:01:39.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:4:0)
[16:01:39.425] Timer tick, asking Buildbucket for the build status
[16:01:39.536] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:39.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:5:0) after 1m0s
[16:02:39.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:5:0)
[16:02:39.591] Timer tick, asking Buildbucket for the build status
[16:02:39.766] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:39.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:6:0) after 1m0s
[16:03:39.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:6:0)
[16:03:39.795] Timer tick, asking Buildbucket for the build status
[16:03:39.899] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:39.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:7:0) after 1m0s
[16:04:40.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:7:0)
[16:04:40.027] Timer tick, asking Buildbucket for the build status
[16:04:40.138] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:40.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:8:0) after 1m0s
[16:05:40.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:8:0)
[16:05:40.242] Timer tick, asking Buildbucket for the build status
[16:05:40.314] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:40.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:9:0) after 1m0s
[16:06:40.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:9:0)
[16:06:40.337] Timer tick, asking Buildbucket for the build status
[16:06:40.408] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:40.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:10:0) after 1m0s
[16:07:40.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:10:0)
[16:07:40.469] Timer tick, asking Buildbucket for the build status
[16:07:40.677] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:40.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:11:0) after 1m0s
[16:08:40.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:11:0)
[16:08:40.823] Timer tick, asking Buildbucket for the build status
[16:08:40.873] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:40.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:12:0) after 1m0s
[16:09:40.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:12:0)
[16:09:40.880] Timer tick, asking Buildbucket for the build status
[16:09:40.961] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:40.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:13:0) after 1m0s
[16:10:41.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:13:0)
[16:10:41.031] Timer tick, asking Buildbucket for the build status
[16:10:41.193] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:41.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:14:0) after 1m0s
[16:11:41.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:14:0)
[16:11:41.328] Timer tick, asking Buildbucket for the build status
[16:11:41.391] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:41.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:15:0) after 1m0s
[16:12:41.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:15:0)
[16:12:41.460] Timer tick, asking Buildbucket for the build status
[16:12:41.572] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:41.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:16:0) after 1m0s
[16:13:41.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:16:0)
[16:13:41.624] Timer tick, asking Buildbucket for the build status
[16:13:41.733] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:41.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:17:0) after 1m0s
[16:14:41.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:17:0)
[16:14:41.752] Timer tick, asking Buildbucket for the build status
[16:14:41.872] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:41.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:18:0) after 1m0s
[16:15:41.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:18:0)
[16:15:41.969] Timer tick, asking Buildbucket for the build status
[16:15:42.609] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:42.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:19:0) after 1m0s
[16:16:42.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:19:0)
[16:16:42.630] Timer tick, asking Buildbucket for the build status
[16:16:42.747] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:42.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:20:0) after 1m0s
[16:17:42.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:20:0)
[16:17:42.786] Timer tick, asking Buildbucket for the build status
[16:17:42.913] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:42.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:21:0) after 1m0s
[16:18:42.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:21:0)
[16:18:42.939] Timer tick, asking Buildbucket for the build status
[16:18:43.387] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:43.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:22:0) after 1m0s
[16:19:43.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:22:0)
[16:19:43.397] Timer tick, asking Buildbucket for the build status
[16:19:43.746] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:43.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:23:0) after 1m0s
[16:20:43.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:23:0)
[16:20:43.724] Timer tick, asking Buildbucket for the build status
[16:20:43.832] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:43.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:24:0) after 1m0s
[16:21:43.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:24:0)
[16:21:43.855] Timer tick, asking Buildbucket for the build status
[16:21:43.965] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:43.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:25:0) after 1m0s
[16:22:43.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:25:0)
[16:22:43.983] Timer tick, asking Buildbucket for the build status
[16:22:44.103] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:44.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:26:0) after 1m0s
[16:23:44.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:26:0)
[16:23:44.164] Timer tick, asking Buildbucket for the build status
[16:23:44.256] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:44.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:27:0) after 1m0s
[16:24:44.290] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:27:0)
[16:24:44.290] Timer tick, asking Buildbucket for the build status
[16:24:44.515] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:44.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:28:0) after 1m0s
[16:25:44.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:28:0)
[16:25:44.581] Timer tick, asking Buildbucket for the build status
[16:25:44.658] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:44.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:29:0) after 1m0s
[16:26:44.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:29:0)
[16:26:44.699] Timer tick, asking Buildbucket for the build status
[16:26:44.827] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:44.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:30:0) after 1m0s
[16:27:44.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:30:0)
[16:27:44.848] Timer tick, asking Buildbucket for the build status
[16:27:45.061] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:45.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:31:0) after 1m0s
[16:28:45.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:31:0)
[16:28:45.074] Timer tick, asking Buildbucket for the build status
[16:28:45.136] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:45.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:32:0) after 1m0s
[16:29:45.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:32:0)
[16:29:45.200] Timer tick, asking Buildbucket for the build status
[16:29:45.346] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:45.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:33:0) after 1m0s
[16:30:45.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:33:0)
[16:30:45.457] Timer tick, asking Buildbucket for the build status
[16:30:45.522] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:45.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:34:0) after 1m0s
[16:31:45.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:34:0)
[16:31:45.589] Timer tick, asking Buildbucket for the build status
[16:31:45.679] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:45.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:35:0) after 1m0s
[16:32:45.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:35:0)
[16:32:45.717] Timer tick, asking Buildbucket for the build status
[16:32:45.796] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:45.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:36:0) after 1m0s
[16:33:45.819] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:36:0)
[16:33:45.819] Timer tick, asking Buildbucket for the build status
[16:33:46.114] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:46.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:37:0) after 1m0s
[16:34:46.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:37:0)
[16:34:46.143] Timer tick, asking Buildbucket for the build status
[16:34:46.271] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:46.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:38:0) after 1m0s
[16:35:46.307] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:38:0)
[16:35:46.307] Timer tick, asking Buildbucket for the build status
[16:35:46.374] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:46.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:39:0) after 1m0s
[16:36:46.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:39:0)
[16:36:46.413] Timer tick, asking Buildbucket for the build status
[16:36:46.500] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:46.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:40:0) after 1m0s
[16:37:46.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:40:0)
[16:37:46.571] Timer tick, asking Buildbucket for the build status
[16:37:46.712] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:46.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:41:0) after 1m0s
[16:38:46.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:41:0)
[16:38:46.745] Timer tick, asking Buildbucket for the build status
[16:38:47.426] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:47.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:42:0) after 1m0s
[16:39:47.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:42:0)
[16:39:47.451] Timer tick, asking Buildbucket for the build status
[16:39:50.119] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:50.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:43:0) after 1m0s
[16:40:50.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:43:0)
[16:40:50.262] Timer tick, asking Buildbucket for the build status
[16:40:50.406] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:50.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:44:0) after 1m0s
[16:41:50.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:44:0)
[16:41:50.487] Timer tick, asking Buildbucket for the build status
[16:41:50.557] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:50.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:45:0) after 1m0s
[16:42:50.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:45:0)
[16:42:50.593] Timer tick, asking Buildbucket for the build status
[16:42:50.729] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:50.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:46:0) after 1m0s
[16:43:50.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:46:0)
[16:43:50.761] Timer tick, asking Buildbucket for the build status
[16:43:50.889] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:50.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:47:0) after 1m0s
[16:44:50.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:47:0)
[16:44:50.899] Timer tick, asking Buildbucket for the build status
[16:44:51.292] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:51.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:48:0) after 1m0s
[16:45:51.356] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:48:0)
[16:45:51.356] Timer tick, asking Buildbucket for the build status
[16:45:51.424] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:51.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:49:0) after 1m0s
[16:46:51.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:49:0)
[16:46:51.491] Timer tick, asking Buildbucket for the build status
[16:46:51.554] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:51.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:50:0) after 1m0s
[16:47:51.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:50:0)
[16:47:51.593] Timer tick, asking Buildbucket for the build status
[16:47:51.738] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:51.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:51:0) after 1m0s
[16:48:51.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:51:0)
[16:48:51.792] Timer tick, asking Buildbucket for the build status
[16:48:52.009] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:52.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:52:0) after 1m0s
[16:49:52.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:52:0)
[16:49:52.044] Timer tick, asking Buildbucket for the build status
[16:49:52.135] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:52.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:53:0) after 1m0s
[16:50:52.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:53:0)
[16:50:52.227] Timer tick, asking Buildbucket for the build status
[16:50:52.401] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:52.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:54:0) after 1m0s
[16:51:52.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:54:0)
[16:51:52.491] Timer tick, asking Buildbucket for the build status
[16:51:52.552] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:52.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:55:0) after 1m0s
[16:52:52.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:55:0)
[16:52:52.636] Timer tick, asking Buildbucket for the build status
[16:52:52.760] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:52.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:56:0) after 1m0s
[16:53:52.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:56:0)
[16:53:52.823] Timer tick, asking Buildbucket for the build status
[16:53:52.944] Build 8860289617492409184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:52.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025719679482042672:57:0) after 1m0s
[16:54:34.718] Received PubSub notification, asking Buildbucket for the build status
[16:54:34.956] Build 8860289617492409184: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:54:34.956] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608569673517658",
"created_by": "project:chromium",
"created_ts": "1608566378084092",
"id": "8860289617492409184",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e21e514dbabae72361cf1aba9400280d01d2a60a\"}}",
"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\": \"e21e514dbabae72361cf1aba9400280d01d2a60a\", \"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-gpyc\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16365, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"4ebcee3eb02ffdd586e20965b9c4bb609876db36\", \"got_dawn_revision\": \"5d4fd88a7403aa255d49919d011af54c691b344a\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"e21e514dbabae72361cf1aba9400280d01d2a60a\", \"got_revision_cp\": \"refs/heads/master@{#838653}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"b96d7e2a4be85264b9cd7ba26d91f7fb98baa9a5\", \"got_v8_revision_cp\": \"refs/heads/8.9.179@{#1}\", \"got_webrtc_revision\": \"ef9c4e07a351c9396c72e72efaa1be3df728c440\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32863}\", \"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\": \"e21e514dbabae72361cf1aba9400280d01d2a60a\", \"swarm_hashes_refs/heads/master(at){#838653}_without_patch\": {\"absl_hardening_tests\": \"82850a7f813fd9a083ed7982c7443a5c5aaee4ca\", \"accessibility_unittests\": \"4262510a44fd75bed93430720693f349be16ac9a\", \"angle_unittests\": \"1d65f451ec25b0ea51f44412a7b7b1e790c5ed2e\", \"app_shell_unittests\": \"cb66e5baaaac9d2ad38b3d791727b7901679d485\", \"aura_unittests\": \"47ca2ab0ab7c2596267119634225b6eee603a623\", \"base_unittests\": \"fc06ef66046e8924a972ca2fa6d4a78aa00f734e\", \"base_util_unittests\": \"97ef970a49bb03884aa200b47428c94f744a4334\", \"blink_common_unittests\": \"0261d351775a8490d95c00d62da5df68edbabe0c\", \"blink_fuzzer_unittests\": \"f6d8c3b4e0eb3f5b9a9d5cbfc2d2f005f0b1af45\", \"blink_heap_unittests\": \"ec7f534014b4c0f5f959cabbe8362577f58790fb\", \"blink_platform_unittests\": \"c4036db9c0cd143016928ea15b5e085d9a571556\", \"blink_unittests\": \"91e797a9d4fa117af8069c35bf1ff9432f715bef\", \"boringssl_crypto_tests\": \"b5f43e4915c555f0f0a79a2e7a89dff7d4ae57c8\", \"boringssl_ssl_tests\": \"4b332d518ca3d3ce5532690fbc210ee41572403f\", \"browser_switcher_bho_unittests\": \"31cd18d8b1eec89e415b331a9d408f1e0dc312e8\", \"browser_tests\": \"cd8f0dcf77a9e8803ed193cdf13831a067c84f1f\", \"capture_unittests\": \"4d9b86625949a41d52c0741ff0cef3d5e278624f\", \"cast_unittests\": \"f109b6e81dbe800fcae4d6e7dfb1cf43dd4cb90a\", \"cc_unittests\": \"9f6bdd68a7d16a2060c2f535dc2fdf554dc36541\", \"chrome_app_unittests\": \"2b7c43f2b79c5570839d826d2484ef80a6e0a221\", \"chrome_cleaner_unittests\": \"0a89e457fd320c1c5b39842afe259f8a36cff6cf\", \"chrome_elf_unittests\": \"7d9f6d834b7145673425fbf4e81ff144c69b525a\", \"chromedriver_unittests\": \"bf448de9e49c415e12ccc28d82686b68c1ecf9c7\", \"components_browsertests\": \"2ed235f5fd9df675ab69755d9610c1db397721b8\", \"components_unittests\": \"f12f1847d0eff867e9d72c3fc963fb81a72d9eb2\", \"compositor_unittests\": \"1984ae4be1db2af5003cedc04a80d26fadebc51b\", \"content_browsertests\": \"f27741bc4ef3d0421c9736520818f875c8569c3e\", \"content_unittests\": \"c24ba8599f2affd3016f055a4eef8a384c4c124f\", \"courgette_unittests\": \"853d8778587fdee67067bccccaaac69f4d6451f0\", \"crashpad_tests\": \"7a0f8e6f613f70c00737a1e0e0abbe0b1eb87f81\", \"cronet_tests\": \"92c7136bf72828149ded99a1077bde5fbb55ea34\", \"cronet_unittests\": \"7264acaec5f29ec22921784c1c71ce771d209f0d\", \"crypto_unittests\": \"bf030e9230c3f3b4fd1feaf333da15001844c088\", \"delayloads_unittests\": \"b57a15b62cf3d3658ab0cf0b93fb46066d0e4c45\", \"device_unittests\": \"362a14895db1495c4d8d824b887e405f68c0ba68\", \"display_unittests\": \"dfe57c1a0eb3aa9551a397149011737a39a1e920\", \"elevation_service_unittests\": \"4c027f2401c2fe901830312b29a6ee6f265a46cc\", \"events_unittests\": \"c2429481c26540beefa19bb300abf4e8dc1b652c\", \"extensions_browsertests\": \"ef186af92bc50eb8a3842096e71fa2c0c64b0e04\", \"extensions_unittests\": \"eeea2415e6de7f2aceac6fd9cd330ffe7ce8c5e4\", \"filesystem_service_unittests\": \"25e6aa51f80d794f42ea2f62729ccbfaa938d994\", \"gcm_unit_tests\": \"898c342f69ca1ac9b30b9c765318745453dc04bf\", \"gcp_unittests\": \"44e8778e2156f9889a762fffa95590cad587bba0\", \"gfx_unittests\": \"2a4ca9379d87674d4a57b741b66e2d177a4872df\", \"gin_unittests\": \"568af2b613a765cc32f48ba8fbaaec6f35899e9f\", \"google_apis_unittests\": \"d065f568f5b660ec611a70b9df35bca9ea91dfb5\", \"gpu_unittests\": \"70d4c7238bb3c80aef0193cd3506f849f72f5265\", \"gwp_asan_unittests\": \"6b102e4d9335c1f6241800a7558034dcf5d17124\", \"headless_browsertests\": \"0a1890fe814d1f2a4469ee6df7d6ababde855a4a\", \"headless_unittests\": \"84a95f0a1f13071167ac046b6144759d3cd815b9\", \"install_static_unittests\": \"1279ac7f9c508c934448f8e2eaa424bdaf8bedf9\", \"installer_util_unittests\": \"1eb8ae60e8f9895c6385acf4bf545693ce5c493d\", \"interactive_ui_tests\": \"cde1390d7a4ae88851da8c2b1f30575b727c3ba3\", \"ipc_tests\": \"53adbb019a421c00d3a485e6af34308ce5e7ba7a\", \"jingle_unittests\": \"1cb0b472a7c62e6cc5098eb63da94a218495c254\", \"latency_unittests\": \"15458416187bf26a8a34d6f78337da9b9f0d0db4\", \"libjingle_xmpp_unittests\": \"d5d8bdf34e77507faaa1d41aea3c03f68e01ee2a\", \"liburlpattern_unittests\": \"d3b46ec2546283c045454e49a2aa112f8950e297\", \"media_blink_unittests\": \"6f7a305b26e035e933d83b828535874ab011318f\", \"media_unittests\": \"d71359be8960ebd724c1de82d3ec77b08aa70e2e\", \"message_center_unittests\": \"e6f2af477df9852a20f2828060d27e5231ea3c55\", \"midi_unittests\": \"f5530a0392942a800ff1db9dc955bd377ded85cd\", \"mojo_core_unittests\": \"d5191911cbf3f3b3f97c25abaa9be4e9a473ea82\", \"mojo_unittests\": \"9440367619c1573eb9e884a73bf2eaf30042687b\", \"nacl_loader_unittests\": \"74ed1daa3aa0e483921a2199ceb8edbb907b8e98\", \"native_theme_unittests\": \"d0077fde920875e4d7ceca1e7c58ae6d6087f27e\", \"net_unittests\": \"7c649bc24fee8ac5d69e50ee24186ad364d08b98\", \"notification_helper_unittests\": \"a04714dd0758c356ef42f85d08b3cdd1ac242848\", \"pdf_unittests\": \"75e16b242cdc3e1e1b848926a57a606fa6a29a16\", \"perfetto_unittests\": \"c8f84016658b36689c6c78d3e23e4a33f11499a6\", \"ppapi_unittests\": \"a6db3022be6e67e6fd483b752da2f186634b16d7\", \"printing_unittests\": \"3fd04f28f30f3c437ee066b384ce80dea8ab7b5d\", \"remoting_unittests\": \"03496d0a427ecf32ff976eb0922b7b2c307e2b45\", \"sbox_integration_tests\": \"c570856925d59b43869ae38932ee32322ebee7fb\", \"sbox_unittests\": \"9e81a0f6533bb11d314f046736673f8c2c70fafc\", \"sbox_validation_tests\": \"c4c0c79337def2aeb065af89092006b488733831\", \"service_manager_unittests\": \"52049ac25c4d5aeabcca25d8925760fc87b11cf1\", \"services_unittests\": \"99b327bf04e3ed66636bcdda47e46e369fa4c22e\", \"setup_unittests\": \"7ac93fcc646f7882c0aca79ff9aea4410bb5aeee\", \"shell_dialogs_unittests\": \"4dcfd8cd30563e74de3b3a7ebcfd4bdbdedaf744\", \"skia_unittests\": \"7f82a11969b4c48ea45523e2aaed6eec1d3b3034\", \"snapshot_unittests\": \"b4fbecc99d2214bffb513160b03587e88813fbf6\", \"sql_unittests\": \"02c60f17060abab4a6b2a8ead6bf7ddec13d842a\", \"storage_unittests\": \"4eb9618b7797819750c9f1f64a4d6a5fe69f0252\", \"sync_integration_tests\": \"ee1b799323420dec709fe7ec45ee3039cdc6d599\", \"traffic_annotation_auditor_unittests\": \"719ba234b50acbaef038e98eaa84b4f21f895a68\", \"ui_base_unittests\": \"52ced8b4c322fce45f8e9107e1f3169cebe0a3c8\", \"ui_touch_selection_unittests\": \"77721c054a9954857af272901daa5ce9b296ba37\", \"unit_tests\": \"4d664aca6273eba305abb81fe6606521ff77ceed\", \"updater_tests\": \"fa9dd0edf60a963610f3682b19808022db27e2c3\", \"url_unittests\": \"99ca02adfc8f11d972c6f0e1b530c55b41f6a633\", \"views_unittests\": \"8cc79bcaf0f167e86f9f349720548161590b809c\", \"viz_unittests\": \"e752755c545bedc4678ff45bc5e702aa686ae007\", \"vr_common_unittests\": \"7b7269875cb6d9d95fb7fcd4fc017d831104ae50\", \"vr_pixeltests\": \"6928724259572723124ae40b62a11e5fd702d653\", \"weblayer_browsertests\": \"24a7dcad3e7afb63a661b54d83c0d8e5d42654e0\", \"weblayer_unittests\": \"0e6894d52d4d5998b36cc458b7fa5d527396da62\", \"wm_unittests\": \"88d4938e1f961578632b334038358ba2444f2e55\", \"wtf_unittests\": \"d58eab6a7295853e7c17f105e7944cd77035e8c6\", \"zlib_unittests\": \"0ff49215505f0060c1f23b43c974c5837a77553a\", \"zucchini_unittests\": \"5d8eb9ac0295c1d97fbad4dd7a79661e742da33c\"}}, \"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-6-gpyc\"], \"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\": [\"5657-0417a3f\"], \"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": "1608566380836758",
"status": "COMPLETED",
"status_changed_ts": "1608569674090824",
"tags": [
"build_address:luci.chromium.ci/win-asan/16365",
"builder:win-asan",
"buildset:commit/git/e21e514dbabae72361cf1aba9400280d01d2a60a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e21e514dbabae72361cf1aba9400280d01d2a60a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025719679482042672",
"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/8860289617492409184/+/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:509ed89b2c057910",
"user_agent:luci-scheduler"
],
"updated_ts": "1608569674135344",
"url": "https://ci.chromium.org/b/8860289617492409184",
"utcnow_ts": "1608569674922790"
}
}
[16:54:34.956] Invocation finished in 54m58.152389224s with status SUCCEEDED