[15:41:22.633] New invocation is queued and will start shortly
[15:41:23.721] Starting the invocation (attempt 1)
[15:41:23.756] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:41:23.758] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:41:23.758] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019741427036829136",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3b86e7e18c213aabb683f44e0215b3e737f5e771\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019741427036829136",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/3b86e7e18c213aabb683f44e0215b3e737f5e771",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3b86e7e18c213aabb683f44e0215b3e737f5e771",
"gitiles_ref:refs/heads/master"
]
}
[15:41:24.344] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614267683829119",
"id": "8854311365119232736",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3b86e7e18c213aabb683f44e0215b3e737f5e771\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614267684231997",
"tags": [
"build_address:luci.chromium.ci/win-asan/17529",
"builder:win-asan",
"buildset:commit/git/3b86e7e18c213aabb683f44e0215b3e737f5e771",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3b86e7e18c213aabb683f44e0215b3e737f5e771",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019741427036829136",
"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/8854311365119232736/+/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": "1614267684232101",
"url": "https://ci.chromium.org/b/8854311365119232736",
"utcnow_ts": "1614267684338607"
}
}
[15:41:24.344] Task URL: https://ci.chromium.org/b/8854311365119232736
[15:41:24.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:2:0) after 1m0s
[15:42:24.347] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:2:0)
[15:42:24.347] Timer tick, asking Buildbucket for the build status
[15:42:24.428] Build 8854311365119232736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:42:24.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:3:0) after 1m0s
[15:43:24.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:3:0)
[15:43:24.504] Timer tick, asking Buildbucket for the build status
[15:43:24.569] Build 8854311365119232736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:43:24.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:4:0) after 1m0s
[15:44:24.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:4:0)
[15:44:24.805] Timer tick, asking Buildbucket for the build status
[15:44:24.876] Build 8854311365119232736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:44:24.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:5:0) after 1m0s
[15:45:15.505] Received PubSub notification, asking Buildbucket for the build status
[15:45:15.624] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:25.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:5:0)
[15:45:25.109] Timer tick, asking Buildbucket for the build status
[15:45:25.187] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:25.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:7:0) after 1m0s
[15:46:25.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:7:0)
[15:46:25.362] Timer tick, asking Buildbucket for the build status
[15:46:25.433] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:25.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:8:0) after 1m0s
[15:47:25.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:8:0)
[15:47:25.586] Timer tick, asking Buildbucket for the build status
[15:47:25.676] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:25.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:9:0) after 1m0s
[15:48:25.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:9:0)
[15:48:25.770] Timer tick, asking Buildbucket for the build status
[15:48:25.985] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:25.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:10:0) after 1m0s
[15:49:26.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:10:0)
[15:49:26.146] Timer tick, asking Buildbucket for the build status
[15:49:26.218] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:26.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:11:0) after 1m0s
[15:50:26.370] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:11:0)
[15:50:26.370] Timer tick, asking Buildbucket for the build status
[15:50:26.712] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:26.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:12:0) after 1m0s
[15:51:27.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:12:0)
[15:51:27.655] Timer tick, asking Buildbucket for the build status
[15:51:27.720] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:27.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:13:0) after 1m0s
[15:52:28.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:13:0)
[15:52:28.006] Timer tick, asking Buildbucket for the build status
[15:52:28.217] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:28.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:14:0) after 1m0s
[15:53:28.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:14:0)
[15:53:28.323] Timer tick, asking Buildbucket for the build status
[15:53:28.410] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:28.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:15:0) after 1m0s
[15:54:28.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:15:0)
[15:54:28.476] Timer tick, asking Buildbucket for the build status
[15:54:28.546] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:28.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:16:0) after 1m0s
[15:55:28.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:16:0)
[15:55:28.673] Timer tick, asking Buildbucket for the build status
[15:55:28.773] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:28.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:17:0) after 1m0s
[15:56:29.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:17:0)
[15:56:29.055] Timer tick, asking Buildbucket for the build status
[15:56:29.256] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:29.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:18:0) after 1m0s
[15:57:29.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:18:0)
[15:57:29.268] Timer tick, asking Buildbucket for the build status
[15:57:29.391] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:29.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:19:0) after 1m0s
[15:58:29.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:19:0)
[15:58:29.582] Timer tick, asking Buildbucket for the build status
[15:58:29.700] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:29.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:20:0) after 1m0s
[15:59:29.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:20:0)
[15:59:29.761] Timer tick, asking Buildbucket for the build status
[15:59:29.843] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:29.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:21:0) after 1m0s
[16:00:29.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:21:0)
[16:00:29.953] Timer tick, asking Buildbucket for the build status
[16:00:30.121] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:30.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:22:0) after 1m0s
[16:01:30.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:22:0)
[16:01:30.248] Timer tick, asking Buildbucket for the build status
[16:01:30.362] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:30.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:23:0) after 1m0s
[16:02:30.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:23:0)
[16:02:30.448] Timer tick, asking Buildbucket for the build status
[16:02:30.602] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:30.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:24:0) after 1m0s
[16:03:30.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:24:0)
[16:03:30.708] Timer tick, asking Buildbucket for the build status
[16:03:30.902] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:30.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:25:0) after 1m0s
[16:04:30.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:25:0)
[16:04:30.910] Timer tick, asking Buildbucket for the build status
[16:04:31.044] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:31.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:26:0) after 1m0s
[16:05:31.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:26:0)
[16:05:31.340] Timer tick, asking Buildbucket for the build status
[16:05:31.468] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:31.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:27:0) after 1m0s
[16:06:31.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:27:0)
[16:06:31.708] Timer tick, asking Buildbucket for the build status
[16:06:31.936] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:31.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:28:0) after 1m0s
[16:07:32.199] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:28:0)
[16:07:32.199] Timer tick, asking Buildbucket for the build status
[16:07:32.543] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:32.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:29:0) after 1m0s
[16:08:32.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:29:0)
[16:08:32.699] Timer tick, asking Buildbucket for the build status
[16:08:33.093] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:33.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:30:0) after 1m0s
[16:09:33.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:30:0)
[16:09:33.281] Timer tick, asking Buildbucket for the build status
[16:09:33.392] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:33.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:31:0) after 1m0s
[16:10:33.443] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:31:0)
[16:10:33.443] Timer tick, asking Buildbucket for the build status
[16:10:33.561] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:33.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:32:0) after 1m0s
[16:11:33.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:32:0)
[16:11:33.651] Timer tick, asking Buildbucket for the build status
[16:11:34.256] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:34.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:33:0) after 1m0s
[16:12:34.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:33:0)
[16:12:34.280] Timer tick, asking Buildbucket for the build status
[16:12:34.752] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:34.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:34:0) after 1m0s
[16:13:34.934] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:34:0)
[16:13:34.934] Timer tick, asking Buildbucket for the build status
[16:13:35.014] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:35.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:35:0) after 1m0s
[16:14:35.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:35:0)
[16:14:35.123] Timer tick, asking Buildbucket for the build status
[16:14:35.247] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:35.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:36:0) after 1m0s
[16:15:35.815] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:36:0)
[16:15:35.815] Timer tick, asking Buildbucket for the build status
[16:15:35.954] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:35.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:37:0) after 1m0s
[16:16:36.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:37:0)
[16:16:36.077] Timer tick, asking Buildbucket for the build status
[16:16:36.167] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:36.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:38:0) after 1m0s
[16:17:36.170] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:38:0)
[16:17:36.170] Timer tick, asking Buildbucket for the build status
[16:17:36.285] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:36.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:39:0) after 1m0s
[16:18:36.418] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:39:0)
[16:18:36.418] Timer tick, asking Buildbucket for the build status
[16:18:36.528] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:36.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:40:0) after 1m0s
[16:19:36.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:40:0)
[16:19:36.593] Timer tick, asking Buildbucket for the build status
[16:19:36.662] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:36.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:41:0) after 1m0s
[16:20:36.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:41:0)
[16:20:36.668] Timer tick, asking Buildbucket for the build status
[16:20:36.789] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:36.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:42:0) after 1m0s
[16:21:36.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:42:0)
[16:21:36.770] Timer tick, asking Buildbucket for the build status
[16:21:36.879] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:36.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:43:0) after 1m0s
[16:22:36.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:43:0)
[16:22:36.898] Timer tick, asking Buildbucket for the build status
[16:22:36.972] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:36.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:44:0) after 1m0s
[16:23:37.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:44:0)
[16:23:37.022] Timer tick, asking Buildbucket for the build status
[16:23:37.086] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:37.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:45:0) after 1m0s
[16:24:37.137] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:45:0)
[16:24:37.137] Timer tick, asking Buildbucket for the build status
[16:24:37.220] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:37.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:46:0) after 1m0s
[16:25:37.197] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:46:0)
[16:25:37.197] Timer tick, asking Buildbucket for the build status
[16:25:37.284] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:37.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:47:0) after 1m0s
[16:26:37.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:47:0)
[16:26:37.453] Timer tick, asking Buildbucket for the build status
[16:26:37.540] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:37.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:48:0) after 1m0s
[16:27:37.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:48:0)
[16:27:37.518] Timer tick, asking Buildbucket for the build status
[16:27:37.590] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:37.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:49:0) after 1m0s
[16:28:37.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:49:0)
[16:28:37.585] Timer tick, asking Buildbucket for the build status
[16:28:37.675] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:37.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:50:0) after 1m0s
[16:29:37.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:50:0)
[16:29:37.704] Timer tick, asking Buildbucket for the build status
[16:29:37.952] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:37.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:51:0) after 1m0s
[16:30:37.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:51:0)
[16:30:37.950] Timer tick, asking Buildbucket for the build status
[16:30:38.033] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:38.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:52:0) after 1m0s
[16:31:38.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:52:0)
[16:31:38.122] Timer tick, asking Buildbucket for the build status
[16:31:38.324] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:38.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:53:0) after 1m0s
[16:32:38.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:53:0)
[16:32:38.374] Timer tick, asking Buildbucket for the build status
[16:32:38.631] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:38.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:54:0) after 1m0s
[16:33:38.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:54:0)
[16:33:38.624] Timer tick, asking Buildbucket for the build status
[16:33:38.697] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:38.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:55:0) after 1m0s
[16:34:38.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:55:0)
[16:34:38.717] Timer tick, asking Buildbucket for the build status
[16:34:38.787] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:38.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:56:0) after 1m0s
[16:35:38.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:56:0)
[16:35:38.854] Timer tick, asking Buildbucket for the build status
[16:35:39.185] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:39.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:57:0) after 1m0s
[16:36:39.174] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:57:0)
[16:36:39.174] Timer tick, asking Buildbucket for the build status
[16:36:39.247] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:39.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:58:0) after 1m0s
[16:37:39.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:58:0)
[16:37:39.385] Timer tick, asking Buildbucket for the build status
[16:37:39.465] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:39.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:59:0) after 1m0s
[16:38:39.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:59:0)
[16:38:39.480] Timer tick, asking Buildbucket for the build status
[16:38:39.584] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:39.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:60:0) after 1m0s
[16:39:39.640] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:60:0)
[16:39:39.640] Timer tick, asking Buildbucket for the build status
[16:39:39.720] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:39.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:61:0) after 1m0s
[16:40:39.819] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:61:0)
[16:40:39.819] Timer tick, asking Buildbucket for the build status
[16:40:39.915] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:39.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:62:0) after 1m0s
[16:41:39.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:62:0)
[16:41:39.948] Timer tick, asking Buildbucket for the build status
[16:41:40.274] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:40.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:63:0) after 1m0s
[16:42:40.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:63:0)
[16:42:40.360] Timer tick, asking Buildbucket for the build status
[16:42:40.504] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:40.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:64:0) after 1m0s
[16:43:40.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:64:0)
[16:43:40.517] Timer tick, asking Buildbucket for the build status
[16:43:40.638] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:40.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:65:0) after 1m0s
[16:44:40.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:65:0)
[16:44:40.745] Timer tick, asking Buildbucket for the build status
[16:44:40.807] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:40.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:66:0) after 1m0s
[16:45:40.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:66:0)
[16:45:40.851] Timer tick, asking Buildbucket for the build status
[16:45:41.090] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:41.090] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:67:0) after 1m0s
[16:46:41.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:67:0)
[16:46:41.140] Timer tick, asking Buildbucket for the build status
[16:46:41.222] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:41.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:68:0) after 1m0s
[16:47:41.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:68:0)
[16:47:41.317] Timer tick, asking Buildbucket for the build status
[16:47:41.434] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:41.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:69:0) after 1m0s
[16:48:41.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:69:0)
[16:48:41.456] Timer tick, asking Buildbucket for the build status
[16:48:41.536] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:41.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:70:0) after 1m0s
[16:49:41.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:70:0)
[16:49:41.538] Timer tick, asking Buildbucket for the build status
[16:49:41.903] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:41.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:71:0) after 1m0s
[16:50:42.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:71:0)
[16:50:42.033] Timer tick, asking Buildbucket for the build status
[16:50:42.112] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:42.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:72:0) after 1m0s
[16:51:42.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:72:0)
[16:51:42.110] Timer tick, asking Buildbucket for the build status
[16:51:42.849] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:42.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:73:0) after 1m0s
[16:52:42.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:73:0)
[16:52:42.867] Timer tick, asking Buildbucket for the build status
[16:52:43.736] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:43.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:74:0) after 1m0s
[16:53:43.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:74:0)
[16:53:43.826] Timer tick, asking Buildbucket for the build status
[16:53:43.906] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:43.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:75:0) after 1m0s
[16:54:43.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:75:0)
[16:54:43.959] Timer tick, asking Buildbucket for the build status
[16:54:44.065] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:44.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:76:0) after 1m0s
[16:55:44.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:76:0)
[16:55:44.133] Timer tick, asking Buildbucket for the build status
[16:55:44.243] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:44.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:77:0) after 1m0s
[16:56:44.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:77:0)
[16:56:44.303] Timer tick, asking Buildbucket for the build status
[16:56:44.421] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:44.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:78:0) after 1m0s
[16:57:44.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:78:0)
[16:57:44.427] Timer tick, asking Buildbucket for the build status
[16:57:44.531] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:44.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:79:0) after 1m0s
[16:58:44.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:79:0)
[16:58:44.553] Timer tick, asking Buildbucket for the build status
[16:58:44.724] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:44.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:80:0) after 1m0s
[16:59:44.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:80:0)
[16:59:44.757] Timer tick, asking Buildbucket for the build status
[16:59:45.333] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:45.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:81:0) after 1m0s
[17:00:45.443] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:81:0)
[17:00:45.443] Timer tick, asking Buildbucket for the build status
[17:00:45.579] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:45.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:82:0) after 1m0s
[17:01:45.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:82:0)
[17:01:45.661] Timer tick, asking Buildbucket for the build status
[17:01:45.743] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:45.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:83:0) after 1m0s
[17:02:45.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:83:0)
[17:02:45.791] Timer tick, asking Buildbucket for the build status
[17:02:47.091] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:47.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:84:0) after 1m0s
[17:03:47.141] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:84:0)
[17:03:47.141] Timer tick, asking Buildbucket for the build status
[17:03:48.227] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:48.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:85:0) after 1m0s
[17:04:48.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:85:0)
[17:04:48.272] Timer tick, asking Buildbucket for the build status
[17:04:48.449] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:48.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:86:0) after 1m0s
[17:05:48.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:86:0)
[17:05:48.482] Timer tick, asking Buildbucket for the build status
[17:05:48.568] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:48.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:87:0) after 1m0s
[17:06:48.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:87:0)
[17:06:48.638] Timer tick, asking Buildbucket for the build status
[17:06:48.722] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:48.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:88:0) after 1m0s
[17:07:48.835] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:88:0)
[17:07:48.835] Timer tick, asking Buildbucket for the build status
[17:07:48.930] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:48.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:89:0) after 1m0s
[17:08:49.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:89:0)
[17:08:49.012] Timer tick, asking Buildbucket for the build status
[17:08:49.203] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:49.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:90:0) after 1m0s
[17:09:49.225] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:90:0)
[17:09:49.226] Timer tick, asking Buildbucket for the build status
[17:09:49.333] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:49.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:91:0) after 1m0s
[17:10:49.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:91:0)
[17:10:49.401] Timer tick, asking Buildbucket for the build status
[17:10:49.496] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:49.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:92:0) after 1m0s
[17:11:49.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:92:0)
[17:11:49.588] Timer tick, asking Buildbucket for the build status
[17:11:49.674] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:49.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:93:0) after 1m0s
[17:12:49.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:93:0)
[17:12:49.653] Timer tick, asking Buildbucket for the build status
[17:12:49.731] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:49.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:94:0) after 1m0s
[17:13:49.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:94:0)
[17:13:49.749] Timer tick, asking Buildbucket for the build status
[17:13:49.855] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:49.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:95:0) after 1m0s
[17:14:49.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:95:0)
[17:14:49.918] Timer tick, asking Buildbucket for the build status
[17:14:50.239] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:50.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:96:0) after 1m0s
[17:15:50.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:96:0)
[17:15:50.308] Timer tick, asking Buildbucket for the build status
[17:15:50.389] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:50.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:97:0) after 1m0s
[17:16:50.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:97:0)
[17:16:50.449] Timer tick, asking Buildbucket for the build status
[17:16:52.380] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:52.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:98:0) after 1m0s
[17:17:52.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:98:0)
[17:17:52.403] Timer tick, asking Buildbucket for the build status
[17:17:52.486] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:52.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:99:0) after 1m0s
[17:18:52.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:99:0)
[17:18:52.508] Timer tick, asking Buildbucket for the build status
[17:18:52.750] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:52.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:100:0) after 1m0s
[17:19:52.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:100:0)
[17:19:52.774] Timer tick, asking Buildbucket for the build status
[17:19:52.961] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:52.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:101:0) after 1m0s
[17:20:52.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:101:0)
[17:20:52.974] Timer tick, asking Buildbucket for the build status
[17:20:53.126] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:53.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:102:0) after 1m0s
[17:21:53.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:102:0)
[17:21:53.212] Timer tick, asking Buildbucket for the build status
[17:21:53.382] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:53.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:103:0) after 1m0s
[17:22:53.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:103:0)
[17:22:53.437] Timer tick, asking Buildbucket for the build status
[17:22:53.874] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:53.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:104:0) after 1m0s
[17:23:53.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:104:0)
[17:23:53.929] Timer tick, asking Buildbucket for the build status
[17:23:54.113] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:54.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:105:0) after 1m0s
[17:24:54.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:105:0)
[17:24:54.146] Timer tick, asking Buildbucket for the build status
[17:24:54.250] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:54.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:106:0) after 1m0s
[17:25:54.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:106:0)
[17:25:54.277] Timer tick, asking Buildbucket for the build status
[17:25:54.386] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:54.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:107:0) after 1m0s
[17:26:54.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:107:0)
[17:26:54.396] Timer tick, asking Buildbucket for the build status
[17:26:54.696] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:54.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:108:0) after 1m0s
[17:27:54.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:108:0)
[17:27:54.722] Timer tick, asking Buildbucket for the build status
[17:27:54.841] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:54.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:109:0) after 1m0s
[17:28:54.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:109:0)
[17:28:54.964] Timer tick, asking Buildbucket for the build status
[17:28:56.182] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:56.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:110:0) after 1m0s
[17:29:56.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:110:0)
[17:29:56.296] Timer tick, asking Buildbucket for the build status
[17:29:56.824] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:56.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:111:0) after 1m0s
[17:30:56.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:111:0)
[17:30:56.979] Timer tick, asking Buildbucket for the build status
[17:30:57.130] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:57.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:112:0) after 1m0s
[17:31:57.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:112:0)
[17:31:57.156] Timer tick, asking Buildbucket for the build status
[17:31:57.780] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:57.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:113:0) after 1m0s
[17:32:57.871] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:113:0)
[17:32:57.871] Timer tick, asking Buildbucket for the build status
[17:32:57.995] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:57.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:114:0) after 1m0s
[17:33:58.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:114:0)
[17:33:58.027] Timer tick, asking Buildbucket for the build status
[17:33:58.496] Build 8854311365119232736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:58.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019741427036829136:115:0) after 1m0s
[17:34:11.794] Received PubSub notification, asking Buildbucket for the build status
[17:34:11.904] Build 8854311365119232736: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:34:11.904] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614274448660817",
"created_by": "project:chromium",
"created_ts": "1614267683829119",
"id": "8854311365119232736",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3b86e7e18c213aabb683f44e0215b3e737f5e771\"}}",
"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\": \"3b86e7e18c213aabb683f44e0215b3e737f5e771\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-3-xpf4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17529, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"9b1c569b14e90765cdd7c07e449400e88f1d6c45\", \"got_dawn_revision\": \"5eb496b863da5b40dc5c9a713c8ca1e413811f35\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"3b86e7e18c213aabb683f44e0215b3e737f5e771\", \"got_revision_cp\": \"refs/heads/master@{#857673}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"349abccec8f7ebd1259de11965d58790a76b0ba4\", \"got_v8_revision\": \"bd0108b4c88e0d6f2350cb79b5f363fbd02f3eb7\", \"got_v8_revision_cp\": \"refs/heads/9.0.257@{#1}\", \"got_webrtc_revision\": \"cd5127b11e04b7f49031b2412625c36e4a86c3da\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33340}\", \"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\": \"3b86e7e18c213aabb683f44e0215b3e737f5e771\", \"swarm_hashes_refs/heads/master(at){#857673}_without_patch\": {\"absl_hardening_tests\": \"684a3f08e1a36cecd08d97097e2dacfb83becee4\", \"accessibility_unittests\": \"8f6fee0edcb238173b8d212d5ab538a9da84884d\", \"angle_unittests\": \"9f7b2fa7554af531d0d5b27d4fc8c550103f6723\", \"app_shell_unittests\": \"d7b69fb2b5fa0ffcee4540bbec6be90df77cef4f\", \"aura_unittests\": \"9a8a7177cb60ea6cea1d16d8083bb8c0a00fad13\", \"base_unittests\": \"f59968c5579ab60741bb6c42c1649f689e7c33b9\", \"base_util_unittests\": \"d0c493cd905025a2fc56166bc0f32c13416286a9\", \"blink_common_unittests\": \"475f1563d025ca09ac2e97b41d0e09697c014907\", \"blink_fuzzer_unittests\": \"546de223478cf07b7014f78b5dc99b20c917f033\", \"blink_heap_unittests\": \"90c963d888650de3a5d965dd773c0dd797241175\", \"blink_platform_unittests\": \"3e69e75c39b30d656166149f3189b2bde1de3522\", \"blink_unittests\": \"2d53cd30b1d63e4533ef539ed671ab1e4be62485\", \"boringssl_crypto_tests\": \"008e4b792f2876d5be6f2c6d04915150e553939b\", \"boringssl_ssl_tests\": \"16cec33a1f19bd538a6d1255724d06ecfa3bc04a\", \"browser_switcher_bho_unittests\": \"d9c609656f5f56c340e23ffcbcb0c2418c574d24\", \"browser_tests\": \"6caf0ad339730caeda600d579cb00fd9b1bb7023\", \"capture_unittests\": \"bc9e2356b715cf682b58fd07dc7d0f8fbbb150a9\", \"cast_unittests\": \"7709e401226e3128ddfaf7dfe4fafd2ea7d64329\", \"cc_unittests\": \"2166d3e2146fd3b1967a82cb14d938867df4347c\", \"chrome_app_unittests\": \"b4d6a640a8ad70d598ae5e0ec6ec1f235039484c\", \"chrome_cleaner_unittests\": \"4f37d491ed7721c1e69443f719396cd7a84c5f66\", \"chrome_elf_unittests\": \"c575e89606e3d7db0bd432cbf41d6dccc941d216\", \"chromedriver_unittests\": \"c39a435f6bb5d6501f6c18dd6afb64ad6c45c17c\", \"color_unittests\": \"be4501a9dec88e7c1cfaeb10e533210bb181a00b\", \"components_browsertests\": \"339db1592f2bf1007442e071eb1f50f73ffb1b0e\", \"components_unittests\": \"f3d450834caa2b3db2ac14afa8d4339f73388953\", \"compositor_unittests\": \"09a20497a20261d0aae0dd0d5e665342337cc094\", \"content_browsertests\": \"5f44f726b8bfd61a7ca223c8261c9468a88d78f4\", \"content_unittests\": \"9daf52404e7be159a01fbde19a4b2c17f20d9f83\", \"courgette_unittests\": \"54eb956589bf0c068f12ccb8363a4355cb1b269c\", \"crashpad_tests\": \"55c9cc66a5c2afff97976c65b611badf8b23951c\", \"cronet_tests\": \"072ed6c71150c730330380fefd1b5d4b336faf8e\", \"cronet_unittests\": \"f35d7c785d28a2593b4d723ead6e659638ccb6e0\", \"crypto_unittests\": \"5737d145a534d8bfb4795f04cbeda9c5b7789608\", \"delayloads_unittests\": \"48584d7d970047d55ed32e262731b6efa54caaee\", \"device_unittests\": \"46068ff37d9b4d2637307541422099c3b474f1b8\", \"display_unittests\": \"03005308e85754d5465d53b3cd2843bc7342a696\", \"elevation_service_unittests\": \"4b6556dbba82e8ae8f3ad8603ef461e5e8b24749\", \"events_unittests\": \"5ce53689ae2cc6bf75c885bc356caece810c370e\", \"extensions_browsertests\": \"14f79bef6c731a6d8c99b9c71b06643c8d21e45e\", \"extensions_unittests\": \"834581d9d02cf5ff7e4e277c3dd656361c71b065\", \"filesystem_service_unittests\": \"c4e5acf740928a185f743d45e709393015f4b80d\", \"gcm_unit_tests\": \"bf7b28e056606cdf5927b600295e657f99b5ec81\", \"gcp_unittests\": \"8b334f4b0ba78b065f2ae58373a34dd6bd9ea8ce\", \"gfx_unittests\": \"0ee1a418fbe25e9aaea82bc3236757564bf016f6\", \"gin_unittests\": \"d6d54f578cf5bbbbcdf0fb3b517a8c7de014aa40\", \"google_apis_unittests\": \"34a910e2b7fa974573c059e190a06eade6dd8c10\", \"gpu_unittests\": \"92da97a8209c8b487ea68f4393e6bd3609eff9ec\", \"gwp_asan_unittests\": \"b4da9c4ad0f470f1931b13b2f376d7b0e30416b3\", \"headless_browsertests\": \"afcbe8293c963967fd900a4b6d893497e17c8497\", \"headless_unittests\": \"2eb39a4cc37d565de92cf3d30bc7c5ca73b6d59c\", \"install_static_unittests\": \"6a6f4da78a6e70cf0d5c7e28724e284adfd1063d\", \"installer_util_unittests\": \"58cc6eeda4480e444c32124ba3013f40e75911dd\", \"interactive_ui_tests\": \"e6c515165a2b6790e7bc863422e283cbd92f14cd\", \"ipc_tests\": \"8a7798c74c9d92484addb5b736f334882f8f660e\", \"jingle_unittests\": \"81d36fb37a1f4553d7b6aa3a0f917dfade958c9f\", \"latency_unittests\": \"c238eb499d2ffb2a6cb36dce199c861c43bf77e7\", \"libjingle_xmpp_unittests\": \"b5912b03b640912fb008b6785908a4038c6dae08\", \"liburlpattern_unittests\": \"b78b4757049d7e35dbdb4aafd6f246e1ce9ac4f1\", \"media_blink_unittests\": \"97c527fe0db591ec2b7f6435266e65bfa2251d38\", \"media_unittests\": \"4b7928e6fb183603f9e3c8106a184a8e5efea4b9\", \"message_center_unittests\": \"9ddc1e31d9843d2d19b4003fe5e99bfc0dddf82e\", \"midi_unittests\": \"850656c0bd7969ca42b099204599df7f6e583c67\", \"mojo_core_unittests\": \"a94fa9a9782fe48d29e1ffdb8e7494c48fb35e08\", \"mojo_unittests\": \"2f76b2370ac95d99ad5cb6b16dd1178c510aa825\", \"nacl_loader_unittests\": \"485683889213f32f57cf70fae990903247da7b0d\", \"native_theme_unittests\": \"a62ec11289ae2a0e6fcbb54f8fc0f282d46c80e4\", \"net_unittests\": \"71126748f1b3603e5dadec848b3f052308f29497\", \"notification_helper_unittests\": \"104c4a1dbaa3136727bcb5b3100cf2a8f820004b\", \"pdf_unittests\": \"afa261399f2de8e934472df05fb884744295631d\", \"perfetto_unittests\": \"2b7069f812585084152e12221820fec3e79dfb50\", \"ppapi_unittests\": \"1ee92b8ecfe7bcb011ad643b345f9346aa60048c\", \"printing_unittests\": \"9156989b986e01b5fdba01fe57a732c6a2fe6bab\", \"remoting_unittests\": \"7157f3a6a532d68c6b6abd1b04fb31f8f9c9147d\", \"sbox_integration_tests\": \"9f50f5dd3bb98fafc65a6772f2a7203a668bcf01\", \"sbox_unittests\": \"36c5c6f037d43a908ab686a9202c6a4e0b3d54e7\", \"sbox_validation_tests\": \"c94fe5456aec715203a5f105ad82880a325df1e3\", \"service_manager_unittests\": \"0444e9b882e64e4af05ab3892b11cd54cef6b83b\", \"services_unittests\": \"14934306d54523e85c475a3a198d3e0eda4cf95d\", \"setup_unittests\": \"754898d1e7f6f347272d7e5e4f39f1be7b911bc5\", \"shell_dialogs_unittests\": \"4fe3a0b54bb2d4812985e21f26ce6cae93429fb3\", \"skia_unittests\": \"2dcd0708b61d7c4b5844542fc4479b4fa40c9e24\", \"snapshot_unittests\": \"30a4fe635acbb07eaa143e56dafc8cb839fa1973\", \"sql_unittests\": \"cc3986588614ead17a3558846c80494c84879774\", \"storage_unittests\": \"27f79de2c56d663d4001948e9f507fc60b47c30d\", \"sync_integration_tests\": \"95a3a847ff42116b06c1a18383af3ac0be006553\", \"traffic_annotation_auditor_unittests\": \"7fe9d574fda8b599c686977186a4ae2b483a972d\", \"ui_base_unittests\": \"2e19c580b11325d9f5064fc0e3e61b0cf429a4d2\", \"ui_touch_selection_unittests\": \"d15343f86f457ee572ca4e779ee31f485ea27388\", \"unit_tests\": \"4fb9049e8347a7c57c316d76bc8bcb27b7ffa9b7\", \"updater_tests\": \"96d50446b8ace447eabd34c8454d8d5093b7b113\", \"url_unittests\": \"5d26c3b3bc6fb57a49543c4537a113304318a8c8\", \"views_unittests\": \"71de5148dbcadd00467a8797ce69568473358b11\", \"viz_unittests\": \"3745dd6f9332846b0f6ad1c0409341e0973b2fe4\", \"vr_common_unittests\": \"7352ecf66532480fa831465d6bab30e07e2e2745\", \"vr_pixeltests\": \"d3db5e6ce7e2ba12c306f37bd58af401f56910e0\", \"weblayer_browsertests\": \"8b86848f1d7c95b299dbf43ba55b99e96de655c0\", \"weblayer_unittests\": \"d58e319feaf13e83877b9e4b9ffa8674668235dd\", \"wm_unittests\": \"5591ddf28bb4b93fdecec547b1abe72084915bba\", \"wtf_unittests\": \"ff926f9e8bccd5e5dca5299f371892bcccc25781\", \"zlib_unittests\": \"f277bc8534a3901876d1525e81c17a7613a4b3c8\", \"zucchini_unittests\": \"8f069d11c277b2fa737e7f15a72c67f9aaeeedcb\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-3-xpf4\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5712-339372b\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614267913987424",
"status": "COMPLETED",
"status_changed_ts": "1614274449003341",
"tags": [
"build_address:luci.chromium.ci/win-asan/17529",
"builder:win-asan",
"buildset:commit/git/3b86e7e18c213aabb683f44e0215b3e737f5e771",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3b86e7e18c213aabb683f44e0215b3e737f5e771",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019741427036829136",
"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/8854311365119232736/+/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:51f2ab9e6bdf0f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614274449025097",
"url": "https://ci.chromium.org/b/8854311365119232736",
"utcnow_ts": "1614274451888865"
}
}
[17:34:11.904] Invocation finished in 1h52m49.287007367s with status SUCCEEDED