[21:39:55.185] New invocation is queued and will start shortly
[21:39:56.814] Starting the invocation (attempt 1)
[21:39:56.862] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:39:56.862] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:39:56.862] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023523942080463504",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0b64e8129bc54c25bcf09dcbbafcb66f239eadf7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023523942080463504",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/0b64e8129bc54c25bcf09dcbbafcb66f239eadf7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0b64e8129bc54c25bcf09dcbbafcb66f239eadf7",
"gitiles_ref:refs/heads/master"
]
}
[21:39:57.609] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610660396945616",
"id": "8858093879570769536",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0b64e8129bc54c25bcf09dcbbafcb66f239eadf7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610660397304977",
"tags": [
"build_address:luci.chromium.ci/win-asan/16824",
"builder:win-asan",
"buildset:commit/git/0b64e8129bc54c25bcf09dcbbafcb66f239eadf7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0b64e8129bc54c25bcf09dcbbafcb66f239eadf7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023523942080463504",
"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/8858093879570769536/+/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": "1610660397305105",
"url": "https://ci.chromium.org/b/8858093879570769536",
"utcnow_ts": "1610660397592903"
}
}
[21:39:57.609] Task URL: https://ci.chromium.org/b/8858093879570769536
[21:39:57.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:2:0) after 1m0s
[21:40:01.489] Received PubSub notification, asking Buildbucket for the build status
[21:40:01.817] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:57.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:2:0)
[21:40:57.625] Timer tick, asking Buildbucket for the build status
[21:40:57.705] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:57.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:4:0) after 1m0s
[21:41:57.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:4:0)
[21:41:57.746] Timer tick, asking Buildbucket for the build status
[21:41:58.142] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:58.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:5:0) after 1m0s
[21:42:58.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:5:0)
[21:42:58.164] Timer tick, asking Buildbucket for the build status
[21:42:58.451] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:58.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:6:0) after 1m0s
[21:43:58.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:6:0)
[21:43:58.481] Timer tick, asking Buildbucket for the build status
[21:43:58.693] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:58.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:7:0) after 1m0s
[21:44:58.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:7:0)
[21:44:58.720] Timer tick, asking Buildbucket for the build status
[21:44:59.128] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:59.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:8:0) after 1m0s
[21:45:59.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:8:0)
[21:45:59.157] Timer tick, asking Buildbucket for the build status
[21:45:59.253] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:59.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:9:0) after 1m0s
[21:46:59.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:9:0)
[21:46:59.253] Timer tick, asking Buildbucket for the build status
[21:46:59.321] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:59.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:10:0) after 1m0s
[21:47:59.344] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:10:0)
[21:47:59.344] Timer tick, asking Buildbucket for the build status
[21:47:59.412] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:59.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:11:0) after 1m0s
[21:48:59.412] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:11:0)
[21:48:59.412] Timer tick, asking Buildbucket for the build status
[21:48:59.482] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:59.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:12:0) after 1m0s
[21:49:59.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:12:0)
[21:49:59.529] Timer tick, asking Buildbucket for the build status
[21:50:00.166] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:00.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:13:0) after 1m0s
[21:51:00.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:13:0)
[21:51:00.217] Timer tick, asking Buildbucket for the build status
[21:51:00.283] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:00.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:14:0) after 1m0s
[21:52:00.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:14:0)
[21:52:00.357] Timer tick, asking Buildbucket for the build status
[21:52:00.530] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:00.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:15:0) after 1m0s
[21:53:00.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:15:0)
[21:53:00.518] Timer tick, asking Buildbucket for the build status
[21:53:00.727] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:00.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:16:0) after 1m0s
[21:54:01.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:16:0)
[21:54:01.117] Timer tick, asking Buildbucket for the build status
[21:54:01.183] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:01.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:17:0) after 1m0s
[21:55:01.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:17:0)
[21:55:01.561] Timer tick, asking Buildbucket for the build status
[21:55:02.293] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:02.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:18:0) after 1m0s
[21:56:02.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:18:0)
[21:56:02.280] Timer tick, asking Buildbucket for the build status
[21:56:02.465] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:02.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:19:0) after 1m0s
[21:57:02.500] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:19:0)
[21:57:02.500] Timer tick, asking Buildbucket for the build status
[21:57:02.566] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:02.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:20:0) after 1m0s
[21:58:02.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:20:0)
[21:58:02.627] Timer tick, asking Buildbucket for the build status
[21:58:03.135] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:03.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:21:0) after 1m0s
[21:59:03.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:21:0)
[21:59:03.169] Timer tick, asking Buildbucket for the build status
[21:59:03.454] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:03.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:22:0) after 1m0s
[22:00:03.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:22:0)
[22:00:03.496] Timer tick, asking Buildbucket for the build status
[22:00:03.883] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:03.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:23:0) after 1m0s
[22:01:03.935] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:23:0)
[22:01:03.935] Timer tick, asking Buildbucket for the build status
[22:01:04.341] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:04.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:24:0) after 1m0s
[22:02:04.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:24:0)
[22:02:04.426] Timer tick, asking Buildbucket for the build status
[22:02:05.185] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:05.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:25:0) after 1m0s
[22:03:05.286] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:25:0)
[22:03:05.286] Timer tick, asking Buildbucket for the build status
[22:03:05.760] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:05.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:26:0) after 1m0s
[22:04:05.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:26:0)
[22:04:05.826] Timer tick, asking Buildbucket for the build status
[22:04:06.454] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:06.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:27:0) after 1m0s
[22:05:06.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:27:0)
[22:05:06.535] Timer tick, asking Buildbucket for the build status
[22:05:06.646] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:06.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:28:0) after 1m0s
[22:06:06.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:28:0)
[22:06:06.692] Timer tick, asking Buildbucket for the build status
[22:06:06.812] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:06.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:29:0) after 1m0s
[22:07:06.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:29:0)
[22:07:06.926] Timer tick, asking Buildbucket for the build status
[22:07:07.175] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:07.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:30:0) after 1m0s
[22:08:07.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:30:0)
[22:08:07.245] Timer tick, asking Buildbucket for the build status
[22:08:07.413] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:07.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:31:0) after 1m0s
[22:09:07.431] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:31:0)
[22:09:07.431] Timer tick, asking Buildbucket for the build status
[22:09:07.875] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:07.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:32:0) after 1m0s
[22:10:08.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:32:0)
[22:10:08.694] Timer tick, asking Buildbucket for the build status
[22:10:09.042] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:09.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:33:0) after 1m0s
[22:11:09.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:33:0)
[22:11:09.403] Timer tick, asking Buildbucket for the build status
[22:11:09.952] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:09.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:34:0) after 1m0s
[22:12:10.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:34:0)
[22:12:10.157] Timer tick, asking Buildbucket for the build status
[22:12:11.029] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:11.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:35:0) after 1m0s
[22:13:11.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:35:0)
[22:13:11.133] Timer tick, asking Buildbucket for the build status
[22:13:11.564] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:11.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:36:0) after 1m0s
[22:14:11.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:36:0)
[22:14:11.689] Timer tick, asking Buildbucket for the build status
[22:14:11.822] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:11.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:37:0) after 1m0s
[22:15:11.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:37:0)
[22:15:11.917] Timer tick, asking Buildbucket for the build status
[22:15:12.028] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:12.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:38:0) after 1m0s
[22:16:12.021] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:38:0)
[22:16:12.021] Timer tick, asking Buildbucket for the build status
[22:16:12.227] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:12.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:39:0) after 1m0s
[22:17:12.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:39:0)
[22:17:12.252] Timer tick, asking Buildbucket for the build status
[22:17:12.503] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:12.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:40:0) after 1m0s
[22:18:12.596] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:40:0)
[22:18:12.596] Timer tick, asking Buildbucket for the build status
[22:18:12.674] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:12.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:41:0) after 1m0s
[22:19:12.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:41:0)
[22:19:12.708] Timer tick, asking Buildbucket for the build status
[22:19:12.813] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:12.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:42:0) after 1m0s
[22:20:12.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:42:0)
[22:20:12.895] Timer tick, asking Buildbucket for the build status
[22:20:13.183] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:13.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:43:0) after 1m0s
[22:21:13.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:43:0)
[22:21:13.241] Timer tick, asking Buildbucket for the build status
[22:21:13.333] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:13.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:44:0) after 1m0s
[22:22:13.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:44:0)
[22:22:13.355] Timer tick, asking Buildbucket for the build status
[22:22:13.443] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:13.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:45:0) after 1m0s
[22:23:13.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:45:0)
[22:23:13.533] Timer tick, asking Buildbucket for the build status
[22:23:13.675] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:13.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:46:0) after 1m0s
[22:24:13.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:46:0)
[22:24:13.711] Timer tick, asking Buildbucket for the build status
[22:24:14.315] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:14.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:47:0) after 1m0s
[22:25:14.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:47:0)
[22:25:14.364] Timer tick, asking Buildbucket for the build status
[22:25:14.758] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:14.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:48:0) after 1m0s
[22:26:14.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:48:0)
[22:26:14.838] Timer tick, asking Buildbucket for the build status
[22:26:14.960] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:14.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:49:0) after 1m0s
[22:27:15.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:49:0)
[22:27:15.004] Timer tick, asking Buildbucket for the build status
[22:27:15.156] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:15.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:50:0) after 1m0s
[22:28:15.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:50:0)
[22:28:15.164] Timer tick, asking Buildbucket for the build status
[22:28:15.954] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:15.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:51:0) after 1m0s
[22:29:15.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:51:0)
[22:29:15.975] Timer tick, asking Buildbucket for the build status
[22:29:16.111] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:16.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:52:0) after 1m0s
[22:30:16.141] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:52:0)
[22:30:16.141] Timer tick, asking Buildbucket for the build status
[22:30:16.325] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:16.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:53:0) after 1m0s
[22:31:16.350] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:53:0)
[22:31:16.350] Timer tick, asking Buildbucket for the build status
[22:31:16.729] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:16.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:54:0) after 1m0s
[22:32:16.821] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:54:0)
[22:32:16.821] Timer tick, asking Buildbucket for the build status
[22:32:16.887] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:16.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:55:0) after 1m0s
[22:33:16.970] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:55:0)
[22:33:16.970] Timer tick, asking Buildbucket for the build status
[22:33:17.133] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:17.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:56:0) after 1m0s
[22:34:17.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:56:0)
[22:34:17.198] Timer tick, asking Buildbucket for the build status
[22:34:17.271] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:17.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:57:0) after 1m0s
[22:35:17.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:57:0)
[22:35:17.291] Timer tick, asking Buildbucket for the build status
[22:35:17.446] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:17.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:58:0) after 1m0s
[22:36:17.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:58:0)
[22:36:17.573] Timer tick, asking Buildbucket for the build status
[22:36:17.662] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:17.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:59:0) after 1m0s
[22:37:17.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:59:0)
[22:37:17.634] Timer tick, asking Buildbucket for the build status
[22:37:17.710] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:17.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:60:0) after 1m0s
[22:38:17.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:60:0)
[22:38:17.749] Timer tick, asking Buildbucket for the build status
[22:38:17.992] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:17.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:61:0) after 1m0s
[22:39:18.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:61:0)
[22:39:18.051] Timer tick, asking Buildbucket for the build status
[22:39:18.636] Build 8858093879570769536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:18.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023523942080463504:62:0) after 1m0s
[22:39:28.811] Received PubSub notification, asking Buildbucket for the build status
[22:39:29.167] Build 8858093879570769536: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:39:29.168] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610663966871560",
"created_by": "project:chromium",
"created_ts": "1610660396945616",
"id": "8858093879570769536",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0b64e8129bc54c25bcf09dcbbafcb66f239eadf7\"}}",
"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\": \"0b64e8129bc54c25bcf09dcbbafcb66f239eadf7\", \"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-nb2x\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16824, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"d392af02b74947659291ea6aee840b09845df9df\", \"got_dawn_revision\": \"d2fb128052aec0a98a696c841ab6232c7323423d\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"0b64e8129bc54c25bcf09dcbbafcb66f239eadf7\", \"got_revision_cp\": \"refs/heads/master@{#843746}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"5255c465bbbab020b2fa98f818a705e764865869\", \"got_v8_revision\": \"16b9bbbd581c25391981aa03180b76aa60463a3e\", \"got_v8_revision_cp\": \"refs/heads/8.9.255@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"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\": \"0b64e8129bc54c25bcf09dcbbafcb66f239eadf7\", \"swarm_hashes_refs/heads/master(at){#843746}_without_patch\": {\"absl_hardening_tests\": \"073517b85db3cd05c9370dae79bdeb3abfcfa61c\", \"accessibility_unittests\": \"efcdfa4ba1090d324ffb7d88da146c84b23f06e3\", \"angle_unittests\": \"63d0d5491ab399fd330ddee8dc7ac96df19b1c0c\", \"app_shell_unittests\": \"9067c954a3d4fd7fb7ec1112ca3b7b21a9f3e5b7\", \"aura_unittests\": \"54c6e532764d75b311f5c0540b0fa84db936d12f\", \"base_unittests\": \"0c12a081e6700f91074ea716a0ac731047e77813\", \"base_util_unittests\": \"b27630da9dbc1cc487d857c42b696aca7d269d9b\", \"blink_common_unittests\": \"15a6cb89067fd4f5c8855f8fb61eac25c2baa4be\", \"blink_fuzzer_unittests\": \"cd57350f61f8b168d1469586191b4295f1d74fed\", \"blink_heap_unittests\": \"f0763752c2a3587202c97da8212f8260640b893a\", \"blink_platform_unittests\": \"93c72411bd5cb8a545d5e67bc1840e292ba5f6f0\", \"blink_unittests\": \"06c09abec766dbd5e6f754913f223a06aec7c859\", \"boringssl_crypto_tests\": \"03d836736035b58d7fe9493026ca217d652c7050\", \"boringssl_ssl_tests\": \"f72a04ab091df1d2e70373b03f6382f175bab7da\", \"browser_switcher_bho_unittests\": \"5a74312bfcf0d8def288f59add30b98931d1958b\", \"browser_tests\": \"72a60a7d88f7f08e36e8be0b96b5de3ada2e9fff\", \"capture_unittests\": \"9b43cbb69c7186cc4c6e49416a6987f50c78353e\", \"cast_unittests\": \"a4746f711ff71f9015922e0ed335d8e60e1b26c6\", \"cc_unittests\": \"06f1694487fe68947cba0e80d04e262c78f17115\", \"chrome_app_unittests\": \"97a9570f62dd426fb653ffaf4945cf6b26896a8b\", \"chrome_cleaner_unittests\": \"db4aadd5a588e8b0eb79fb458f02dab6a99a43c7\", \"chrome_elf_unittests\": \"76fc04f3ab72b176f25ec263d3cb359536e3727a\", \"chromedriver_unittests\": \"0a8b2dc98741aad84be7e921b6c407cdac158d52\", \"components_browsertests\": \"d8e968f02572e49da1bd4cf028529852cb4968a4\", \"components_unittests\": \"8a870a0f7fe1e68b8158198f5ac541d278cb5bd8\", \"compositor_unittests\": \"614c6e722620a95a818c7f2cb46edbaac8e7d202\", \"content_browsertests\": \"5887e31b4d5bb923502c4ca40da7ccc468d2751d\", \"content_unittests\": \"5fe3213f7f70722f400acdc0330c4040b63110fb\", \"courgette_unittests\": \"2ff7aa335f02ef4b50b557329ef2e6dbc98034e5\", \"crashpad_tests\": \"98f307f83dc8b9e9412884060be15bb234ad3e61\", \"cronet_tests\": \"d8819b52a0c1b8e490339acb38099ecc80a9dc43\", \"cronet_unittests\": \"6433ce0c183278592ef58ac4d6a2472688e2df47\", \"crypto_unittests\": \"3495659946eee19703ec5ca8699d16b7adc0f0b7\", \"delayloads_unittests\": \"7a9cfa2c59aa8b3878c940a492303f5eb59cc628\", \"device_unittests\": \"5af5bce1a78bc05a237baa5e117fe57d92f11f4b\", \"display_unittests\": \"eeb5c07b35d15ad20aefde794dbca51f813da312\", \"elevation_service_unittests\": \"a6c5ea1c2f76000a4f34beb6efa1c7b033bd2035\", \"events_unittests\": \"7664e55691c268ce6cc1898febd6d623b3b46769\", \"extensions_browsertests\": \"d39b0c4594204a8a781b12e673a95e4744281be1\", \"extensions_unittests\": \"70b956f83c1a22c81e7ab41d4c91613a79756de9\", \"filesystem_service_unittests\": \"a24246f0212a599cfafe7641cee6cc75718bd10e\", \"gcm_unit_tests\": \"b9da6627aa08d763354401aec33666085d43c24e\", \"gcp_unittests\": \"92c42ea2e03f6d63ac11405468ada98a3dece21b\", \"gfx_unittests\": \"2c30181e4653c899e8688aac8dfc25cdefcf7002\", \"gin_unittests\": \"1688175e72689e35547185b20b9de152c1efc832\", \"google_apis_unittests\": \"e4890d2bb85e2022733ba6eeb4d6900f5b5d08fa\", \"gpu_unittests\": \"71893f1637654f1fa83c2a83bc2fecaca000fb33\", \"gwp_asan_unittests\": \"3af9cd39f583e622cf9a38c4c98c7b4b4a030edb\", \"headless_browsertests\": \"3a1402612fa399ed9c57e835f5ce82e55415f2fd\", \"headless_unittests\": \"3e18a628b53009e8fdcaf17d962147f6033da39d\", \"install_static_unittests\": \"568d02e2fd6676871f2d515c7d1254fbe6bc4834\", \"installer_util_unittests\": \"42ea8479b9fc36a6c409311fecee5a1495b6e1f0\", \"interactive_ui_tests\": \"897ba7df904292c9b54016f7b1ca1523f6cfa5be\", \"ipc_tests\": \"3e91b2c71b80ab73354731677211140a73f0bd89\", \"jingle_unittests\": \"084873bfe7c6e51672155d587d8fd7614ca39198\", \"latency_unittests\": \"761af2d5d2712b3d2cc5dbae4544b80e5bf975cc\", \"libjingle_xmpp_unittests\": \"94f21a7adf1ecf79f557b755137a687f03b0bc92\", \"liburlpattern_unittests\": \"8faee5a6e41313dc1ce1ff2bd9bb83c611b7fd54\", \"media_blink_unittests\": \"db7b5d33fc75bdda685d822fea0be06a97c2e5e4\", \"media_unittests\": \"4a44c51cd281ae1f3461da6e4c2eca0ea3d759cb\", \"message_center_unittests\": \"0faf1a801ed22f7ba5b4e493a1471cbbdb356ceb\", \"midi_unittests\": \"085b9da99f36aa797850ed0b6b81ce992b308e2e\", \"mojo_core_unittests\": \"d4957bf4b5b3c82073cd66da2f9ba0d100a38e83\", \"mojo_unittests\": \"09bf28d59ba1a0f17768ab7334a0d05d1278a26e\", \"nacl_loader_unittests\": \"4965ea6f84aa43c26d72b5552e09422ed370be30\", \"native_theme_unittests\": \"7f558d70aac4cc901cec4a94ede49716e4bd4d49\", \"net_unittests\": \"e5ec5ab2ea5d884fc6caec0aa5b5efe5ce864440\", \"notification_helper_unittests\": \"546f0d71d25fed444aa9784e60b5670bed0717cd\", \"pdf_unittests\": \"4aabdea77d6a22c58dba56bbd7f238c94ac43d8a\", \"perfetto_unittests\": \"d76db4ef90663086dd2d314f9241aee91e3d3087\", \"ppapi_unittests\": \"a229697b3c3d95a39096e306dae9b0a3ffd28da9\", \"printing_unittests\": \"c9a2e186fdd78a3671427f1e066560934f03ff78\", \"remoting_unittests\": \"31044e7ae9f89f67219f6f8711015de8ec38f7d1\", \"sbox_integration_tests\": \"38e0b476eb5343916ab4499bd65aba4acbad2764\", \"sbox_unittests\": \"632cfd68e143c9331ee933abcfc72d7c1e073704\", \"sbox_validation_tests\": \"baf57a91d9d4bddb85ca06b8dd923b54c811c144\", \"service_manager_unittests\": \"e17e62d70c5638b18b4a6645a1b49fe4f4bfc578\", \"services_unittests\": \"ea7f60c039c006b61481998a9e387df307509688\", \"setup_unittests\": \"d0acccaaae7f171338a32cec885e060f8f6e0ca2\", \"shell_dialogs_unittests\": \"269b45db77d25b4382081320ab645ee3e57013fb\", \"skia_unittests\": \"850529e3939b9416234f4841d84a936976d29e0e\", \"snapshot_unittests\": \"d5b0da97d4382c7e9a441f2bdf0ecb323f681170\", \"sql_unittests\": \"7ae8e30b4111464d9edc0c3f8fae98d648ffa77a\", \"storage_unittests\": \"557ec940283201c21fc9e772568646e118493fd0\", \"sync_integration_tests\": \"056e8dd3961b060fe32dcae5b3ed00a4e9d63c82\", \"traffic_annotation_auditor_unittests\": \"9e5f28bedef2b1ce165dcebf45a0b39520a39cb5\", \"ui_base_unittests\": \"e1bb67089f322f7d09ee66e1187334e79095b85c\", \"ui_touch_selection_unittests\": \"cb549b3c012a71bbe21c9c7e60f476d87b4a49a3\", \"unit_tests\": \"8b1cde05ce76cd2b069063f752d4a9cab134f986\", \"updater_tests\": \"947c0a5607f6c747adc57dcc3cbc96ef253a8303\", \"url_unittests\": \"ffe4e9c5faccae122b25bccce90b70045b2dff8a\", \"views_unittests\": \"00dce2c9cb35503fc61d4bba7504cd3a83c34dcf\", \"viz_unittests\": \"1205bee2fad43437c3549c15ccfe311dc3e2fc88\", \"vr_common_unittests\": \"28ae941635b64d7175194337312d09d33fc03947\", \"vr_pixeltests\": \"1527a3b75d09e25b17bf026d4b9b87cf09d4a241\", \"weblayer_browsertests\": \"02255693443ddd4f029059968af6518f2a8ae82c\", \"weblayer_unittests\": \"3849791c1ad1035609091b2164d153238a52568a\", \"wm_unittests\": \"e8b7b7f43ba243c0e560cf6b58850f87ef7f7b8a\", \"wtf_unittests\": \"bbf0d41084591b7404850d6dd8f3aed516f690b5\", \"zlib_unittests\": \"b612b7885a7de0919d26c991b4b02d4a505fca37\", \"zucchini_unittests\": \"9e7b38ce52639aa7a846c6055b0bec564976de48\"}}, \"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-nb2x\"], \"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\": [\"5661-c6f6250\"], \"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": "1610660399687001",
"status": "COMPLETED",
"status_changed_ts": "1610663967499389",
"tags": [
"build_address:luci.chromium.ci/win-asan/16824",
"builder:win-asan",
"buildset:commit/git/0b64e8129bc54c25bcf09dcbbafcb66f239eadf7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0b64e8129bc54c25bcf09dcbbafcb66f239eadf7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023523942080463504",
"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/8858093879570769536/+/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:511ba8ca8c43f010",
"user_agent:luci-scheduler"
],
"updated_ts": "1610663967519909",
"url": "https://ci.chromium.org/b/8858093879570769536",
"utcnow_ts": "1610663969146219"
}
}
[22:39:29.168] Invocation finished in 59m33.99966631s with status SUCCEEDED