[17:51:59.012] New invocation is queued and will start shortly
[17:52:00.065] Starting the invocation (attempt 1)
[17:52:00.098] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:52:00.098] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:52:00.098] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029970667207986288",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f07dceab6e8b1e435369000d32ab626ffb47593f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029970667207986288",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/f07dceab6e8b1e435369000d32ab626ffb47593f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f07dceab6e8b1e435369000d32ab626ffb47593f",
"gitiles_ref:refs/heads/master"
]
}
[17:52:01.421] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604512320338788",
"id": "8864540605147224144",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f07dceab6e8b1e435369000d32ab626ffb47593f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604512321232769",
"tags": [
"build_address:luci.chromium.ci/win-asan/15459",
"builder:win-asan",
"buildset:commit/git/f07dceab6e8b1e435369000d32ab626ffb47593f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f07dceab6e8b1e435369000d32ab626ffb47593f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029970667207986288",
"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/8864540605147224144/+/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": "1604512321232958",
"url": "https://ci.chromium.org/b/8864540605147224144",
"utcnow_ts": "1604512321412145"
}
}
[17:52:01.421] Task URL: https://ci.chromium.org/b/8864540605147224144
[17:52:01.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:2:0) after 1m0s
[17:52:04.002] Received PubSub notification, asking Buildbucket for the build status
[17:52:04.111] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:01.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:2:0)
[17:53:01.963] Timer tick, asking Buildbucket for the build status
[17:53:02.058] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:02.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:4:0) after 1m0s
[17:54:02.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:4:0)
[17:54:02.110] Timer tick, asking Buildbucket for the build status
[17:54:02.381] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:02.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:5:0) after 1m0s
[17:55:02.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:5:0)
[17:55:02.497] Timer tick, asking Buildbucket for the build status
[17:55:02.605] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:02.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:6:0) after 1m0s
[17:56:02.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:6:0)
[17:56:02.823] Timer tick, asking Buildbucket for the build status
[17:56:04.086] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:04.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:7:0) after 1m0s
[17:57:04.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:7:0)
[17:57:04.162] Timer tick, asking Buildbucket for the build status
[17:57:04.280] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:04.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:8:0) after 1m0s
[17:58:04.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:8:0)
[17:58:04.446] Timer tick, asking Buildbucket for the build status
[17:58:04.522] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:04.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:9:0) after 1m0s
[17:59:04.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:9:0)
[17:59:04.654] Timer tick, asking Buildbucket for the build status
[17:59:04.769] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:04.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:10:0) after 1m0s
[18:00:04.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:10:0)
[18:00:04.979] Timer tick, asking Buildbucket for the build status
[18:00:05.334] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:05.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:11:0) after 1m0s
[18:01:05.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:11:0)
[18:01:05.475] Timer tick, asking Buildbucket for the build status
[18:01:05.559] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:05.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:12:0) after 1m0s
[18:02:05.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:12:0)
[18:02:05.639] Timer tick, asking Buildbucket for the build status
[18:02:05.812] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:05.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:13:0) after 1m0s
[18:03:05.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:13:0)
[18:03:05.836] Timer tick, asking Buildbucket for the build status
[18:03:05.904] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:05.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:14:0) after 1m0s
[18:04:05.996] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:14:0)
[18:04:05.996] Timer tick, asking Buildbucket for the build status
[18:04:06.092] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:06.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:15:0) after 1m0s
[18:05:06.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:15:0)
[18:05:06.186] Timer tick, asking Buildbucket for the build status
[18:05:06.320] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:06.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:16:0) after 1m0s
[18:06:06.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:16:0)
[18:06:06.414] Timer tick, asking Buildbucket for the build status
[18:06:06.497] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:06.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:17:0) after 1m0s
[18:07:06.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:17:0)
[18:07:06.532] Timer tick, asking Buildbucket for the build status
[18:07:06.636] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:06.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:18:0) after 1m0s
[18:08:06.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:18:0)
[18:08:06.680] Timer tick, asking Buildbucket for the build status
[18:08:06.763] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:06.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:19:0) after 1m0s
[18:09:06.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:19:0)
[18:09:06.813] Timer tick, asking Buildbucket for the build status
[18:09:08.160] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:08.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:20:0) after 1m0s
[18:10:08.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:20:0)
[18:10:08.212] Timer tick, asking Buildbucket for the build status
[18:10:08.537] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:08.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:21:0) after 1m0s
[18:11:08.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:21:0)
[18:11:08.562] Timer tick, asking Buildbucket for the build status
[18:11:08.688] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:08.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:22:0) after 1m0s
[18:12:08.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:22:0)
[18:12:08.729] Timer tick, asking Buildbucket for the build status
[18:12:08.854] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:08.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:23:0) after 1m0s
[18:13:08.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:23:0)
[18:13:08.933] Timer tick, asking Buildbucket for the build status
[18:13:12.780] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:12.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:24:0) after 1m0s
[18:14:12.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:24:0)
[18:14:12.839] Timer tick, asking Buildbucket for the build status
[18:14:12.995] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:12.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:25:0) after 1m0s
[18:15:13.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:25:0)
[18:15:13.026] Timer tick, asking Buildbucket for the build status
[18:15:13.196] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:13.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:26:0) after 1m0s
[18:16:13.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:26:0)
[18:16:13.171] Timer tick, asking Buildbucket for the build status
[18:16:13.318] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:13.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:27:0) after 1m0s
[18:17:13.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:27:0)
[18:17:13.360] Timer tick, asking Buildbucket for the build status
[18:17:13.812] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:13.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:28:0) after 1m0s
[18:18:13.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:28:0)
[18:18:13.898] Timer tick, asking Buildbucket for the build status
[18:18:14.019] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:14.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:29:0) after 1m0s
[18:19:14.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:29:0)
[18:19:14.092] Timer tick, asking Buildbucket for the build status
[18:19:14.262] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:14.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:30:0) after 1m0s
[18:20:14.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:30:0)
[18:20:14.322] Timer tick, asking Buildbucket for the build status
[18:20:14.510] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:14.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:31:0) after 1m0s
[18:21:14.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:31:0)
[18:21:14.586] Timer tick, asking Buildbucket for the build status
[18:21:15.818] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:15.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:32:0) after 1m0s
[18:22:15.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:32:0)
[18:22:15.854] Timer tick, asking Buildbucket for the build status
[18:22:16.389] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:16.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:33:0) after 1m0s
[18:23:16.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:33:0)
[18:23:16.414] Timer tick, asking Buildbucket for the build status
[18:23:16.539] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:16.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:34:0) after 1m0s
[18:24:16.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:34:0)
[18:24:16.533] Timer tick, asking Buildbucket for the build status
[18:24:16.654] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:16.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:35:0) after 1m0s
[18:25:16.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:35:0)
[18:25:16.726] Timer tick, asking Buildbucket for the build status
[18:25:18.316] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:18.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:36:0) after 1m0s
[18:26:18.473] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:36:0)
[18:26:18.473] Timer tick, asking Buildbucket for the build status
[18:26:18.883] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:18.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:37:0) after 1m0s
[18:27:19.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:37:0)
[18:27:19.118] Timer tick, asking Buildbucket for the build status
[18:27:19.372] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:19.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:38:0) after 1m0s
[18:28:19.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:38:0)
[18:28:19.490] Timer tick, asking Buildbucket for the build status
[18:28:19.690] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:19.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:39:0) after 1m0s
[18:29:19.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:39:0)
[18:29:19.743] Timer tick, asking Buildbucket for the build status
[18:29:20.103] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:20.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:40:0) after 1m0s
[18:30:20.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:40:0)
[18:30:20.251] Timer tick, asking Buildbucket for the build status
[18:30:20.334] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:20.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:41:0) after 1m0s
[18:31:20.445] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:41:0)
[18:31:20.445] Timer tick, asking Buildbucket for the build status
[18:31:21.587] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:21.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:42:0) after 1m0s
[18:32:21.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:42:0)
[18:32:21.683] Timer tick, asking Buildbucket for the build status
[18:32:21.827] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:21.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:43:0) after 1m0s
[18:33:21.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:43:0)
[18:33:21.886] Timer tick, asking Buildbucket for the build status
[18:33:22.115] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:22.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:44:0) after 1m0s
[18:34:22.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:44:0)
[18:34:22.121] Timer tick, asking Buildbucket for the build status
[18:34:22.208] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:22.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:45:0) after 1m0s
[18:35:22.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:45:0)
[18:35:22.268] Timer tick, asking Buildbucket for the build status
[18:35:22.451] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:22.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:46:0) after 1m0s
[18:36:22.543] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:46:0)
[18:36:22.543] Timer tick, asking Buildbucket for the build status
[18:36:22.653] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:22.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:47:0) after 1m0s
[18:37:22.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:47:0)
[18:37:22.673] Timer tick, asking Buildbucket for the build status
[18:37:23.607] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:23.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:48:0) after 1m0s
[18:38:23.695] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:48:0)
[18:38:23.695] Timer tick, asking Buildbucket for the build status
[18:38:24.102] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:24.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:49:0) after 1m0s
[18:39:24.103] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:49:0)
[18:39:24.103] Timer tick, asking Buildbucket for the build status
[18:39:24.494] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:24.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:50:0) after 1m0s
[18:40:24.559] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:50:0)
[18:40:24.559] Timer tick, asking Buildbucket for the build status
[18:40:24.656] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:24.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:51:0) after 1m0s
[18:41:24.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:51:0)
[18:41:24.682] Timer tick, asking Buildbucket for the build status
[18:41:24.825] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:24.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:52:0) after 1m0s
[18:42:24.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:52:0)
[18:42:24.849] Timer tick, asking Buildbucket for the build status
[18:42:24.996] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:24.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:53:0) after 1m0s
[18:43:25.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:53:0)
[18:43:25.022] Timer tick, asking Buildbucket for the build status
[18:43:25.184] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:25.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:54:0) after 1m0s
[18:44:25.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:54:0)
[18:44:25.207] Timer tick, asking Buildbucket for the build status
[18:44:25.326] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:25.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:55:0) after 1m0s
[18:45:25.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:55:0)
[18:45:25.362] Timer tick, asking Buildbucket for the build status
[18:45:25.485] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:25.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:56:0) after 1m0s
[18:46:25.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:56:0)
[18:46:25.546] Timer tick, asking Buildbucket for the build status
[18:46:25.616] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:25.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:57:0) after 1m0s
[18:47:25.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:57:0)
[18:47:25.636] Timer tick, asking Buildbucket for the build status
[18:47:25.824] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:25.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:58:0) after 1m0s
[18:48:25.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:58:0)
[18:48:25.875] Timer tick, asking Buildbucket for the build status
[18:48:26.223] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:26.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:59:0) after 1m0s
[18:49:26.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:59:0)
[18:49:26.246] Timer tick, asking Buildbucket for the build status
[18:49:26.400] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:26.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:60:0) after 1m0s
[18:50:26.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:60:0)
[18:50:26.447] Timer tick, asking Buildbucket for the build status
[18:50:26.527] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:26.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:61:0) after 1m0s
[18:51:26.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:61:0)
[18:51:26.622] Timer tick, asking Buildbucket for the build status
[18:51:27.668] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:27.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:62:0) after 1m0s
[18:52:27.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:62:0)
[18:52:27.675] Timer tick, asking Buildbucket for the build status
[18:52:29.175] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:29.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:63:0) after 1m0s
[18:53:29.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:63:0)
[18:53:29.243] Timer tick, asking Buildbucket for the build status
[18:53:29.381] Build 8864540605147224144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:29.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029970667207986288:64:0) after 1m0s
[18:54:06.233] Received PubSub notification, asking Buildbucket for the build status
[18:54:06.319] Build 8864540605147224144: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:54:06.319] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604516042152515",
"created_by": "project:chromium",
"created_ts": "1604512320338788",
"id": "8864540605147224144",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f07dceab6e8b1e435369000d32ab626ffb47593f\"}}",
"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\": \"f07dceab6e8b1e435369000d32ab626ffb47593f\", \"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-5-vrjs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15459, \"clang_revision\": \"llvmorg-12-init-5627-gf086e85e-3\", \"got_angle_revision\": \"8a275449c2e8c3fa7b9c942c3b673def3e7f51e1\", \"got_dawn_revision\": \"9d6265bc072546e92c1cea293e209e079d7345fb\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"f07dceab6e8b1e435369000d32ab626ffb47593f\", \"got_revision_cp\": \"refs/heads/master@{#824045}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"19f01495a8a18f2d222c29367901c7c1045ba4a2\", \"got_v8_revision\": \"7ae0bc9f7258d206e0de93911c5987c3e04979b8\", \"got_v8_revision_cp\": \"refs/heads/8.8.228@{#1}\", \"got_webrtc_revision\": \"42cafa5696f516712bbe6709fedeedffac61544c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32540}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f07dceab6e8b1e435369000d32ab626ffb47593f\", \"swarm_hashes_refs/heads/master(at){#824045}_without_patch\": {\"absl_hardening_tests\": \"c318892627e26109f8310f261dd37371506bd8e1\", \"accessibility_unittests\": \"9f5082761970f1fac35bcd246cdf7ceb35aaa7c9\", \"angle_unittests\": \"082569eecbf4686c7cd04b87599eb948aa3f576c\", \"app_shell_unittests\": \"d882e5a7c3654143103adb5fc6b80a780578156b\", \"aura_unittests\": \"6d0f2cf71ce479adc9efe88b222f97da44fbef0c\", \"base_unittests\": \"66d24d58a408c9baa498eaebc98fb1594f9a961c\", \"base_util_unittests\": \"e1db6a1bfed767a685176ff5c87b64ca01b7427e\", \"blink_common_unittests\": \"aef5f7ba6de4936ac058c99ab240bcbd1d50a485\", \"blink_fuzzer_unittests\": \"940fbf6dfff041d991c037651f1649ace59de036\", \"blink_heap_unittests\": \"c962194910b484cb18bfce319a1b4d61dbfe900e\", \"blink_platform_unittests\": \"16e4599aa1a4eaf8b56127e8faa48f8f0762586c\", \"blink_unittests\": \"03904e66dd1cce5563a52888c01124139caccb51\", \"boringssl_crypto_tests\": \"025af658ad4f68e6fafc3d78cf434e231f7d84d1\", \"boringssl_ssl_tests\": \"87ac68db9593042c5bc04955da9aedae68bce363\", \"browser_switcher_bho_unittests\": \"63e70489f0739b6f6d8560bb39f9637db4f4fc7d\", \"browser_tests\": \"34a01ce12cdd58291dd4f9c51be13a3280218365\", \"capture_unittests\": \"0fa43df45599e1196b4b8dbe092632c76944c556\", \"cast_unittests\": \"f8a4671c53de42727b1c8d02c02a5a8ec792fa7e\", \"cc_unittests\": \"d3b2c8caecac0543bad79c740545b67a0b055593\", \"chrome_app_unittests\": \"fbb792c52f7cebdb20aeb60eaefdbc5c2247ad31\", \"chrome_cleaner_unittests\": \"57b6057ebcd4a12ab01b8c0582f185cd13830adb\", \"chrome_elf_unittests\": \"fcc199e80a992fe1033f400304aaca0c57fa2b25\", \"chromedriver_unittests\": \"58a1a84c5555e482f098ef1810b2e2b0a9653026\", \"components_browsertests\": \"e61378b919d3d1340204356a0419273eb3639824\", \"components_unittests\": \"e0d9f87d9fcd67f08b61ab6efc1220de6b96985d\", \"compositor_unittests\": \"f3afd4399d742548b04f9ae47ce86a52f955701c\", \"content_browsertests\": \"ce450dc2e6ab7d62aa04f1babb1170733bcb6b28\", \"content_unittests\": \"a6efb638a7add26bb6a32f9a5c2927025b30dbf6\", \"courgette_unittests\": \"b38c274c5a5455b74925ca124ded5f319ec14e82\", \"crashpad_tests\": \"6833d47e6cd05ab80bd0d4f62b8ca940e9e8dfe1\", \"cronet_tests\": \"3dad7c672e50b7049d2d4d76231f07050546d57d\", \"cronet_unittests\": \"ff93f2bc6ad845b232ce31a4ae5bac9c638ff9b0\", \"crypto_unittests\": \"7548151402b840d0ecacc0ec9a1b1c963e70b40e\", \"delayloads_unittests\": \"a4851cb5b92bb04b4039c86a5d77ec6c51ad09af\", \"device_unittests\": \"1b1ab73c12e3da4a17d808a02565d9400ec67064\", \"display_unittests\": \"c3da4d39d45d188cc9757c1762d7f1d1d1d484ff\", \"elevation_service_unittests\": \"4d1dbfe2f82284284fa2f991acec741bf66ac579\", \"events_unittests\": \"bc051f58939a9d6b4e3af650f8cc345a899a8b18\", \"extensions_browsertests\": \"dd7ca2f3af1a8b3896692d16a570c1e0ba6989a3\", \"extensions_unittests\": \"fa3a4f80373c82cf7a98c505230481d546a533ff\", \"filesystem_service_unittests\": \"7bf8240fc9038498c9785914a237a966f6d8a92d\", \"gcm_unit_tests\": \"ce94bbd2a32f7e770e56f5fbb1f7167d3b78488a\", \"gcp_unittests\": \"04893db7b6d03e50286d48f7108797b211cb2025\", \"gfx_unittests\": \"0b2e9bcf79a586b8730fca767b703e4b6dbe8e9e\", \"gin_unittests\": \"685cdcf64c2bc7247836bbfc32eade67b9b182ae\", \"google_apis_unittests\": \"d9f60e398bdecd608e81fb2b12abec646f5e3f9b\", \"gpu_unittests\": \"7ef43d48f2f7903c22ae0cae92b8920227e10e26\", \"gwp_asan_unittests\": \"bf20e67807aae11a34b2b69222ac635c74756caa\", \"headless_browsertests\": \"e3de9edf1d461e30bada4d63264efcd87f02f39d\", \"headless_unittests\": \"62e3c06b212d23cbae27bc28587c7e6c52ccbeed\", \"install_static_unittests\": \"1b359b8822acb0c22860523d307d16d88932c283\", \"installer_util_unittests\": \"e420b937f7b773b6f21805c88cd530cc02e3e80e\", \"interactive_ui_tests\": \"19f9fa85c107411764b47f0c0d139be7d7e38cbc\", \"ipc_tests\": \"f36dfff6820db28d897bc9eeb5f88a1bb8fbf2ce\", \"jingle_unittests\": \"515655cd1a50d5baac80e0d95fc3c85835ea11dc\", \"latency_unittests\": \"4f56205a7b40bf368cd46d61003015f2c3263d25\", \"libjingle_xmpp_unittests\": \"780ec3c30985e6a96d2f5bc8707617a5b89bd5bf\", \"liburlpattern_unittests\": \"5b014aa0eac6dab7e7281a28fe944ec8e0b03eeb\", \"media_blink_unittests\": \"a7ac139d142a22485d203becc7b7528b10e25079\", \"media_unittests\": \"9b4e428d87c728d0bb21ad5566bd2ea8185d4078\", \"message_center_unittests\": \"49fc8f7003b828ca1b213a04344cb8f9bb3a2af8\", \"midi_unittests\": \"4fe89f13799185f97b46ce11279b8ec38633db69\", \"mojo_core_unittests\": \"b68d1d69f3bb01dbae8bb6af0c32aa5a94cb9893\", \"mojo_unittests\": \"e04dbb6211bf5ab3ef65da4db93c052411da51f7\", \"nacl_loader_unittests\": \"cf161c6dc1acfd252dd9535e9cc5cd3f5a2d8247\", \"native_theme_unittests\": \"e40bc221e5ae3d75d60e4d58407e3bee6a8523d5\", \"net_unittests\": \"7e5c745dfd0ee4903473d76559de30192cd3434e\", \"notification_helper_unittests\": \"9b531cd71951434b97f31258456bc82c87823a5c\", \"pdf_unittests\": \"a98a2ad1cf807be96e0ef73ee22999b577b9ea97\", \"perfetto_unittests\": \"a8fce4f9bc7a54dc278d9e538e7b142872f85fd6\", \"ppapi_unittests\": \"c1b0b54fa0a71a68d7e328dd8cbd4433fad9b165\", \"printing_unittests\": \"9d31725c2389f48425298976b8a8b887d1e436ea\", \"remoting_unittests\": \"df8461f5cf503e7e6167616dae67c65d0baf8b20\", \"sbox_integration_tests\": \"6fd6a82357b00ae4bebd2405639bf2c378cc5df1\", \"sbox_unittests\": \"09ffa5c2a9d0b3f2c6002b4f96f2fc9a2c80eb1c\", \"sbox_validation_tests\": \"09eae9677cbb08f02bddb0987054006fdcb4639e\", \"service_manager_unittests\": \"eabbbdf355cd14c814c8731fcc995f717818e92c\", \"services_unittests\": \"b44b6ea5412c549caf9a1b9f2d8928088a7b5a47\", \"setup_unittests\": \"094899ec2286809890451e2c68723681d48b5938\", \"shell_dialogs_unittests\": \"3b648c26d7c29abd77bee3d2472ec94d0a5e30ea\", \"skia_unittests\": \"387b5b7394f1af2bccdb8b9d14a6b52b0cd95bb0\", \"snapshot_unittests\": \"81cb58b2656f8ed6757a8387f8ae72b63bb5cfb2\", \"sql_unittests\": \"364fe8a67297994035fb37d3a8f9493ea5e4394b\", \"storage_unittests\": \"efe43473bc8fa99af9e5e360f53c6b091f8e7922\", \"sync_integration_tests\": \"5d1caefeb5f90b869532656afc66c8884069c0fc\", \"traffic_annotation_auditor_unittests\": \"4439ad6d93c440d578dc21e9947ee102a59c15aa\", \"ui_base_unittests\": \"11a40d3d0e49ae270730afbbf4e1a96426a1dc60\", \"ui_touch_selection_unittests\": \"75fa74eacd0fe8fa4901d5b47fd01464bb2564e8\", \"unit_tests\": \"5ccf9f7b87cf78af0b1e3c7f6bb8ea2f0a93c0be\", \"updater_tests\": \"f3a4abd934e9249c0d4477393e6e5af5a6ffd551\", \"url_unittests\": \"1040982aee728dfa9845940743acc66838317d68\", \"views_unittests\": \"632b2f8beacecb73c7e7de270c97bf2945b84891\", \"viz_unittests\": \"a5841d91f7fcb652159fd5a46ee7af30e783bc4c\", \"vr_common_unittests\": \"f7732834d49aac52ad4be4a4b9b2377efe22a41e\", \"vr_pixeltests\": \"9e5cbcb4dfefb2d60fd49221b6e7384b88c0d360\", \"weblayer_browsertests\": \"0df63e10033d530966774b26c1f3482d5aac920c\", \"weblayer_unittests\": \"2867daf0b407f8d7e6c3333874ddf45862a19672\", \"wm_unittests\": \"95dd242a7cf03a5e477d8a1ab61773723a6510c2\", \"wtf_unittests\": \"3175de420045353f34c20ee9632727dd1b52d6f6\", \"zlib_unittests\": \"81e3169a5fdbcc42f642f0c49f9b6a51543722fd\", \"zucchini_unittests\": \"da5cbaec75b97d1c72642956775783cf8cdf28d9\"}}, \"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-5-vrjs\"], \"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\": [\"5614-d862eaa\"], \"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": "1604512322895496",
"status": "COMPLETED",
"status_changed_ts": "1604516042887478",
"tags": [
"build_address:luci.chromium.ci/win-asan/15459",
"builder:win-asan",
"buildset:commit/git/f07dceab6e8b1e435369000d32ab626ffb47593f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f07dceab6e8b1e435369000d32ab626ffb47593f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029970667207986288",
"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/8864540605147224144/+/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:4fad3497ebb1e010",
"user_agent:luci-scheduler"
],
"updated_ts": "1604516043178249",
"url": "https://ci.chromium.org/b/8864540605147224144",
"utcnow_ts": "1604516046311621"
}
}
[18:54:06.319] Invocation finished in 1h2m7.330687791s with status SUCCEEDED