[21:43:45.318] New invocation is queued and will start shortly
[21:43:46.788] Starting the invocation (attempt 1)
[21:43:46.892] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:43:46.893] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:43:46.893] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019084449412378096",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"bfc45342f7991083b883f2bfb94ab9bb88a8e290\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019084449412378096",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/bfc45342f7991083b883f2bfb94ab9bb88a8e290",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bfc45342f7991083b883f2bfb94ab9bb88a8e290",
"gitiles_ref:refs/heads/master"
]
}
[21:43:49.788] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614894228594682",
"id": "8853654385315730656",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bfc45342f7991083b883f2bfb94ab9bb88a8e290\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614894229364895",
"tags": [
"build_address:luci.chromium.ci/win-asan/17635",
"builder:win-asan",
"buildset:commit/git/bfc45342f7991083b883f2bfb94ab9bb88a8e290",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bfc45342f7991083b883f2bfb94ab9bb88a8e290",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019084449412378096",
"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/8853654385315730656/+/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": "1614894229365022",
"url": "https://ci.chromium.org/b/8853654385315730656",
"utcnow_ts": "1614894229669143"
}
}
[21:43:49.788] Task URL: https://ci.chromium.org/b/8853654385315730656
[21:43:49.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:2:0) after 1m0s
[21:44:50.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:2:0)
[21:44:50.572] Timer tick, asking Buildbucket for the build status
[21:44:50.665] Build 8853654385315730656: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:44:50.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:3:0) after 1m0s
[21:45:50.800] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:3:0)
[21:45:50.800] Timer tick, asking Buildbucket for the build status
[21:45:50.876] Build 8853654385315730656: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:45:50.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:4:0) after 1m0s
[21:46:30.901] Received PubSub notification, asking Buildbucket for the build status
[21:46:31.063] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:50.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:4:0)
[21:46:50.948] Timer tick, asking Buildbucket for the build status
[21:46:51.011] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:51.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:6:0) after 1m0s
[21:47:51.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:6:0)
[21:47:51.107] Timer tick, asking Buildbucket for the build status
[21:47:51.164] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:51.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:7:0) after 1m0s
[21:48:51.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:7:0)
[21:48:51.234] Timer tick, asking Buildbucket for the build status
[21:48:51.329] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:51.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:8:0) after 1m0s
[21:49:51.729] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:8:0)
[21:49:51.729] Timer tick, asking Buildbucket for the build status
[21:49:55.444] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:55.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:9:0) after 1m0s
[21:50:55.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:9:0)
[21:50:55.451] Timer tick, asking Buildbucket for the build status
[21:50:55.522] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:55.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:10:0) after 1m0s
[21:51:55.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:10:0)
[21:51:55.538] Timer tick, asking Buildbucket for the build status
[21:51:55.633] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:55.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:11:0) after 1m0s
[21:52:55.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:11:0)
[21:52:55.671] Timer tick, asking Buildbucket for the build status
[21:52:57.011] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:57.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:12:0) after 1m0s
[21:53:57.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:12:0)
[21:53:57.154] Timer tick, asking Buildbucket for the build status
[21:53:57.251] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:57.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:13:0) after 1m0s
[21:54:57.318] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:13:0)
[21:54:57.318] Timer tick, asking Buildbucket for the build status
[21:54:57.398] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:57.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:14:0) after 1m0s
[21:55:57.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:14:0)
[21:55:57.448] Timer tick, asking Buildbucket for the build status
[21:55:57.516] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:57.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:15:0) after 1m0s
[21:56:57.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:15:0)
[21:56:57.556] Timer tick, asking Buildbucket for the build status
[21:56:57.628] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:57.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:16:0) after 1m0s
[21:57:57.685] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:16:0)
[21:57:57.685] Timer tick, asking Buildbucket for the build status
[21:57:57.779] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:57.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:17:0) after 1m0s
[21:58:57.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:17:0)
[21:58:57.818] Timer tick, asking Buildbucket for the build status
[21:58:58.016] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:58.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:18:0) after 1m0s
[21:59:58.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:18:0)
[21:59:58.028] Timer tick, asking Buildbucket for the build status
[21:59:58.127] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:58.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:19:0) after 1m0s
[22:00:58.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:19:0)
[22:00:58.145] Timer tick, asking Buildbucket for the build status
[22:00:58.230] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:58.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:20:0) after 1m0s
[22:01:58.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:20:0)
[22:01:58.209] Timer tick, asking Buildbucket for the build status
[22:01:58.316] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:58.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:21:0) after 1m0s
[22:02:58.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:21:0)
[22:02:58.413] Timer tick, asking Buildbucket for the build status
[22:02:58.484] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:58.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:22:0) after 1m0s
[22:03:58.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:22:0)
[22:03:58.533] Timer tick, asking Buildbucket for the build status
[22:03:58.618] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:58.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:23:0) after 1m0s
[22:04:58.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:23:0)
[22:04:58.745] Timer tick, asking Buildbucket for the build status
[22:04:58.814] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:58.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:24:0) after 1m0s
[22:05:58.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:24:0)
[22:05:58.824] Timer tick, asking Buildbucket for the build status
[22:05:59.028] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:59.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:25:0) after 1m0s
[22:06:59.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:25:0)
[22:06:59.501] Timer tick, asking Buildbucket for the build status
[22:06:59.626] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:59.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:26:0) after 1m0s
[22:07:59.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:26:0)
[22:07:59.767] Timer tick, asking Buildbucket for the build status
[22:08:00.378] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:00.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:27:0) after 1m0s
[22:09:00.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:27:0)
[22:09:00.811] Timer tick, asking Buildbucket for the build status
[22:09:00.904] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:00.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:28:0) after 1m0s
[22:10:01.024] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:28:0)
[22:10:01.024] Timer tick, asking Buildbucket for the build status
[22:10:01.093] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:01.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:29:0) after 1m0s
[22:11:01.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:29:0)
[22:11:01.162] Timer tick, asking Buildbucket for the build status
[22:11:01.252] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:01.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:30:0) after 1m0s
[22:12:01.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:30:0)
[22:12:01.365] Timer tick, asking Buildbucket for the build status
[22:12:01.938] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:01.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:31:0) after 1m0s
[22:13:02.082] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:31:0)
[22:13:02.082] Timer tick, asking Buildbucket for the build status
[22:13:02.635] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:02.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:32:0) after 1m0s
[22:14:02.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:32:0)
[22:14:02.710] Timer tick, asking Buildbucket for the build status
[22:14:02.889] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:02.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:33:0) after 1m0s
[22:15:03.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:33:0)
[22:15:03.138] Timer tick, asking Buildbucket for the build status
[22:15:03.219] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:03.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:34:0) after 1m0s
[22:16:03.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:34:0)
[22:16:03.240] Timer tick, asking Buildbucket for the build status
[22:16:03.769] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:03.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:35:0) after 1m0s
[22:17:03.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:35:0)
[22:17:03.897] Timer tick, asking Buildbucket for the build status
[22:17:04.108] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:04.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:36:0) after 1m0s
[22:18:04.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:36:0)
[22:18:04.216] Timer tick, asking Buildbucket for the build status
[22:18:04.508] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:04.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:37:0) after 1m0s
[22:19:04.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:37:0)
[22:19:04.707] Timer tick, asking Buildbucket for the build status
[22:19:04.889] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:04.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:38:0) after 1m0s
[22:20:06.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:38:0)
[22:20:06.165] Timer tick, asking Buildbucket for the build status
[22:20:06.226] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:06.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:39:0) after 1m0s
[22:21:06.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:39:0)
[22:21:06.592] Timer tick, asking Buildbucket for the build status
[22:21:06.890] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:06.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:40:0) after 1m0s
[22:22:07.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:40:0)
[22:22:07.472] Timer tick, asking Buildbucket for the build status
[22:22:07.567] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:07.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:41:0) after 1m0s
[22:23:07.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:41:0)
[22:23:07.638] Timer tick, asking Buildbucket for the build status
[22:23:07.733] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:07.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:42:0) after 1m0s
[22:24:07.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:42:0)
[22:24:07.932] Timer tick, asking Buildbucket for the build status
[22:24:08.100] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:08.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:43:0) after 1m0s
[22:25:08.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:43:0)
[22:25:08.226] Timer tick, asking Buildbucket for the build status
[22:25:08.300] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:08.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:44:0) after 1m0s
[22:26:08.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:44:0)
[22:26:08.522] Timer tick, asking Buildbucket for the build status
[22:26:08.595] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:08.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:45:0) after 1m0s
[22:27:08.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:45:0)
[22:27:08.732] Timer tick, asking Buildbucket for the build status
[22:27:08.810] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:08.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:46:0) after 1m0s
[22:28:09.047] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:46:0)
[22:28:09.047] Timer tick, asking Buildbucket for the build status
[22:28:09.130] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:09.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:47:0) after 1m0s
[22:29:09.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:47:0)
[22:29:09.162] Timer tick, asking Buildbucket for the build status
[22:29:09.569] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:09.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:48:0) after 1m0s
[22:30:09.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:48:0)
[22:30:09.692] Timer tick, asking Buildbucket for the build status
[22:30:09.771] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:09.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:49:0) after 1m0s
[22:31:09.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:49:0)
[22:31:09.870] Timer tick, asking Buildbucket for the build status
[22:31:09.949] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:09.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:50:0) after 1m0s
[22:32:09.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:50:0)
[22:32:09.968] Timer tick, asking Buildbucket for the build status
[22:32:10.038] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:10.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:51:0) after 1m0s
[22:33:10.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:51:0)
[22:33:10.033] Timer tick, asking Buildbucket for the build status
[22:33:10.095] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:10.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:52:0) after 1m0s
[22:34:10.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:52:0)
[22:34:10.205] Timer tick, asking Buildbucket for the build status
[22:34:10.285] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:10.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:53:0) after 1m0s
[22:35:10.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:53:0)
[22:35:10.322] Timer tick, asking Buildbucket for the build status
[22:35:10.387] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:10.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:54:0) after 1m0s
[22:36:10.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:54:0)
[22:36:10.453] Timer tick, asking Buildbucket for the build status
[22:36:10.562] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:10.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:55:0) after 1m0s
[22:37:11.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:55:0)
[22:37:11.245] Timer tick, asking Buildbucket for the build status
[22:37:11.557] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:11.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:56:0) after 1m0s
[22:38:11.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:56:0)
[22:38:11.658] Timer tick, asking Buildbucket for the build status
[22:38:11.988] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:11.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:57:0) after 1m0s
[22:39:12.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:57:0)
[22:39:12.019] Timer tick, asking Buildbucket for the build status
[22:39:12.323] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:12.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:58:0) after 1m0s
[22:40:12.344] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:58:0)
[22:40:12.344] Timer tick, asking Buildbucket for the build status
[22:40:12.413] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:12.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:59:0) after 1m0s
[22:41:12.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:59:0)
[22:41:12.485] Timer tick, asking Buildbucket for the build status
[22:41:12.565] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:12.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:60:0) after 1m0s
[22:42:12.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:60:0)
[22:42:12.682] Timer tick, asking Buildbucket for the build status
[22:42:12.886] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:12.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:61:0) after 1m0s
[22:43:12.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:61:0)
[22:43:12.895] Timer tick, asking Buildbucket for the build status
[22:43:13.294] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:13.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:62:0) after 1m0s
[22:44:13.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:62:0)
[22:44:13.573] Timer tick, asking Buildbucket for the build status
[22:44:13.845] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:13.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:63:0) after 1m0s
[22:45:14.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:63:0)
[22:45:14.159] Timer tick, asking Buildbucket for the build status
[22:45:14.231] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:14.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:64:0) after 1m0s
[22:46:14.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:64:0)
[22:46:14.532] Timer tick, asking Buildbucket for the build status
[22:46:14.625] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:14.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:65:0) after 1m0s
[22:47:14.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:65:0)
[22:47:14.812] Timer tick, asking Buildbucket for the build status
[22:47:14.926] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:14.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:66:0) after 1m0s
[22:48:15.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:66:0)
[22:48:15.124] Timer tick, asking Buildbucket for the build status
[22:48:15.436] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:15.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:67:0) after 1m0s
[22:49:15.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:67:0)
[22:49:15.609] Timer tick, asking Buildbucket for the build status
[22:49:16.980] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:16.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:68:0) after 1m0s
[22:50:17.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:68:0)
[22:50:17.105] Timer tick, asking Buildbucket for the build status
[22:50:17.243] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:17.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:69:0) after 1m0s
[22:51:17.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:69:0)
[22:51:17.312] Timer tick, asking Buildbucket for the build status
[22:51:17.598] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:17.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:70:0) after 1m0s
[22:52:17.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:70:0)
[22:52:17.631] Timer tick, asking Buildbucket for the build status
[22:52:17.757] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:17.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:71:0) after 1m0s
[22:53:17.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:71:0)
[22:53:17.891] Timer tick, asking Buildbucket for the build status
[22:53:17.987] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:17.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:72:0) after 1m0s
[22:54:18.081] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:72:0)
[22:54:18.081] Timer tick, asking Buildbucket for the build status
[22:54:18.333] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:18.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:73:0) after 1m0s
[22:55:18.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:73:0)
[22:55:18.329] Timer tick, asking Buildbucket for the build status
[22:55:18.404] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:18.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:74:0) after 1m0s
[22:56:18.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:74:0)
[22:56:18.486] Timer tick, asking Buildbucket for the build status
[22:56:18.574] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:18.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:75:0) after 1m0s
[22:57:18.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:75:0)
[22:57:18.684] Timer tick, asking Buildbucket for the build status
[22:57:18.863] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:18.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:76:0) after 1m0s
[22:58:18.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:76:0)
[22:58:18.964] Timer tick, asking Buildbucket for the build status
[22:58:19.164] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:19.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:77:0) after 1m0s
[22:59:19.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:77:0)
[22:59:19.505] Timer tick, asking Buildbucket for the build status
[22:59:19.613] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:19.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:78:0) after 1m0s
[23:00:19.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:78:0)
[23:00:19.993] Timer tick, asking Buildbucket for the build status
[23:00:20.076] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:20.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:79:0) after 1m0s
[23:01:20.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:79:0)
[23:01:20.245] Timer tick, asking Buildbucket for the build status
[23:01:20.373] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:20.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:80:0) after 1m0s
[23:02:20.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:80:0)
[23:02:20.710] Timer tick, asking Buildbucket for the build status
[23:02:20.788] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:20.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:81:0) after 1m0s
[23:03:20.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:81:0)
[23:03:20.857] Timer tick, asking Buildbucket for the build status
[23:03:20.948] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:20.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:82:0) after 1m0s
[23:04:20.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:82:0)
[23:04:20.997] Timer tick, asking Buildbucket for the build status
[23:04:21.097] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:21.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:83:0) after 1m0s
[23:05:21.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:83:0)
[23:05:21.519] Timer tick, asking Buildbucket for the build status
[23:05:21.625] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:21.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:84:0) after 1m0s
[23:06:21.842] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:84:0)
[23:06:21.842] Timer tick, asking Buildbucket for the build status
[23:06:21.944] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:21.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:85:0) after 1m0s
[23:07:22.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:85:0)
[23:07:22.143] Timer tick, asking Buildbucket for the build status
[23:07:22.295] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:22.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:86:0) after 1m0s
[23:08:22.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:86:0)
[23:08:22.382] Timer tick, asking Buildbucket for the build status
[23:08:22.488] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:22.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:87:0) after 1m0s
[23:09:22.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:87:0)
[23:09:22.583] Timer tick, asking Buildbucket for the build status
[23:09:22.798] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:22.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:88:0) after 1m0s
[23:10:22.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:88:0)
[23:10:22.844] Timer tick, asking Buildbucket for the build status
[23:10:22.925] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:22.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:89:0) after 1m0s
[23:11:23.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:89:0)
[23:11:23.037] Timer tick, asking Buildbucket for the build status
[23:11:28.127] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:28.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:90:0) after 1m0s
[23:12:28.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:90:0)
[23:12:28.144] Timer tick, asking Buildbucket for the build status
[23:12:29.432] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:29.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:91:0) after 1m0s
[23:13:29.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:91:0)
[23:13:29.604] Timer tick, asking Buildbucket for the build status
[23:13:30.138] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:30.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:92:0) after 1m0s
[23:14:30.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:92:0)
[23:14:30.597] Timer tick, asking Buildbucket for the build status
[23:14:30.700] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:30.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:93:0) after 1m0s
[23:15:30.871] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:93:0)
[23:15:30.871] Timer tick, asking Buildbucket for the build status
[23:15:31.012] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:31.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:94:0) after 1m0s
[23:16:31.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:94:0)
[23:16:31.121] Timer tick, asking Buildbucket for the build status
[23:16:31.539] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:31.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:95:0) after 1m0s
[23:17:31.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:95:0)
[23:17:31.665] Timer tick, asking Buildbucket for the build status
[23:17:31.757] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:31.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:96:0) after 1m0s
[23:18:31.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:96:0)
[23:18:31.805] Timer tick, asking Buildbucket for the build status
[23:18:32.235] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:32.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:97:0) after 1m0s
[23:19:32.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:97:0)
[23:19:32.285] Timer tick, asking Buildbucket for the build status
[23:19:32.442] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:32.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:98:0) after 1m0s
[23:20:32.466] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:98:0)
[23:20:32.466] Timer tick, asking Buildbucket for the build status
[23:20:32.568] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:32.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:99:0) after 1m0s
[23:21:32.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:99:0)
[23:21:32.634] Timer tick, asking Buildbucket for the build status
[23:21:32.982] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:32.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:100:0) after 1m0s
[23:22:33.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:100:0)
[23:22:33.052] Timer tick, asking Buildbucket for the build status
[23:22:33.127] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:33.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:101:0) after 1m0s
[23:23:33.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:101:0)
[23:23:33.180] Timer tick, asking Buildbucket for the build status
[23:23:33.357] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:33.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:102:0) after 1m0s
[23:24:33.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:102:0)
[23:24:33.362] Timer tick, asking Buildbucket for the build status
[23:24:33.617] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:33.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:103:0) after 1m0s
[23:25:33.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:103:0)
[23:25:33.644] Timer tick, asking Buildbucket for the build status
[23:25:33.862] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:33.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:104:0) after 1m0s
[23:26:33.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:104:0)
[23:26:33.986] Timer tick, asking Buildbucket for the build status
[23:26:34.229] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:34.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:105:0) after 1m0s
[23:27:34.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:105:0)
[23:27:34.260] Timer tick, asking Buildbucket for the build status
[23:27:34.516] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:34.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:106:0) after 1m0s
[23:28:34.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:106:0)
[23:28:34.531] Timer tick, asking Buildbucket for the build status
[23:28:34.724] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:34.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:107:0) after 1m0s
[23:29:34.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:107:0)
[23:29:34.778] Timer tick, asking Buildbucket for the build status
[23:29:34.892] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:34.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:108:0) after 1m0s
[23:30:34.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:108:0)
[23:30:34.928] Timer tick, asking Buildbucket for the build status
[23:30:35.627] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:35.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:109:0) after 1m0s
[23:31:35.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:109:0)
[23:31:35.630] Timer tick, asking Buildbucket for the build status
[23:31:35.718] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:35.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:110:0) after 1m0s
[23:32:35.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:110:0)
[23:32:35.783] Timer tick, asking Buildbucket for the build status
[23:32:35.916] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:35.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:111:0) after 1m0s
[23:33:36.020] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:111:0)
[23:33:36.020] Timer tick, asking Buildbucket for the build status
[23:33:36.102] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:36.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:112:0) after 1m0s
[23:34:36.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:112:0)
[23:34:36.148] Timer tick, asking Buildbucket for the build status
[23:34:36.485] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:36.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:113:0) after 1m0s
[23:35:36.525] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:113:0)
[23:35:36.525] Timer tick, asking Buildbucket for the build status
[23:35:36.632] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:36.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:114:0) after 1m0s
[23:36:36.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:114:0)
[23:36:36.769] Timer tick, asking Buildbucket for the build status
[23:36:36.866] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:36.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:115:0) after 1m0s
[23:37:37.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:115:0)
[23:37:37.144] Timer tick, asking Buildbucket for the build status
[23:37:37.983] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:37.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:116:0) after 1m0s
[23:38:38.103] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:116:0)
[23:38:38.103] Timer tick, asking Buildbucket for the build status
[23:38:38.225] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:38.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:117:0) after 1m0s
[23:39:38.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:117:0)
[23:39:38.327] Timer tick, asking Buildbucket for the build status
[23:39:38.455] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:38.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:118:0) after 1m0s
[23:40:38.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:118:0)
[23:40:38.521] Timer tick, asking Buildbucket for the build status
[23:40:38.655] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:38.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:119:0) after 1m0s
[23:41:39.102] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:119:0)
[23:41:39.102] Timer tick, asking Buildbucket for the build status
[23:41:39.185] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:39.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:120:0) after 1m0s
[23:42:39.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:120:0)
[23:42:39.673] Timer tick, asking Buildbucket for the build status
[23:42:39.961] Build 8853654385315730656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:39.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019084449412378096:121:0) after 1m0s
[23:42:55.878] Received PubSub notification, asking Buildbucket for the build status
[23:42:55.975] Build 8853654385315730656: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:42:55.976] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614901375096513",
"created_by": "project:chromium",
"created_ts": "1614894228594682",
"id": "8853654385315730656",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bfc45342f7991083b883f2bfb94ab9bb88a8e290\"}}",
"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\": \"bfc45342f7991083b883f2bfb94ab9bb88a8e290\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-6-55ju\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17635, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"d0c8d6a9157ae9764d52e09ca74f315d218ba483\", \"got_dawn_revision\": \"80915849ce7d5f4c0fe834924ed420ed0002b210\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"bfc45342f7991083b883f2bfb94ab9bb88a8e290\", \"got_revision_cp\": \"refs/heads/master@{#859953}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"6846389f25cad4233a4bd04fd3fc6c60ae30ff35\", \"got_v8_revision\": \"5134dc63648b2cf1d5f95604dc1d87520f121443\", \"got_v8_revision_cp\": \"refs/heads/9.1.44@{#1}\", \"got_webrtc_revision\": \"3208bf102b75e8ec1eaa6640dfa3b545310b1e02\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33382}\", \"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\": \"bfc45342f7991083b883f2bfb94ab9bb88a8e290\", \"swarm_hashes_refs/heads/master(at){#859953}_without_patch\": {\"absl_hardening_tests\": \"3132fbb345e194144819e2dc2ac2f175d3d7d46a\", \"accessibility_unittests\": \"a5e68a07ce8c7ba81015afd60011f21a17e35c86\", \"angle_unittests\": \"6e4586c04e9e4c218fd2741e6a86f9e634905d51\", \"app_shell_unittests\": \"2760d87ed32e14797ca1a24db2b004bc487d9b0e\", \"aura_unittests\": \"b1dcf77bb7730b2ecb7fbe5b7a2839369f4738a3\", \"base_unittests\": \"0f1007661ab7a2b473d7ec02ef59b5c96dac8abf\", \"base_util_unittests\": \"ad99669e669b61500d47f4f4bbfe0dd4edd87cfe\", \"blink_common_unittests\": \"695b58ad880916cb395c634aa959c983977da1b8\", \"blink_fuzzer_unittests\": \"93214acab8b9a3c64e67662e33b14b7ade08aaf9\", \"blink_heap_unittests\": \"5a9e336238a38fcde35425b9b2e6e51cbbd4f8d6\", \"blink_platform_unittests\": \"79bbd54f8e240a1586d019129dc296d7b92dbb9d\", \"blink_unittests\": \"a4788c410676a3020dcb123b320495683b076a1b\", \"boringssl_crypto_tests\": \"c6aa936fdd87631d2ff320231d056fbb41a5952d\", \"boringssl_ssl_tests\": \"b57fe4a387efb29d9b003596ee6e18aa16a5b31e\", \"browser_switcher_bho_unittests\": \"705158a9cb77b5a7ad9d362fb2d7211c24c7d4b5\", \"browser_tests\": \"ab953620bf87a952be4f700b8efb790f60e8df88\", \"capture_unittests\": \"f708cb3cb5b2adc37532ebd0cd13db5c21b11c29\", \"cast_unittests\": \"eb3431bd55f1ea658b4aa4a3eaff20e055e2e212\", \"cc_unittests\": \"4fe8a1934f63bf601cd929d174650119c2dd066e\", \"chrome_app_unittests\": \"2951a44c1945c8c8e78436df1afffc9a2ca3de5c\", \"chrome_cleaner_unittests\": \"058a9959a214ef06d6b7212c77b0fbb7ff745cd4\", \"chrome_elf_unittests\": \"464bcbbfe324d8dd7203c4596efb391cd9765322\", \"chromedriver_unittests\": \"0452c30b14da58eca6094c3ee56a0a0ff6a906ab\", \"color_unittests\": \"b0bea54202455dbf4c74e657e7bb609de18b1312\", \"components_browsertests\": \"f4628a9f84a46b9139ca5d8230008e940ff39698\", \"components_unittests\": \"ce96a8e26e22f14927733c3f1cee80fda80538fb\", \"compositor_unittests\": \"315b9c2d4a8dc70cf9a48aa86e38cf4b1d7a8c82\", \"content_browsertests\": \"8b954e12b162751e0e89fc95b0595b27aa4b5d46\", \"content_unittests\": \"93a8a573d1212094b7e797f0c700f9abd9c64b2b\", \"courgette_unittests\": \"752f2d576740b9c92f3dc2bac07c7dc1d1493f92\", \"crashpad_tests\": \"bd2ee515285bd2d7aa7a2a6522fcabb3a2972e13\", \"cronet_tests\": \"3b1c310d168a159e7df546f02fd02fa1bc5b3200\", \"cronet_unittests\": \"ad1985c14bb55a03fa72dbf0a9bc7f25640f6919\", \"crypto_unittests\": \"c3d400ef69559e8719d23e19822298661234b53a\", \"delayloads_unittests\": \"f3d62f3b343812d553141d66d20286c0da1f4306\", \"device_unittests\": \"e2170019f04f89d05fe495613961bf68223dac88\", \"display_unittests\": \"e15583c07715ed1779ecb695bd56df98cef8fcf2\", \"elevation_service_unittests\": \"d2c8a503c9f32cc6d324b270fb3bf483dafd9b99\", \"events_unittests\": \"9a586b0e537d3d07ddeb31761e123287260b6058\", \"extensions_browsertests\": \"0d9a15cbc59ab19c7a14ab9610e39db1cd8b151a\", \"extensions_unittests\": \"0f774ceda90e14c38ac94ad5bfb9aa7c11234fa8\", \"filesystem_service_unittests\": \"921a0b285f4cdfd313e48ccefe03c0ecdac7b7ea\", \"gcm_unit_tests\": \"e8479b06927f0e29d84d221b8eb8d77049dc0019\", \"gcp_unittests\": \"db4994ef7c670ab79aa1cb37bd81568ec2f94c76\", \"gfx_unittests\": \"de196d7b8a33ca6336303a718961fdf794e31fff\", \"gin_unittests\": \"f95ce2acb52fb24d2a7e0866735edf5c3830b23b\", \"google_apis_unittests\": \"94bb95954fc2c566ececfa693bb4b374b8c0cde6\", \"gpu_unittests\": \"03f6aae23ee709791367ec76946bf62015dd4007\", \"gwp_asan_unittests\": \"bc10b374ead97bc83ea91886ee748ac6dc2e55ae\", \"headless_browsertests\": \"f7193f830c884723d8ec58a469210a13681db32f\", \"headless_unittests\": \"2ea7cd6eb008e382c1928adf099669c5f00719c6\", \"install_static_unittests\": \"14fd58e99fa009ee29262612257f847d0a27bf67\", \"installer_util_unittests\": \"15fe793418381687de2d98abd02e01c784a0423d\", \"interactive_ui_tests\": \"17371a99e04b8a1b48f8b3731881f1cfa3eb1377\", \"ipc_tests\": \"ae48f077dd775422c05607e1107bc0164ed8117b\", \"jingle_unittests\": \"b04b03fb5621f7690acd6f3f96779c02c1d5d2ad\", \"latency_unittests\": \"e352b8704a696018a4ae38ca4e780ba11d0f79b2\", \"libjingle_xmpp_unittests\": \"9c8d980c231c9d6757469ffc61ce0240f6e7a318\", \"liburlpattern_unittests\": \"2bc59e939ed5abaf7dcd7c4d922ca0f32ab79439\", \"media_blink_unittests\": \"d9ca600aba786c87848f5a00b99a03ac71251fb5\", \"media_unittests\": \"ec4b9ec0ca04e1db1241ac077483f07f5b006dd2\", \"message_center_unittests\": \"b2f0a160be2038dfe7a2fb2a9e93e611d626d8be\", \"midi_unittests\": \"64fb1407c3c44d071b98e223f0ac828895b8286b\", \"mojo_core_unittests\": \"c87285ae8ee47dacb6c0f5254d2947bd4aa82fe9\", \"mojo_unittests\": \"386e8a1d4416443510ad9a41d232a4c754018ce1\", \"nacl_loader_unittests\": \"64fc29f35ae2afb02cd815d2bee5ed9e3a4e7ea7\", \"native_theme_unittests\": \"30cc74b1287187ee9c2c40058364ce17aadab51a\", \"net_unittests\": \"e84f410a8d08d51a1c55c3570419a313c39a3e93\", \"notification_helper_unittests\": \"22ef03e0b77818a5e865d12daa05f34f2d7183ce\", \"pdf_unittests\": \"70b4d48a879a70329f5ff1e57516b141e824ab85\", \"perfetto_unittests\": \"eaf4eab1aecfa3ce5e596997b37f01da3e4ecefb\", \"ppapi_unittests\": \"9796664ddcbaa40b0e76421c506a564f4e376b0e\", \"printing_unittests\": \"c3c0aaa99eb80935fa6e34bcbb90a0d1ae8dc0ec\", \"remoting_unittests\": \"a4ece0fdb7aa6c27a863ef6981a4456d7d9a88bc\", \"sbox_integration_tests\": \"c892af98b703c15aa9454fb281db90d25faa1f82\", \"sbox_unittests\": \"680a8590bfad0af0d8e2bd18ead171cd0bacdb74\", \"sbox_validation_tests\": \"d4d0c3c628ed16c343de682af5d613b852f9dbca\", \"service_manager_unittests\": \"27e5193f017e7ff6585620d6d0fffb462f614064\", \"services_unittests\": \"3bd0f8790dfcfd28a0732fa5fd6e53dde7786fe9\", \"setup_unittests\": \"6334b3dc75e7a9010dbdf98b3cd2fa129cc78644\", \"shell_dialogs_unittests\": \"7011dca3e18507a2ce8bfcf15cd5281df0afdf0c\", \"skia_unittests\": \"b84190383946e13ed67d40318dbfb56873456e4c\", \"snapshot_unittests\": \"bc10a39b40ac5308101804c9695672ddf6d8abf7\", \"sql_unittests\": \"822c85692dd1686d96e1921a2da4683e1b74386f\", \"storage_unittests\": \"b920919f70a029fa88b4b21ef40cc0628540dc17\", \"sync_integration_tests\": \"f99a9b7e49b1f3bfa866c6367662ce06f64dec84\", \"traffic_annotation_auditor_unittests\": \"7426fbc2e5cb7b49181e0d0a30fc5a6bc3198dd7\", \"ui_base_unittests\": \"488788b6aea1344ad50d99933fe8e5940de4b48b\", \"ui_touch_selection_unittests\": \"0dd17b99ad5081639208d502cb876166e967f8ed\", \"unit_tests\": \"7cc34aa27656bd0d9cf0dbeae9394c7ac0510228\", \"updater_tests\": \"95cb1bb63ea9b9fe39af8d32bf0b4fecb578d4c6\", \"url_unittests\": \"c945b5853b94be03260a620687f176323c2208a9\", \"views_unittests\": \"ad1f4969676115bb5bf655d4f8ed6ca194cb59b6\", \"viz_unittests\": \"e91cedb2068fcf498c9e2976b3b8b06f144d48ef\", \"vr_common_unittests\": \"e8e2d204337ed29984222205f4c38c0f66ec66b4\", \"vr_pixeltests\": \"0192046b54bb1978761bc83a16ee22cac0a72fce\", \"weblayer_browsertests\": \"164e8330a862185c1fa9d394571827c51946d2c5\", \"weblayer_unittests\": \"beb1fefbb16b54106f4d2bf4987c36351d009b50\", \"wm_unittests\": \"5d58c142c687a45849fe5a3f8713c23e83459522\", \"wtf_unittests\": \"e9838c2a4fe7e0cff805c083273efccf60d81ab9\", \"zlib_unittests\": \"85317918fa0ce8fd390858730794e659a8f39971\", \"zucchini_unittests\": \"4b317bf861e24dad041316add0a6e2884b5866d5\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-6-55ju\"], \"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\": [\"5725-1a0d85e\"], \"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": "1614894385361508",
"status": "COMPLETED",
"status_changed_ts": "1614901375449521",
"tags": [
"build_address:luci.chromium.ci/win-asan/17635",
"builder:win-asan",
"buildset:commit/git/bfc45342f7991083b883f2bfb94ab9bb88a8e290",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bfc45342f7991083b883f2bfb94ab9bb88a8e290",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019084449412378096",
"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/8853654385315730656/+/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:521803f153e7fe10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614901375454186",
"url": "https://ci.chromium.org/b/8853654385315730656",
"utcnow_ts": "1614901375966573"
}
}
[23:42:55.976] Invocation finished in 1h59m10.67131794s with status SUCCEEDED