[06:35:14.894] New invocation is queued and will start shortly
[06:35:15.973] Starting the invocation (attempt 1)
[06:35:16.041] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:35:16.041] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:35:16.041] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022312501563704896",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d000648cfe66994208163760aa94e4e71bdeadc2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022312501563704896",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/d000648cfe66994208163760aa94e4e71bdeadc2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d000648cfe66994208163760aa94e4e71bdeadc2",
"gitiles_ref:refs/heads/master"
]
}
[06:35:16.811] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611815716135137",
"id": "8856882439596211440",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d000648cfe66994208163760aa94e4e71bdeadc2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611815716640592",
"tags": [
"build_address:luci.chromium.ci/win-asan/17045",
"builder:win-asan",
"buildset:commit/git/d000648cfe66994208163760aa94e4e71bdeadc2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d000648cfe66994208163760aa94e4e71bdeadc2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022312501563704896",
"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/8856882439596211440/+/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": "1611815716640710",
"url": "https://ci.chromium.org/b/8856882439596211440",
"utcnow_ts": "1611815716799197"
}
}
[06:35:16.811] Task URL: https://ci.chromium.org/b/8856882439596211440
[06:35:16.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:2:0) after 1m0s
[06:36:16.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:2:0)
[06:36:16.830] Timer tick, asking Buildbucket for the build status
[06:36:16.970] Build 8856882439596211440: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:36:16.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:3:0) after 1m0s
[06:37:17.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:3:0)
[06:37:17.033] Timer tick, asking Buildbucket for the build status
[06:37:17.133] Build 8856882439596211440: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:37:17.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:4:0) after 1m0s
[06:38:17.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:4:0)
[06:38:17.124] Timer tick, asking Buildbucket for the build status
[06:38:17.352] Build 8856882439596211440: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:38:17.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:5:0) after 1m0s
[06:39:17.423] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:5:0)
[06:39:17.423] Timer tick, asking Buildbucket for the build status
[06:39:17.488] Build 8856882439596211440: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:39:17.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:6:0) after 1m0s
[06:40:17.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:6:0)
[06:40:17.544] Timer tick, asking Buildbucket for the build status
[06:40:17.671] Build 8856882439596211440: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:40:17.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:7:0) after 1m0s
[06:41:17.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:7:0)
[06:41:17.684] Timer tick, asking Buildbucket for the build status
[06:41:17.797] Build 8856882439596211440: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:41:17.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:8:0) after 1m0s
[06:41:55.022] Received PubSub notification, asking Buildbucket for the build status
[06:41:55.287] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:17.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:8:0)
[06:42:17.889] Timer tick, asking Buildbucket for the build status
[06:42:17.982] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:17.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:10:0) after 1m0s
[06:43:18.002] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:10:0)
[06:43:18.002] Timer tick, asking Buildbucket for the build status
[06:43:18.198] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:18.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:11:0) after 1m0s
[06:44:18.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:11:0)
[06:44:18.223] Timer tick, asking Buildbucket for the build status
[06:44:18.328] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:18.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:12:0) after 1m0s
[06:45:18.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:12:0)
[06:45:18.352] Timer tick, asking Buildbucket for the build status
[06:45:18.524] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:18.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:13:0) after 1m0s
[06:46:18.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:13:0)
[06:46:18.535] Timer tick, asking Buildbucket for the build status
[06:46:18.657] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:18.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:14:0) after 1m0s
[06:47:18.679] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:14:0)
[06:47:18.679] Timer tick, asking Buildbucket for the build status
[06:47:19.190] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:19.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:15:0) after 1m0s
[06:48:19.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:15:0)
[06:48:19.189] Timer tick, asking Buildbucket for the build status
[06:48:19.286] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:19.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:16:0) after 1m0s
[06:49:19.318] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:16:0)
[06:49:19.318] Timer tick, asking Buildbucket for the build status
[06:49:19.385] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:19.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:17:0) after 1m0s
[06:50:19.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:17:0)
[06:50:19.401] Timer tick, asking Buildbucket for the build status
[06:50:19.994] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:19.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:18:0) after 1m0s
[06:51:20.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:18:0)
[06:51:20.048] Timer tick, asking Buildbucket for the build status
[06:51:20.127] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:20.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:19:0) after 1m0s
[06:52:20.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:19:0)
[06:52:20.104] Timer tick, asking Buildbucket for the build status
[06:52:20.239] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:20.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:20:0) after 1m0s
[06:53:20.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:20:0)
[06:53:20.309] Timer tick, asking Buildbucket for the build status
[06:53:20.422] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:20.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:21:0) after 1m0s
[06:54:20.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:21:0)
[06:54:20.460] Timer tick, asking Buildbucket for the build status
[06:54:20.669] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:20.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:22:0) after 1m0s
[06:55:20.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:22:0)
[06:55:20.721] Timer tick, asking Buildbucket for the build status
[06:55:20.839] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:20.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:23:0) after 1m0s
[06:56:20.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:23:0)
[06:56:20.892] Timer tick, asking Buildbucket for the build status
[06:56:21.169] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:21.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:24:0) after 1m0s
[06:57:21.191] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:24:0)
[06:57:21.191] Timer tick, asking Buildbucket for the build status
[06:57:21.309] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:21.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:25:0) after 1m0s
[06:58:21.305] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:25:0)
[06:58:21.305] Timer tick, asking Buildbucket for the build status
[06:58:21.559] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:21.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:26:0) after 1m0s
[06:59:21.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:26:0)
[06:59:21.554] Timer tick, asking Buildbucket for the build status
[06:59:21.612] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:21.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:27:0) after 1m0s
[07:00:21.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:27:0)
[07:00:21.632] Timer tick, asking Buildbucket for the build status
[07:00:21.762] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:21.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:28:0) after 1m0s
[07:01:21.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:28:0)
[07:01:21.783] Timer tick, asking Buildbucket for the build status
[07:01:22.028] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:22.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:29:0) after 1m0s
[07:02:22.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:29:0)
[07:02:22.054] Timer tick, asking Buildbucket for the build status
[07:02:22.122] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:22.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:30:0) after 1m0s
[07:03:22.141] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:30:0)
[07:03:22.141] Timer tick, asking Buildbucket for the build status
[07:03:22.220] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:22.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:31:0) after 1m0s
[07:04:22.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:31:0)
[07:04:22.237] Timer tick, asking Buildbucket for the build status
[07:04:22.333] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:22.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:32:0) after 1m0s
[07:05:22.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:32:0)
[07:05:22.386] Timer tick, asking Buildbucket for the build status
[07:05:22.517] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:22.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:33:0) after 1m0s
[07:06:22.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:33:0)
[07:06:22.526] Timer tick, asking Buildbucket for the build status
[07:06:22.595] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:22.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:34:0) after 1m0s
[07:07:22.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:34:0)
[07:07:22.601] Timer tick, asking Buildbucket for the build status
[07:07:22.707] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:22.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:35:0) after 1m0s
[07:08:22.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:35:0)
[07:08:22.742] Timer tick, asking Buildbucket for the build status
[07:08:22.890] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:22.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:36:0) after 1m0s
[07:09:23.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:36:0)
[07:09:23.057] Timer tick, asking Buildbucket for the build status
[07:09:23.364] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:23.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:37:0) after 1m0s
[07:10:23.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:37:0)
[07:10:23.505] Timer tick, asking Buildbucket for the build status
[07:10:23.640] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:23.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:38:0) after 1m0s
[07:11:23.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:38:0)
[07:11:23.626] Timer tick, asking Buildbucket for the build status
[07:11:23.715] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:23.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:39:0) after 1m0s
[07:12:23.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:39:0)
[07:12:23.692] Timer tick, asking Buildbucket for the build status
[07:12:23.746] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:23.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:40:0) after 1m0s
[07:13:23.966] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:40:0)
[07:13:23.966] Timer tick, asking Buildbucket for the build status
[07:13:24.024] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:24.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:41:0) after 1m0s
[07:14:23.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:41:0)
[07:14:23.997] Timer tick, asking Buildbucket for the build status
[07:14:24.125] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:24.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:42:0) after 1m0s
[07:15:24.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:42:0)
[07:15:24.119] Timer tick, asking Buildbucket for the build status
[07:15:24.216] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:24.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:43:0) after 1m0s
[07:16:24.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:43:0)
[07:16:24.222] Timer tick, asking Buildbucket for the build status
[07:16:24.308] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:24.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:44:0) after 1m0s
[07:17:24.367] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:44:0)
[07:17:24.367] Timer tick, asking Buildbucket for the build status
[07:17:24.424] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:24.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:45:0) after 1m0s
[07:18:24.500] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:45:0)
[07:18:24.500] Timer tick, asking Buildbucket for the build status
[07:18:24.878] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:24.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:46:0) after 1m0s
[07:19:24.941] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:46:0)
[07:19:24.941] Timer tick, asking Buildbucket for the build status
[07:19:25.231] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:25.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:47:0) after 1m0s
[07:20:25.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:47:0)
[07:20:25.276] Timer tick, asking Buildbucket for the build status
[07:20:25.771] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:25.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:48:0) after 1m0s
[07:21:25.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:48:0)
[07:21:25.769] Timer tick, asking Buildbucket for the build status
[07:21:25.840] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:25.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:49:0) after 1m0s
[07:22:25.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:49:0)
[07:22:25.954] Timer tick, asking Buildbucket for the build status
[07:22:26.015] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:26.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:50:0) after 1m0s
[07:23:26.221] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:50:0)
[07:23:26.221] Timer tick, asking Buildbucket for the build status
[07:23:26.274] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:26.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:51:0) after 1m0s
[07:24:26.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:51:0)
[07:24:26.303] Timer tick, asking Buildbucket for the build status
[07:24:26.355] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:26.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:52:0) after 1m0s
[07:25:26.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:52:0)
[07:25:26.459] Timer tick, asking Buildbucket for the build status
[07:25:26.586] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:26.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:53:0) after 1m0s
[07:26:26.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:53:0)
[07:26:26.630] Timer tick, asking Buildbucket for the build status
[07:26:26.756] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:26.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:54:0) after 1m0s
[07:27:26.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:54:0)
[07:27:26.869] Timer tick, asking Buildbucket for the build status
[07:27:26.937] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:26.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:55:0) after 1m0s
[07:28:27.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:55:0)
[07:28:27.062] Timer tick, asking Buildbucket for the build status
[07:28:27.176] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:27.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:56:0) after 1m0s
[07:29:27.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:56:0)
[07:29:27.304] Timer tick, asking Buildbucket for the build status
[07:29:27.357] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:27.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:57:0) after 1m0s
[07:30:27.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:57:0)
[07:30:27.373] Timer tick, asking Buildbucket for the build status
[07:30:27.475] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:27.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:58:0) after 1m0s
[07:31:27.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:58:0)
[07:31:27.554] Timer tick, asking Buildbucket for the build status
[07:31:27.641] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:27.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:59:0) after 1m0s
[07:32:27.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:59:0)
[07:32:27.812] Timer tick, asking Buildbucket for the build status
[07:32:27.877] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:27.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:60:0) after 1m0s
[07:33:28.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:60:0)
[07:33:28.037] Timer tick, asking Buildbucket for the build status
[07:33:28.118] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:28.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:61:0) after 1m0s
[07:34:28.230] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:61:0)
[07:34:28.230] Timer tick, asking Buildbucket for the build status
[07:34:28.292] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:28.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:62:0) after 1m0s
[07:35:28.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:62:0)
[07:35:28.912] Timer tick, asking Buildbucket for the build status
[07:35:29.034] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:29.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:63:0) after 1m0s
[07:36:29.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:63:0)
[07:36:29.234] Timer tick, asking Buildbucket for the build status
[07:36:29.373] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:29.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:64:0) after 1m0s
[07:37:29.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:64:0)
[07:37:29.424] Timer tick, asking Buildbucket for the build status
[07:37:29.498] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:29.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:65:0) after 1m0s
[07:38:29.547] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:65:0)
[07:38:29.547] Timer tick, asking Buildbucket for the build status
[07:38:29.619] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:29.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:66:0) after 1m0s
[07:39:29.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:66:0)
[07:39:29.653] Timer tick, asking Buildbucket for the build status
[07:39:29.751] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:29.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:67:0) after 1m0s
[07:40:29.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:67:0)
[07:40:29.769] Timer tick, asking Buildbucket for the build status
[07:40:29.862] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:29.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:68:0) after 1m0s
[07:41:29.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:68:0)
[07:41:29.890] Timer tick, asking Buildbucket for the build status
[07:41:30.038] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:30.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:69:0) after 1m0s
[07:42:30.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:69:0)
[07:42:30.136] Timer tick, asking Buildbucket for the build status
[07:42:32.060] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:32.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:70:0) after 1m0s
[07:43:32.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:70:0)
[07:43:32.111] Timer tick, asking Buildbucket for the build status
[07:43:32.259] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:32.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:71:0) after 1m0s
[07:44:32.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:71:0)
[07:44:32.903] Timer tick, asking Buildbucket for the build status
[07:44:32.988] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:32.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:72:0) after 1m0s
[07:45:33.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:72:0)
[07:45:33.338] Timer tick, asking Buildbucket for the build status
[07:45:33.463] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:33.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:73:0) after 1m0s
[07:46:33.530] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:73:0)
[07:46:33.530] Timer tick, asking Buildbucket for the build status
[07:46:33.650] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:33.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:74:0) after 1m0s
[07:47:33.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:74:0)
[07:47:33.742] Timer tick, asking Buildbucket for the build status
[07:47:34.409] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:34.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:75:0) after 1m0s
[07:48:34.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:75:0)
[07:48:34.581] Timer tick, asking Buildbucket for the build status
[07:48:34.817] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:34.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:76:0) after 1m0s
[07:49:34.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:76:0)
[07:49:34.850] Timer tick, asking Buildbucket for the build status
[07:49:35.493] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:35.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:77:0) after 1m0s
[07:50:35.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:77:0)
[07:50:35.479] Timer tick, asking Buildbucket for the build status
[07:50:39.033] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:39.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:78:0) after 1m0s
[07:51:39.155] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:78:0)
[07:51:39.156] Timer tick, asking Buildbucket for the build status
[07:51:39.266] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:39.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:79:0) after 1m0s
[07:52:39.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:79:0)
[07:52:39.291] Timer tick, asking Buildbucket for the build status
[07:52:39.417] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:39.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:80:0) after 1m0s
[07:53:39.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:80:0)
[07:53:39.511] Timer tick, asking Buildbucket for the build status
[07:53:39.633] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:39.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:81:0) after 1m0s
[07:54:39.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:81:0)
[07:54:39.638] Timer tick, asking Buildbucket for the build status
[07:54:39.773] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:39.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:82:0) after 1m0s
[07:55:39.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:82:0)
[07:55:39.852] Timer tick, asking Buildbucket for the build status
[07:55:40.304] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:40.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:83:0) after 1m0s
[07:56:40.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:83:0)
[07:56:40.298] Timer tick, asking Buildbucket for the build status
[07:56:40.869] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:40.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:84:0) after 1m0s
[07:57:40.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:84:0)
[07:57:40.879] Timer tick, asking Buildbucket for the build status
[07:57:41.083] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:41.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:85:0) after 1m0s
[07:58:41.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:85:0)
[07:58:41.109] Timer tick, asking Buildbucket for the build status
[07:58:41.192] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:41.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:86:0) after 1m0s
[07:59:41.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:86:0)
[07:59:41.242] Timer tick, asking Buildbucket for the build status
[07:59:41.387] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:41.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:87:0) after 1m0s
[08:00:41.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:87:0)
[08:00:41.465] Timer tick, asking Buildbucket for the build status
[08:00:41.618] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:41.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:88:0) after 1m0s
[08:01:41.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:88:0)
[08:01:41.666] Timer tick, asking Buildbucket for the build status
[08:01:41.737] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:41.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:89:0) after 1m0s
[08:02:41.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:89:0)
[08:02:41.770] Timer tick, asking Buildbucket for the build status
[08:02:41.846] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:41.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:90:0) after 1m0s
[08:03:41.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:90:0)
[08:03:41.890] Timer tick, asking Buildbucket for the build status
[08:03:42.043] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:42.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:91:0) after 1m0s
[08:04:42.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:91:0)
[08:04:42.142] Timer tick, asking Buildbucket for the build status
[08:04:42.230] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:42.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:92:0) after 1m0s
[08:05:42.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:92:0)
[08:05:42.422] Timer tick, asking Buildbucket for the build status
[08:05:42.560] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:42.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:93:0) after 1m0s
[08:06:42.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:93:0)
[08:06:42.670] Timer tick, asking Buildbucket for the build status
[08:06:43.865] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:43.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:94:0) after 1m0s
[08:07:43.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:94:0)
[08:07:43.915] Timer tick, asking Buildbucket for the build status
[08:07:44.031] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:44.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:95:0) after 1m0s
[08:08:44.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:95:0)
[08:08:44.058] Timer tick, asking Buildbucket for the build status
[08:08:44.620] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:44.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:96:0) after 1m0s
[08:09:44.640] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:96:0)
[08:09:44.640] Timer tick, asking Buildbucket for the build status
[08:09:44.928] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:44.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:97:0) after 1m0s
[08:10:44.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:97:0)
[08:10:44.979] Timer tick, asking Buildbucket for the build status
[08:10:45.167] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:45.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:98:0) after 1m0s
[08:11:45.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:98:0)
[08:11:45.237] Timer tick, asking Buildbucket for the build status
[08:11:45.653] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:45.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:99:0) after 1m0s
[08:12:45.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:99:0)
[08:12:45.715] Timer tick, asking Buildbucket for the build status
[08:12:45.828] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:45.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:100:0) after 1m0s
[08:13:45.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:100:0)
[08:13:45.838] Timer tick, asking Buildbucket for the build status
[08:13:46.228] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:46.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:101:0) after 1m0s
[08:14:46.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:101:0)
[08:14:46.340] Timer tick, asking Buildbucket for the build status
[08:14:46.436] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:46.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:102:0) after 1m0s
[08:15:46.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:102:0)
[08:15:46.485] Timer tick, asking Buildbucket for the build status
[08:15:46.633] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:46.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:103:0) after 1m0s
[08:16:46.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:103:0)
[08:16:46.660] Timer tick, asking Buildbucket for the build status
[08:16:49.338] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:49.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:104:0) after 1m0s
[08:17:49.488] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:104:0)
[08:17:49.488] Timer tick, asking Buildbucket for the build status
[08:17:49.583] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:49.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:105:0) after 1m0s
[08:18:49.698] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:105:0)
[08:18:49.698] Timer tick, asking Buildbucket for the build status
[08:18:49.813] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:49.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:106:0) after 1m0s
[08:19:49.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:106:0)
[08:19:49.913] Timer tick, asking Buildbucket for the build status
[08:19:50.043] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:50.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:107:0) after 1m0s
[08:20:50.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:107:0)
[08:20:50.166] Timer tick, asking Buildbucket for the build status
[08:20:50.568] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:50.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:108:0) after 1m0s
[08:21:50.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:108:0)
[08:21:50.628] Timer tick, asking Buildbucket for the build status
[08:21:50.734] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:50.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:109:0) after 1m0s
[08:22:50.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:109:0)
[08:22:50.832] Timer tick, asking Buildbucket for the build status
[08:22:51.067] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:51.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:110:0) after 1m0s
[08:23:51.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:110:0)
[08:23:51.187] Timer tick, asking Buildbucket for the build status
[08:23:51.536] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:51.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:111:0) after 1m0s
[08:24:51.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:111:0)
[08:24:51.562] Timer tick, asking Buildbucket for the build status
[08:24:51.722] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:51.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:112:0) after 1m0s
[08:25:51.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:112:0)
[08:25:51.797] Timer tick, asking Buildbucket for the build status
[08:25:51.894] Build 8856882439596211440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:51.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022312501563704896:113:0) after 1m0s
[08:26:13.657] Received PubSub notification, asking Buildbucket for the build status
[08:26:13.730] Build 8856882439596211440: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:26:13.730] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611822372477952",
"created_by": "project:chromium",
"created_ts": "1611815716135137",
"id": "8856882439596211440",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d000648cfe66994208163760aa94e4e71bdeadc2\"}}",
"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\": \"d000648cfe66994208163760aa94e4e71bdeadc2\", \"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-10-zyt5\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17045, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"351e6e8c7b4952f7d01facdb322ac765d876bae2\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"d000648cfe66994208163760aa94e4e71bdeadc2\", \"got_revision_cp\": \"refs/heads/master@{#847994}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"54a4e0518d25fab76af6ec1d99b860b0b53b47cf\", \"got_v8_revision_cp\": \"refs/heads/9.0.90@{#1}\", \"got_webrtc_revision\": \"49dbad021e802197b3c0b966c03b4aa766a5b578\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33080}\", \"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\": \"d000648cfe66994208163760aa94e4e71bdeadc2\", \"swarm_hashes_refs/heads/master(at){#847994}_without_patch\": {\"absl_hardening_tests\": \"032995192725a1aac5944962d9f875e705f00cda\", \"accessibility_unittests\": \"3ca3e223746e22b5b7d23c67ee092386fb7bddc0\", \"angle_unittests\": \"a909378d73b21eab0f02b9a1e4132e8f110ba7fc\", \"app_shell_unittests\": \"7cabba54724f59415f7742b841917ae673e64650\", \"aura_unittests\": \"6e419f5893fbcaa7d8239a0e18af4de0ef956f76\", \"base_unittests\": \"b0061a102fe346472b568442045e1819d8a62ecb\", \"base_util_unittests\": \"5dfd7b01900abd3a67d15ca32d6a4a4871c52e18\", \"blink_common_unittests\": \"f4a529ba30ccab892b78629b3a61767c61135bd2\", \"blink_fuzzer_unittests\": \"e96a7f9c0153cc727dd5258ef174d781bb98422a\", \"blink_heap_unittests\": \"5059dbab27f719b9cb73d2ff231a3f239e84fbbd\", \"blink_platform_unittests\": \"e1eef27f4b7cc731da7dc35129cacf897a87e833\", \"blink_unittests\": \"dd30fb1e83f50cfe663369047195caffe5f1e585\", \"boringssl_crypto_tests\": \"057dcb7233344fcde7174204d26b295d224747ba\", \"boringssl_ssl_tests\": \"30db1adffb1c983beb1010c77b1d8aadd413bae5\", \"browser_switcher_bho_unittests\": \"923c5c30de3d5232dae827c9160a7d64287e22df\", \"browser_tests\": \"2c41e52d99a4f2cf1efd9e287e3000697fcfbe67\", \"capture_unittests\": \"7435ded6a85eb66f092983ab1d9a31302a862b95\", \"cast_unittests\": \"dbd66bf6152c4e0fdf4ce1c58f7f07f97d56185e\", \"cc_unittests\": \"23a6b5e89fe1ad9d9978d79e68f222c873678e7e\", \"chrome_app_unittests\": \"a6e0a506f67ca2e0af9e6082a776a920fd7e666f\", \"chrome_cleaner_unittests\": \"dd0b7af1fb539b0f3b4f4ce7158605fa9c2251d8\", \"chrome_elf_unittests\": \"9dcfbc0e67c91101553aa6a04dc7eb1b59ae2c34\", \"chromedriver_unittests\": \"b8594f04fae8bc4613a692a9af0adfdeef62b220\", \"components_browsertests\": \"e7a47c7f215dae4ae5d455cfe8959e3054ab960e\", \"components_unittests\": \"a5190ae4c4a8afbee3a3d3fd55fee24498115cb7\", \"compositor_unittests\": \"a4dc0ebed888484b54585ce58bf598069c98d30e\", \"content_browsertests\": \"cfd57222fb7dc264f4cc6ec51881bc6ba041db36\", \"content_unittests\": \"3d322066783934e792cf898d58be0bb02c6cbbb2\", \"courgette_unittests\": \"31e3d604210a7bfed18e5a5647c09a5c1496195b\", \"crashpad_tests\": \"d2d346f3bc3279d7946ee4f5e68b344cba51c4b0\", \"cronet_tests\": \"a9e55b14c7ff68be573077c538b2977ec37bbf31\", \"cronet_unittests\": \"24d97f207fb750550d808e098d8ccda873ab0af6\", \"crypto_unittests\": \"4aae22388738fb613097774e9429205d3e3b7b1d\", \"delayloads_unittests\": \"f1de467fa68803ee2798c1bb1560105cc070d2b0\", \"device_unittests\": \"28276a25f9d984eab8a91d725b2cb816ae64608d\", \"display_unittests\": \"82f2b26cdf017823006da62c20b9b87548265499\", \"elevation_service_unittests\": \"115056f92cadcf9259825a63338aaaa313230001\", \"events_unittests\": \"ab761336f183affbb0950031d01665c68883fafc\", \"extensions_browsertests\": \"eec9fe232a585cbd991b53d0c537c1939a2ce3d1\", \"extensions_unittests\": \"974d6156104afb6a188368400c6a717acd2cebe7\", \"filesystem_service_unittests\": \"8133b80c2def74bf759ebefc41075fd71203822f\", \"gcm_unit_tests\": \"7de2c608c71235bd031e6de6a983980408b7c99f\", \"gcp_unittests\": \"84d7ec7e6aefbecf95a904354f4a0cd2c97d67ec\", \"gfx_unittests\": \"09542f2cb2ff77fd5df51b0d7a748bb546d675b0\", \"gin_unittests\": \"bb8a227cdf4ab6aa2636b5f47ed8d817b9b941b8\", \"google_apis_unittests\": \"0a339bb66d6eabc4eaee5877f72894b38465c4d9\", \"gpu_unittests\": \"a6fd2062f37fd3b633e32bae2d1144b0f6631533\", \"gwp_asan_unittests\": \"b05cd75bc068bd66f8f4eabba4b50b076439b4c0\", \"headless_browsertests\": \"89ee7eb7654782d2dce059a343afe1988d145b03\", \"headless_unittests\": \"f42809da9b389ff4a7c7a3e5a5102207efe43b2b\", \"install_static_unittests\": \"d5d918de1e28212375c8208fe2214a32b444d11c\", \"installer_util_unittests\": \"85c6a547aa4d4144203c7750b69b88987cb6f1dc\", \"interactive_ui_tests\": \"1449abf4d362777bb05b3b1e15a0e93a09c2889a\", \"ipc_tests\": \"3517d4f2dcfb84c100f7e6c75d4778d6187ea329\", \"jingle_unittests\": \"7c0b2b1e1710cbccbd129431a9d7a49cdac136b0\", \"latency_unittests\": \"1efb57fc89dc2ee41ec8cb12bc8cf73e2d883c5f\", \"libjingle_xmpp_unittests\": \"54b7d0ab0fbf12823fc95a12f37d86d154e32d1e\", \"liburlpattern_unittests\": \"1c0cabba5088145eb2496c2b35fe43f6ce7d4363\", \"media_blink_unittests\": \"a8565a27576e181a3f26de872aba5b32360759e7\", \"media_unittests\": \"658cc0e34adad9a54246bc9c0c14fdb5a3db9ca1\", \"message_center_unittests\": \"aa24eca79e6f90a7614784890cff3642b1b21535\", \"midi_unittests\": \"35a9f39a8a509aa1ac1a2a6a71f2cfd7e0fa8094\", \"mojo_core_unittests\": \"09efd2349cdd8ba5764f8d7338ef6fd078362689\", \"mojo_unittests\": \"244108e169ff7bde46db99577b4698dcf5d2a406\", \"nacl_loader_unittests\": \"3a486ca6e8fe70fdd41e1ba9961a1cd535af5f21\", \"native_theme_unittests\": \"2a9dfda709d22b0aeebe897dcdaa0e79fca80b07\", \"net_unittests\": \"0c1624962b1d263e02df5e2edf3bb7b07084faaa\", \"notification_helper_unittests\": \"9bcc5455baab7f814542d06a0da5ae7aae155cb2\", \"pdf_unittests\": \"1bb43f80d0ad1b8493609e35417ac585532e88c0\", \"perfetto_unittests\": \"d23224444cb31f80d448b1be19164f24f0860c65\", \"ppapi_unittests\": \"18eda1f7e4a9e5c27282065eb3d7f29f6c3df463\", \"printing_unittests\": \"bfb97aced7e2e064839fe25e0f1580f64fecfc36\", \"remoting_unittests\": \"afa635cde9b55994475502dbb2f28ece4935dba6\", \"sbox_integration_tests\": \"aad7df834c804ce3b00f1ee0b5dc491aa5994c5a\", \"sbox_unittests\": \"d7fc81dd5b66a49f7e863ad4bb78ee3fc59dc110\", \"sbox_validation_tests\": \"54114eb3b37e0729e17ec70e386997209ad927b1\", \"service_manager_unittests\": \"a045c386746898097f9dd74944d8be4166a0f38f\", \"services_unittests\": \"7e16bd937e2f5fba4067007e5387fb60a4c30407\", \"setup_unittests\": \"09aeed7ad4051834e4d230f10c62a87660a06ebf\", \"shell_dialogs_unittests\": \"e21170dc6252fd63b2378acacb92e6757bd1fc32\", \"skia_unittests\": \"6735448877bb47283246e942829cf05871386409\", \"snapshot_unittests\": \"0be926cf0a93b9d978a0f8f12faf5580ec24ab87\", \"sql_unittests\": \"5f9bd95666dd3238da486b33c908649b56320410\", \"storage_unittests\": \"4bc242924416f56e371448bebda1c2e9d3378247\", \"sync_integration_tests\": \"26646fda3126ff5aae62242d2ccdc68e76350932\", \"traffic_annotation_auditor_unittests\": \"46e0cc834633d50043626d6956e713e7880786b3\", \"ui_base_unittests\": \"c82f9ccb10e9a2f016c0e549258f4c624f151e79\", \"ui_touch_selection_unittests\": \"2cfe322bf7b8d2a5a2c9c46f35afbf7e71b65cad\", \"unit_tests\": \"bcf5cb6a2a15b94a00601f81d5411a08f78c2301\", \"updater_tests\": \"71b2abc85b10f6a5cc6217d4e91cf29676c83011\", \"url_unittests\": \"a6fe8f0362590065745812b4f4d995886c666c8f\", \"views_unittests\": \"c31a4c19c21fd32b00f3f199e65179c8dfef46c6\", \"viz_unittests\": \"8f422461142f51a4e83369621b0de108fe8a135d\", \"vr_common_unittests\": \"2ea7e173bc04f64f9f04fa7ded07d8825b2bd651\", \"vr_pixeltests\": \"8e6553a3a9ee8bcdfde362c31de8725359114aa3\", \"weblayer_browsertests\": \"91b5edbe953e31b5d9e5b27fcf64b76b668c7ed4\", \"weblayer_unittests\": \"366680ef186e56478a40c6e98c58b4f51e263b53\", \"wm_unittests\": \"2221a620ddbcb0bcc62b45c58f6e4a280706699e\", \"wtf_unittests\": \"d7c314afb783722a7f0bfc5f3e6af31119fedb3f\", \"zlib_unittests\": \"ad238152baaf30083f36a5de627f04af912d1111\", \"zucchini_unittests\": \"4b568190783f8121a626c8464483ce6bf29cba48\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"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-10-zyt5\"], \"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\": [\"5673-2413400\"], \"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": "1611816114114408",
"status": "COMPLETED",
"status_changed_ts": "1611822372843579",
"tags": [
"build_address:luci.chromium.ci/win-asan/17045",
"builder:win-asan",
"buildset:commit/git/d000648cfe66994208163760aa94e4e71bdeadc2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d000648cfe66994208163760aa94e4e71bdeadc2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022312501563704896",
"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/8856882439596211440/+/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:516085903543c110",
"user_agent:luci-scheduler"
],
"updated_ts": "1611822372847984",
"url": "https://ci.chromium.org/b/8856882439596211440",
"utcnow_ts": "1611822373721980"
}
}
[08:26:13.730] Invocation finished in 1h50m58.854477271s with status SUCCEEDED