[23:37:38.510] New invocation is queued and will start shortly
[23:37:39.835] Starting the invocation (attempt 1)
[23:37:39.869] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:37:39.869] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:37:39.869] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019439672161026864",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"202be91e6658aad3047e55a5bacd72b3ca1a5d5c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019439672161026864",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/202be91e6658aad3047e55a5bacd72b3ca1a5d5c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/202be91e6658aad3047e55a5bacd72b3ca1a5d5c",
"gitiles_ref:refs/heads/master"
]
}
[23:37:40.642] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1614555460004914",
"id": "8854009609928950912",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"202be91e6658aad3047e55a5bacd72b3ca1a5d5c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614555460510915",
"tags": [
"build_address:luci.chromium.ci/win-asan/17564",
"builder:win-asan",
"buildset:commit/git/202be91e6658aad3047e55a5bacd72b3ca1a5d5c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/202be91e6658aad3047e55a5bacd72b3ca1a5d5c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019439672161026864",
"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/8854009609928950912/+/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": "1614555460511022",
"url": "https://ci.chromium.org/b/8854009609928950912",
"utcnow_ts": "1614555460636226"
}
}
[23:37:40.642] Task URL: https://ci.chromium.org/b/8854009609928950912
[23:37:40.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:2:0) after 1m0s
[23:37:42.783] Received PubSub notification, asking Buildbucket for the build status
[23:37:42.860] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:40.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:2:0)
[23:38:40.661] Timer tick, asking Buildbucket for the build status
[23:38:40.728] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:40.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:4:0) after 1m0s
[23:39:40.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:4:0)
[23:39:40.705] Timer tick, asking Buildbucket for the build status
[23:39:40.783] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:40.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:5:0) after 1m0s
[23:40:40.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:5:0)
[23:40:40.812] Timer tick, asking Buildbucket for the build status
[23:40:41.017] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:41.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:6:0) after 1m0s
[23:41:41.030] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:6:0)
[23:41:41.030] Timer tick, asking Buildbucket for the build status
[23:41:41.115] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:41.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:7:0) after 1m0s
[23:42:41.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:7:0)
[23:42:41.116] Timer tick, asking Buildbucket for the build status
[23:42:41.227] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:41.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:8:0) after 1m0s
[23:43:41.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:8:0)
[23:43:41.353] Timer tick, asking Buildbucket for the build status
[23:43:41.431] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:41.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:9:0) after 1m0s
[23:44:41.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:9:0)
[23:44:41.498] Timer tick, asking Buildbucket for the build status
[23:44:41.566] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:41.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:10:0) after 1m0s
[23:45:41.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:10:0)
[23:45:41.680] Timer tick, asking Buildbucket for the build status
[23:45:41.870] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:41.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:11:0) after 1m0s
[23:46:41.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:11:0)
[23:46:41.967] Timer tick, asking Buildbucket for the build status
[23:46:42.042] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:42.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:12:0) after 1m0s
[23:47:42.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:12:0)
[23:47:42.110] Timer tick, asking Buildbucket for the build status
[23:47:42.204] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:42.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:13:0) after 1m0s
[23:48:42.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:13:0)
[23:48:42.274] Timer tick, asking Buildbucket for the build status
[23:48:42.374] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:42.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:14:0) after 1m0s
[23:49:42.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:14:0)
[23:49:42.407] Timer tick, asking Buildbucket for the build status
[23:49:42.500] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:42.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:15:0) after 1m0s
[23:50:42.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:15:0)
[23:50:42.527] Timer tick, asking Buildbucket for the build status
[23:50:42.837] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:42.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:16:0) after 1m0s
[23:51:42.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:16:0)
[23:51:42.857] Timer tick, asking Buildbucket for the build status
[23:51:42.932] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:42.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:17:0) after 1m0s
[23:52:42.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:17:0)
[23:52:42.962] Timer tick, asking Buildbucket for the build status
[23:52:43.059] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:43.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:18:0) after 1m0s
[23:53:43.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:18:0)
[23:53:43.075] Timer tick, asking Buildbucket for the build status
[23:53:43.147] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:43.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:19:0) after 1m0s
[23:54:43.204] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:19:0)
[23:54:43.204] Timer tick, asking Buildbucket for the build status
[23:54:43.331] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:43.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:20:0) after 1m0s
[23:55:43.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:20:0)
[23:55:43.429] Timer tick, asking Buildbucket for the build status
[23:55:43.751] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:43.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:21:0) after 1m0s
[23:56:43.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:21:0)
[23:56:43.728] Timer tick, asking Buildbucket for the build status
[23:56:43.930] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:43.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:22:0) after 1m0s
[23:57:43.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:22:0)
[23:57:43.979] Timer tick, asking Buildbucket for the build status
[23:57:44.165] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:44.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:23:0) after 1m0s
[23:58:44.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:23:0)
[23:58:44.182] Timer tick, asking Buildbucket for the build status
[23:58:44.346] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:44.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:24:0) after 1m0s
[23:59:44.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:24:0)
[23:59:44.374] Timer tick, asking Buildbucket for the build status
[23:59:44.457] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:44.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:25:0) after 1m0s
[00:00:44.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:25:0)
[00:00:44.475] Timer tick, asking Buildbucket for the build status
[00:00:45.072] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:45.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:26:0) after 1m0s
[00:01:45.090] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:26:0)
[00:01:45.090] Timer tick, asking Buildbucket for the build status
[00:01:45.259] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:45.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:27:0) after 1m0s
[00:02:45.278] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:27:0)
[00:02:45.278] Timer tick, asking Buildbucket for the build status
[00:02:45.664] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:45.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:28:0) after 1m0s
[00:03:45.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:28:0)
[00:03:45.775] Timer tick, asking Buildbucket for the build status
[00:03:45.860] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:45.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:29:0) after 1m0s
[00:04:45.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:29:0)
[00:04:45.956] Timer tick, asking Buildbucket for the build status
[00:04:46.648] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:46.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:30:0) after 1m0s
[00:05:46.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:30:0)
[00:05:46.655] Timer tick, asking Buildbucket for the build status
[00:05:46.823] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:46.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:31:0) after 1m0s
[00:06:46.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:31:0)
[00:06:46.929] Timer tick, asking Buildbucket for the build status
[00:06:47.057] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:47.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:32:0) after 1m0s
[00:07:47.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:32:0)
[00:07:47.123] Timer tick, asking Buildbucket for the build status
[00:07:47.236] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:47.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:33:0) after 1m0s
[00:08:47.307] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:33:0)
[00:08:47.307] Timer tick, asking Buildbucket for the build status
[00:08:47.404] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:47.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:34:0) after 1m0s
[00:09:47.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:34:0)
[00:09:47.379] Timer tick, asking Buildbucket for the build status
[00:09:47.552] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:47.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:35:0) after 1m0s
[00:10:47.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:35:0)
[00:10:47.542] Timer tick, asking Buildbucket for the build status
[00:10:47.851] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:47.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:36:0) after 1m0s
[00:11:47.920] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:36:0)
[00:11:47.920] Timer tick, asking Buildbucket for the build status
[00:11:48.071] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:48.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:37:0) after 1m0s
[00:12:48.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:37:0)
[00:12:48.063] Timer tick, asking Buildbucket for the build status
[00:12:48.188] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:48.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:38:0) after 1m0s
[00:13:48.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:38:0)
[00:13:48.233] Timer tick, asking Buildbucket for the build status
[00:13:48.447] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:48.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:39:0) after 1m0s
[00:14:48.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:39:0)
[00:14:48.462] Timer tick, asking Buildbucket for the build status
[00:14:48.541] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:48.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:40:0) after 1m0s
[00:15:48.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:40:0)
[00:15:48.581] Timer tick, asking Buildbucket for the build status
[00:15:48.683] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:48.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:41:0) after 1m0s
[00:16:48.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:41:0)
[00:16:48.705] Timer tick, asking Buildbucket for the build status
[00:16:49.002] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:49.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:42:0) after 1m0s
[00:17:49.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:42:0)
[00:17:49.048] Timer tick, asking Buildbucket for the build status
[00:17:49.187] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:49.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:43:0) after 1m0s
[00:18:49.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:43:0)
[00:18:49.208] Timer tick, asking Buildbucket for the build status
[00:18:49.318] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:49.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:44:0) after 1m0s
[00:19:49.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:44:0)
[00:19:49.408] Timer tick, asking Buildbucket for the build status
[00:19:49.494] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:49.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:45:0) after 1m0s
[00:20:49.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:45:0)
[00:20:49.545] Timer tick, asking Buildbucket for the build status
[00:20:49.634] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:49.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:46:0) after 1m0s
[00:21:49.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:46:0)
[00:21:49.683] Timer tick, asking Buildbucket for the build status
[00:21:49.768] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:49.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:47:0) after 1m0s
[00:22:49.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:47:0)
[00:22:49.785] Timer tick, asking Buildbucket for the build status
[00:22:50.144] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:50.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:48:0) after 1m0s
[00:23:50.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:48:0)
[00:23:50.232] Timer tick, asking Buildbucket for the build status
[00:23:52.419] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:52.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:49:0) after 1m0s
[00:24:54.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:49:0)
[00:24:54.611] Timer tick, asking Buildbucket for the build status
[00:24:54.784] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:54.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:50:0) after 1m0s
[00:25:55.047] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:50:0)
[00:25:55.047] Timer tick, asking Buildbucket for the build status
[00:25:55.135] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:55.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:51:0) after 1m0s
[00:26:55.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:51:0)
[00:26:55.414] Timer tick, asking Buildbucket for the build status
[00:26:55.495] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:55.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:52:0) after 1m0s
[00:27:55.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:52:0)
[00:27:55.556] Timer tick, asking Buildbucket for the build status
[00:27:56.077] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:56.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:53:0) after 1m0s
[00:28:56.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:53:0)
[00:28:56.134] Timer tick, asking Buildbucket for the build status
[00:28:56.229] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:56.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:54:0) after 1m0s
[00:29:56.275] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:54:0)
[00:29:56.275] Timer tick, asking Buildbucket for the build status
[00:29:56.371] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:56.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:55:0) after 1m0s
[00:30:56.440] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:55:0)
[00:30:56.440] Timer tick, asking Buildbucket for the build status
[00:30:56.547] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:56.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:56:0) after 1m0s
[00:31:56.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:56:0)
[00:31:56.616] Timer tick, asking Buildbucket for the build status
[00:31:56.826] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:56.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:57:0) after 1m0s
[00:32:56.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:57:0)
[00:32:56.847] Timer tick, asking Buildbucket for the build status
[00:32:56.973] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:56.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:58:0) after 1m0s
[00:33:57.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:58:0)
[00:33:57.008] Timer tick, asking Buildbucket for the build status
[00:33:57.096] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:57.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:59:0) after 1m0s
[00:34:57.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:59:0)
[00:34:57.117] Timer tick, asking Buildbucket for the build status
[00:34:57.302] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:57.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:60:0) after 1m0s
[00:35:57.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:60:0)
[00:35:57.298] Timer tick, asking Buildbucket for the build status
[00:35:57.940] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:57.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:61:0) after 1m0s
[00:36:57.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:61:0)
[00:36:57.979] Timer tick, asking Buildbucket for the build status
[00:36:58.462] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:58.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:62:0) after 1m0s
[00:37:58.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:62:0)
[00:37:58.582] Timer tick, asking Buildbucket for the build status
[00:37:58.798] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:58.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:63:0) after 1m0s
[00:38:58.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:63:0)
[00:38:58.895] Timer tick, asking Buildbucket for the build status
[00:38:58.982] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:58.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:64:0) after 1m0s
[00:39:59.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:64:0)
[00:39:59.062] Timer tick, asking Buildbucket for the build status
[00:39:59.391] Build 8854009609928950912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:59.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019439672161026864:65:0) after 1m0s
[00:40:46.927] Received PubSub notification, asking Buildbucket for the build status
[00:40:47.130] Build 8854009609928950912: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:40:47.130] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1614559246311208",
"created_by": "project:chromium",
"created_ts": "1614555460004914",
"id": "8854009609928950912",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"202be91e6658aad3047e55a5bacd72b3ca1a5d5c\"}}",
"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\": \"202be91e6658aad3047e55a5bacd72b3ca1a5d5c\", \"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-3-xpf4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17564, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"73afaf1e896b43b7570a302793d64ff97262d0f3\", \"got_dawn_revision\": \"329c1c98fef964ea532a0579ae6f2553ff3382c5\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"202be91e6658aad3047e55a5bacd72b3ca1a5d5c\", \"got_revision_cp\": \"refs/heads/master@{#858448}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"106e01583a3ac2fb950777390dde2dcc31a8f161\", \"got_v8_revision\": \"45e415150419491d6d12428ba6ee98876a405ba4\", \"got_v8_revision_cp\": \"refs/heads/9.1.1@{#1}\", \"got_webrtc_revision\": \"376cf384ac689eca0bf8d4d1d3a3cf80888d3fbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33348}\", \"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\": \"202be91e6658aad3047e55a5bacd72b3ca1a5d5c\", \"swarm_hashes_refs/heads/master(at){#858448}_without_patch\": {\"absl_hardening_tests\": \"f5e1ce4ad7e40415ddea9e8ef815f5b57a0d2c18\", \"accessibility_unittests\": \"30f5f06cc6d1429597a366434a1a91fd348ec87c\", \"angle_unittests\": \"705563bf3e57b9520e653472809aebf1c12f8be7\", \"app_shell_unittests\": \"0fa4b4901ccc839722f96c9a8d597b6ea6f50f19\", \"aura_unittests\": \"9acf3ee66fac1c15c47afa6f7977d9af2d024217\", \"base_unittests\": \"01853cb85811b8c8365282ed0afb8268d2f22314\", \"base_util_unittests\": \"390d6530e4fa409c433121b77371dd212ad1af41\", \"blink_common_unittests\": \"7cb3353768d55cc75fb36ff3e5ce1fd54fc9899b\", \"blink_fuzzer_unittests\": \"646b3fe72a1df81c8e093456aacf2d884243e34b\", \"blink_heap_unittests\": \"6c8f904a535b2a9dae5e1a2c445b0b284f7bce09\", \"blink_platform_unittests\": \"e3c9629a7ff7024a116e4fddb53b5a159dfc2297\", \"blink_unittests\": \"16020f17d83fb7564acae63359199b7d8b4afbe7\", \"boringssl_crypto_tests\": \"528ebea48188e1814a6ee22def74c8231dc74003\", \"boringssl_ssl_tests\": \"d596a7b849e989259aeec311e9cc7773ee3c68b4\", \"browser_switcher_bho_unittests\": \"3dbf17372a522a319ccc3069a550a9967bdaf8cb\", \"browser_tests\": \"72316abede338fdb0d0497cbab7fab0f272250d9\", \"capture_unittests\": \"3b353f21c36e68cbd9d588b5f1653fc3ef78ee42\", \"cast_unittests\": \"e54c1eaf108728ed5c9b329bdc5b57f2e29976b8\", \"cc_unittests\": \"2948e45410428ba7460974cfcbfd9506c7a7c2e3\", \"chrome_app_unittests\": \"dc2632ef0dcb3020353c2050fafa6f2323f6bff1\", \"chrome_cleaner_unittests\": \"45a6b57bc14ef7480fba5e1d4e677bbcd4d1827a\", \"chrome_elf_unittests\": \"7aa6563c115a4eba2bb824bce2f2f5872def6857\", \"chromedriver_unittests\": \"d3cf6b6d23b282abadf097eef397598901634e3b\", \"color_unittests\": \"dc68095caa4a37e03b09dc30a30728207dce598e\", \"components_browsertests\": \"20fa3d2bb9181a71d601f3ffd38f5ad70fd9e2b1\", \"components_unittests\": \"14de3d904dcb4a21d768b7f26e069ff8bf33189c\", \"compositor_unittests\": \"8997872f2e956f58bf75e67672d3a68f7c45787a\", \"content_browsertests\": \"620b761036edfb02e03f18508e1e3d3de2097dee\", \"content_unittests\": \"608b497fc7888efe512e6dd525a37535d3f417d6\", \"courgette_unittests\": \"59c59bab3c51ca8e94da45218a5dde1d2ef5ab67\", \"crashpad_tests\": \"1b9426b7d4fc6473e0a161573de9a49794f93a07\", \"cronet_tests\": \"9f15a2df98a7a1f60fa5d4ab2c729b8300292474\", \"cronet_unittests\": \"7ef5ff1a22d69c992d1a836b55e33b67c07289f2\", \"crypto_unittests\": \"cddc7164b413377d2e880e5ca5487d9c924cb581\", \"delayloads_unittests\": \"3dae88e1715e3bfdd5d5d9547b629d11580b8072\", \"device_unittests\": \"d3459e8e1ef900bd01ff6d138f144cfcb79b2060\", \"display_unittests\": \"443c6de642bf566bdcf8fbfe0f9b98f49548b7e5\", \"elevation_service_unittests\": \"9047c1aabdd3657668ee977f22e7679060579794\", \"events_unittests\": \"51c8edc5438a224427d0d16ce839cf9765a3c9ff\", \"extensions_browsertests\": \"47c0a6894b09e207b402b244d2b7000cf6d7081e\", \"extensions_unittests\": \"34281e0394af4447876b66d68a7201c5400fe0e2\", \"filesystem_service_unittests\": \"f81ed029515b192d62855f5128d6f4dafde23ae3\", \"gcm_unit_tests\": \"79c562273c7486685050f67e91e420adbae5ab56\", \"gcp_unittests\": \"31777c6a892924d976279940d43e910c9c39c238\", \"gfx_unittests\": \"6fbd115af57ba8a5251733894ebe27cd249bab80\", \"gin_unittests\": \"15e9e19310b2e9e4d3570b5c40b1de28de387dbf\", \"google_apis_unittests\": \"355dcdab285eb6c2172bec93d629fd9433d6a2d6\", \"gpu_unittests\": \"826785a6b9f13325f36874e8870e4f794fba43cb\", \"gwp_asan_unittests\": \"6deb2b30fcdd3958858f8ec97653377a5dca3a2f\", \"headless_browsertests\": \"4c45a4e17af79b7d0e7bc39e5c4683166ccb719d\", \"headless_unittests\": \"1dc8cd4999e14efff0e6bfe33327e2e90174bd81\", \"install_static_unittests\": \"cf4b93be4d79f37bbec3ce30e482c81040b9ff3e\", \"installer_util_unittests\": \"71e6f2384a8ceef7e460d2407ab24852c8d50d6d\", \"interactive_ui_tests\": \"153d47eee38fad80cf84d017a13ee97e04d23b8d\", \"ipc_tests\": \"40a7327f0a89fbfb006d502d26b24ffe6d19692a\", \"jingle_unittests\": \"6f18da3d9560f5a75973d6b9ab287c4a17ac3c8b\", \"latency_unittests\": \"86825f79a21e37efc3ee67d4a43c0c78d054f3cd\", \"libjingle_xmpp_unittests\": \"fae9c8c71df405a6f926c4fcd79bc03fed54a728\", \"liburlpattern_unittests\": \"978bab83b37b4e12fcc9513bcc904ee465d152ac\", \"media_blink_unittests\": \"11b2b563ffae8af93a0908bdf8fde56c39080cc5\", \"media_unittests\": \"1cfc947c00810c5826120e9d6fbb72f2ee9bb273\", \"message_center_unittests\": \"e9fddabe9cd75b0475087556f02682136cd96d34\", \"midi_unittests\": \"948beec888f31195e333b5429fe05c5a317460fe\", \"mojo_core_unittests\": \"ca9350603b6c3e09a40423b98aecac4bed421aa6\", \"mojo_unittests\": \"9bbfe0d9a0c9bf790a52d88dca75202b3b8ae791\", \"nacl_loader_unittests\": \"cf44181446964d1c956591e4570bda6ee3f5cf2c\", \"native_theme_unittests\": \"aaa71abf773e40e1793d4fee826741cf5d0cccf4\", \"net_unittests\": \"b447b1b0d498b5461bbbc80bbb41531f516b1009\", \"notification_helper_unittests\": \"5e47b9535f22799d0466077ae4d48be27f369f77\", \"pdf_unittests\": \"9f6adea474b9235758342138fc4613487f2ee87f\", \"perfetto_unittests\": \"05d2c7111aa4ba8c37b26a3013e1090d026aeecf\", \"ppapi_unittests\": \"7288a7e42a7dae9976a1368caf61905590eea16f\", \"printing_unittests\": \"c906ba4a61c11aa8c867952e415582fcb610e059\", \"remoting_unittests\": \"4c3414ee9f0adeeabe1f7b6118a30b58e753626a\", \"sbox_integration_tests\": \"d6a13f3919e3bfcb0c4410a459492d3dad92ca01\", \"sbox_unittests\": \"4f542d709d703943db53b68924d3f0bc0ac786c0\", \"sbox_validation_tests\": \"8d4b53e9f495aa47eae28d07f5ff7c3586da1251\", \"service_manager_unittests\": \"c764bdfeada08a38dbbf520cd201cdf964b96264\", \"services_unittests\": \"9a33ee9968b77e1a4f6e6326058d34ac0e7cd707\", \"setup_unittests\": \"23c93c720ed0b67fbbd73b14dc2ecf4da0002197\", \"shell_dialogs_unittests\": \"43d01048c61072d4b9bca7fecd4f201bbdc93660\", \"skia_unittests\": \"00b83de356d4f3f9ba26d97ab519ec19b526c6e6\", \"snapshot_unittests\": \"d7e8a31f3638da56d02d31034c10664e4f2dde62\", \"sql_unittests\": \"42bfde58e860dffbc3c5d69c33dddbdf4ad6b498\", \"storage_unittests\": \"725e30c7cb5f4cf702f0d317327a37b753d11fb4\", \"sync_integration_tests\": \"bc8398bb618bfd1403217f115590638ebc9281f7\", \"traffic_annotation_auditor_unittests\": \"db28e429fb6781c752c695f797614042bcddc7b7\", \"ui_base_unittests\": \"85be7d1b0d9b6983b6e5c0d72d14891e999a88fe\", \"ui_touch_selection_unittests\": \"109049b30c63e8de32a6a94ec712a558f868dcd0\", \"unit_tests\": \"a4c18e4bbb1c36d63e741261a68bee8d8dd29534\", \"updater_tests\": \"8ed5d8dc9a868acbfe618daf47f913f90200b571\", \"url_unittests\": \"f6476b583e5fea9211322a5ee9ea31c836017311\", \"views_unittests\": \"791b7ed193539de70bef203e228dcb7817617591\", \"viz_unittests\": \"0fe2f4b211db799482a14462423fc534e57f7644\", \"vr_common_unittests\": \"ffa9f455a367e32d16d4fd5e65ac1564a34df591\", \"vr_pixeltests\": \"0d52750d91414d3f8ac7786606af023d85f19b82\", \"weblayer_browsertests\": \"3e000d1c9af8cad29cbcb9b9a86b2f70555de6dd\", \"weblayer_unittests\": \"141b51b2077fbcc90fd55bd1c73d478124fdf81a\", \"wm_unittests\": \"e7ba313c993d6e08ccf146f6251f76e54d5abc6e\", \"wtf_unittests\": \"ad05eca5184bb0badee920412f61b2d789c876e3\", \"zlib_unittests\": \"2d4ff3fa2ab1390d2327e6f5a9a147984bfad37e\", \"zucchini_unittests\": \"312d32fda38a462031f96f88a44c6a8f9e23ae22\"}}, \"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-3-xpf4\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5712-339372b\"], \"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": "1614555461968995",
"status": "COMPLETED",
"status_changed_ts": "1614559246647183",
"tags": [
"build_address:luci.chromium.ci/win-asan/17564",
"builder:win-asan",
"buildset:commit/git/202be91e6658aad3047e55a5bacd72b3ca1a5d5c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/202be91e6658aad3047e55a5bacd72b3ca1a5d5c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019439672161026864",
"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/8854009609928950912/+/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:5203d2bc64c16910",
"user_agent:luci-scheduler"
],
"updated_ts": "1614559246651649",
"url": "https://ci.chromium.org/b/8854009609928950912",
"utcnow_ts": "1614559247112673"
}
}
[00:40:47.130] Invocation finished in 1h3m8.6379835s with status SUCCEEDED