[02:56:30.674] New invocation is queued and will start shortly
[02:56:31.794] Starting the invocation (attempt 1)
[02:56:31.823] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:56:31.823] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:56:31.823] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020604920940308112",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"055cf2142b6a0ac90529d24fd626a463295ebd05\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020604920940308112",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/055cf2142b6a0ac90529d24fd626a463295ebd05",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/055cf2142b6a0ac90529d24fd626a463295ebd05",
"gitiles_ref:refs/heads/master"
]
}
[02:56:32.478] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613444191893255",
"id": "8855174858999399872",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"055cf2142b6a0ac90529d24fd626a463295ebd05\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613444192226100",
"tags": [
"build_address:luci.chromium.ci/win-asan/17367",
"builder:win-asan",
"buildset:commit/git/055cf2142b6a0ac90529d24fd626a463295ebd05",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/055cf2142b6a0ac90529d24fd626a463295ebd05",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020604920940308112",
"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/8855174858999399872/+/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": "1613444192226211",
"url": "https://ci.chromium.org/b/8855174858999399872",
"utcnow_ts": "1613444192469273"
}
}
[02:56:32.478] Task URL: https://ci.chromium.org/b/8855174858999399872
[02:56:32.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:2:0) after 1m0s
[02:56:37.356] Received PubSub notification, asking Buildbucket for the build status
[02:56:37.499] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:32.560] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:2:0)
[02:57:32.560] Timer tick, asking Buildbucket for the build status
[02:57:32.758] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:32.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:4:0) after 1m0s
[02:58:32.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:4:0)
[02:58:32.879] Timer tick, asking Buildbucket for the build status
[02:58:32.965] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:32.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:5:0) after 1m0s
[02:59:33.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:5:0)
[02:59:33.010] Timer tick, asking Buildbucket for the build status
[02:59:33.311] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:33.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:6:0) after 1m0s
[03:00:33.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:6:0)
[03:00:33.375] Timer tick, asking Buildbucket for the build status
[03:00:33.460] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:33.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:7:0) after 1m0s
[03:01:33.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:7:0)
[03:01:33.484] Timer tick, asking Buildbucket for the build status
[03:01:33.597] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:33.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:8:0) after 1m0s
[03:02:33.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:8:0)
[03:02:33.627] Timer tick, asking Buildbucket for the build status
[03:02:33.923] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:33.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:9:0) after 1m0s
[03:03:33.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:9:0)
[03:03:33.918] Timer tick, asking Buildbucket for the build status
[03:03:34.055] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:34.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:10:0) after 1m0s
[03:04:34.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:10:0)
[03:04:34.078] Timer tick, asking Buildbucket for the build status
[03:04:34.150] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:34.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:11:0) after 1m0s
[03:05:34.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:11:0)
[03:05:34.171] Timer tick, asking Buildbucket for the build status
[03:05:34.308] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:34.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:12:0) after 1m0s
[03:06:34.341] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:12:0)
[03:06:34.341] Timer tick, asking Buildbucket for the build status
[03:06:34.832] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:34.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:13:0) after 1m0s
[03:07:34.834] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:13:0)
[03:07:34.834] Timer tick, asking Buildbucket for the build status
[03:07:34.910] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:34.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:14:0) after 1m0s
[03:08:34.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:14:0)
[03:08:34.974] Timer tick, asking Buildbucket for the build status
[03:08:35.091] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:35.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:15:0) after 1m0s
[03:09:35.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:15:0)
[03:09:35.217] Timer tick, asking Buildbucket for the build status
[03:09:35.320] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:35.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:16:0) after 1m0s
[03:10:35.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:16:0)
[03:10:35.377] Timer tick, asking Buildbucket for the build status
[03:10:35.482] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:35.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:17:0) after 1m0s
[03:11:35.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:17:0)
[03:11:35.598] Timer tick, asking Buildbucket for the build status
[03:11:35.813] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:35.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:18:0) after 1m0s
[03:12:35.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:18:0)
[03:12:35.823] Timer tick, asking Buildbucket for the build status
[03:12:36.108] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:36.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:19:0) after 1m0s
[03:13:36.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:19:0)
[03:13:36.165] Timer tick, asking Buildbucket for the build status
[03:13:36.276] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:36.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:20:0) after 1m0s
[03:14:36.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:20:0)
[03:14:36.378] Timer tick, asking Buildbucket for the build status
[03:14:36.462] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:36.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:21:0) after 1m0s
[03:15:36.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:21:0)
[03:15:36.519] Timer tick, asking Buildbucket for the build status
[03:15:36.591] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:36.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:22:0) after 1m0s
[03:16:36.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:22:0)
[03:16:36.634] Timer tick, asking Buildbucket for the build status
[03:16:36.755] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:36.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:23:0) after 1m0s
[03:17:36.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:23:0)
[03:17:36.866] Timer tick, asking Buildbucket for the build status
[03:17:37.064] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:37.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:24:0) after 1m0s
[03:18:37.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:24:0)
[03:18:37.202] Timer tick, asking Buildbucket for the build status
[03:18:37.489] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:37.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:25:0) after 1m0s
[03:19:37.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:25:0)
[03:19:37.545] Timer tick, asking Buildbucket for the build status
[03:19:37.656] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:37.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:26:0) after 1m0s
[03:20:37.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:26:0)
[03:20:37.684] Timer tick, asking Buildbucket for the build status
[03:20:37.775] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:37.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:27:0) after 1m0s
[03:21:37.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:27:0)
[03:21:37.798] Timer tick, asking Buildbucket for the build status
[03:21:37.959] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:37.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:28:0) after 1m0s
[03:22:37.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:28:0)
[03:22:37.974] Timer tick, asking Buildbucket for the build status
[03:22:38.528] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:38.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:29:0) after 1m0s
[03:23:38.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:29:0)
[03:23:38.798] Timer tick, asking Buildbucket for the build status
[03:23:38.895] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:38.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:30:0) after 1m0s
[03:24:38.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:30:0)
[03:24:38.948] Timer tick, asking Buildbucket for the build status
[03:24:39.107] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:39.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:31:0) after 1m0s
[03:25:39.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:31:0)
[03:25:39.144] Timer tick, asking Buildbucket for the build status
[03:25:39.226] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:39.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:32:0) after 1m0s
[03:26:39.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:32:0)
[03:26:39.276] Timer tick, asking Buildbucket for the build status
[03:26:39.467] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:39.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:33:0) after 1m0s
[03:27:39.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:33:0)
[03:27:39.478] Timer tick, asking Buildbucket for the build status
[03:27:39.560] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:39.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:34:0) after 1m0s
[03:28:39.579] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:34:0)
[03:28:39.579] Timer tick, asking Buildbucket for the build status
[03:28:39.665] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:39.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:35:0) after 1m0s
[03:29:39.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:35:0)
[03:29:39.848] Timer tick, asking Buildbucket for the build status
[03:29:39.940] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:39.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:36:0) after 1m0s
[03:30:39.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:36:0)
[03:30:39.960] Timer tick, asking Buildbucket for the build status
[03:30:40.053] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:40.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:37:0) after 1m0s
[03:31:40.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:37:0)
[03:31:40.095] Timer tick, asking Buildbucket for the build status
[03:31:40.197] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:40.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:38:0) after 1m0s
[03:32:40.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:38:0)
[03:32:40.210] Timer tick, asking Buildbucket for the build status
[03:32:40.509] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:40.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:39:0) after 1m0s
[03:33:40.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:39:0)
[03:33:40.529] Timer tick, asking Buildbucket for the build status
[03:33:41.105] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:41.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:40:0) after 1m0s
[03:34:41.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:40:0)
[03:34:41.143] Timer tick, asking Buildbucket for the build status
[03:34:41.248] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:41.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:41:0) after 1m0s
[03:35:41.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:41:0)
[03:35:41.386] Timer tick, asking Buildbucket for the build status
[03:35:41.624] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:41.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:42:0) after 1m0s
[03:36:41.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:42:0)
[03:36:41.670] Timer tick, asking Buildbucket for the build status
[03:36:42.126] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:42.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:43:0) after 1m0s
[03:37:42.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:43:0)
[03:37:42.241] Timer tick, asking Buildbucket for the build status
[03:37:42.551] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:42.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:44:0) after 1m0s
[03:38:42.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:44:0)
[03:38:42.662] Timer tick, asking Buildbucket for the build status
[03:38:42.749] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:42.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:45:0) after 1m0s
[03:39:42.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:45:0)
[03:39:42.854] Timer tick, asking Buildbucket for the build status
[03:39:42.932] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:42.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:46:0) after 1m0s
[03:40:42.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:46:0)
[03:40:42.953] Timer tick, asking Buildbucket for the build status
[03:40:43.127] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:43.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:47:0) after 1m0s
[03:41:43.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:47:0)
[03:41:43.183] Timer tick, asking Buildbucket for the build status
[03:41:43.311] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:43.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:48:0) after 1m0s
[03:42:43.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:48:0)
[03:42:43.351] Timer tick, asking Buildbucket for the build status
[03:42:43.461] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:43.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:49:0) after 1m0s
[03:43:43.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:49:0)
[03:43:43.509] Timer tick, asking Buildbucket for the build status
[03:43:43.598] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:43.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:50:0) after 1m0s
[03:44:43.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:50:0)
[03:44:43.622] Timer tick, asking Buildbucket for the build status
[03:44:43.706] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:43.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:51:0) after 1m0s
[03:45:43.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:51:0)
[03:45:43.752] Timer tick, asking Buildbucket for the build status
[03:45:44.019] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:44.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:52:0) after 1m0s
[03:46:44.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:52:0)
[03:46:44.038] Timer tick, asking Buildbucket for the build status
[03:46:44.448] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:44.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:53:0) after 1m0s
[03:47:44.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:53:0)
[03:47:44.453] Timer tick, asking Buildbucket for the build status
[03:47:44.711] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:44.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:54:0) after 1m0s
[03:48:44.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:54:0)
[03:48:44.797] Timer tick, asking Buildbucket for the build status
[03:48:45.029] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:45.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:55:0) after 1m0s
[03:49:45.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:55:0)
[03:49:45.086] Timer tick, asking Buildbucket for the build status
[03:49:45.181] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:45.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:56:0) after 1m0s
[03:50:45.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:56:0)
[03:50:45.215] Timer tick, asking Buildbucket for the build status
[03:50:45.344] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:45.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:57:0) after 1m0s
[03:51:45.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:57:0)
[03:51:45.363] Timer tick, asking Buildbucket for the build status
[03:51:45.469] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:45.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:58:0) after 1m0s
[03:52:45.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:58:0)
[03:52:45.485] Timer tick, asking Buildbucket for the build status
[03:52:45.578] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:45.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:59:0) after 1m0s
[03:53:45.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:59:0)
[03:53:45.613] Timer tick, asking Buildbucket for the build status
[03:53:45.783] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:45.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:60:0) after 1m0s
[03:54:45.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:60:0)
[03:54:45.791] Timer tick, asking Buildbucket for the build status
[03:54:45.904] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:45.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:61:0) after 1m0s
[03:55:45.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:61:0)
[03:55:45.928] Timer tick, asking Buildbucket for the build status
[03:55:46.135] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:46.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:62:0) after 1m0s
[03:56:46.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:62:0)
[03:56:46.154] Timer tick, asking Buildbucket for the build status
[03:56:46.248] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:46.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:63:0) after 1m0s
[03:57:46.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:63:0)
[03:57:46.267] Timer tick, asking Buildbucket for the build status
[03:57:46.607] Build 8855174858999399872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:46.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020604920940308112:64:0) after 1m0s
[03:58:38.896] Received PubSub notification, asking Buildbucket for the build status
[03:58:39.307] Build 8855174858999399872: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:58:39.307] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613447918284872",
"created_by": "project:chromium",
"created_ts": "1613444191893255",
"id": "8855174858999399872",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"055cf2142b6a0ac90529d24fd626a463295ebd05\"}}",
"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\": \"055cf2142b6a0ac90529d24fd626a463295ebd05\", \"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-0-6l07\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17367, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-1\", \"got_angle_revision\": \"ca6573ebb08a3048f94965aec7514750579a158d\", \"got_dawn_revision\": \"e61c9d86884145733d5763ab23c15bf09acf358c\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"055cf2142b6a0ac90529d24fd626a463295ebd05\", \"got_revision_cp\": \"refs/heads/master@{#854136}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"539ef8e9426e10d7611d433ee60b94de0737344a\", \"got_v8_revision\": \"ebacb645b20531cd78dc088730fb72269822eeb6\", \"got_v8_revision_cp\": \"refs/heads/9.0.172@{#2}\", \"got_webrtc_revision\": \"8408c9938cb543a7cd5e7127927f3b4d0834ad59\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33272}\", \"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\": \"055cf2142b6a0ac90529d24fd626a463295ebd05\", \"swarm_hashes_refs/heads/master(at){#854136}_without_patch\": {\"absl_hardening_tests\": \"e3b1188b5fad238d6f229bec22f1743b1238d9ff\", \"accessibility_unittests\": \"f384a7ae0edeb91ea623bd0a9836bc3b183aa3e1\", \"angle_unittests\": \"9f745fadad6b95ffc4edbe6e84b446f9835e47f5\", \"app_shell_unittests\": \"7a8401011f91e816362a40d0e0351b5bbc69684b\", \"aura_unittests\": \"c6ef4cb869c0bc951dfa70e77e33ab76527a4d22\", \"base_unittests\": \"785254e20cd6bd35c33a80e515ef2a1c45203c17\", \"base_util_unittests\": \"2cba5f008f6330d2c5c6059f173c454c47e888ea\", \"blink_common_unittests\": \"4c64a7007a712255736d5d0ad1a16d3d5e0529cb\", \"blink_fuzzer_unittests\": \"7c2de157c1d058eb2da9f889138de948448d4dd8\", \"blink_heap_unittests\": \"d67e0afae32ba8440468451056537facf35dd984\", \"blink_platform_unittests\": \"6e3ef9788f3de220bd2ac4118ba6d194d6a1e67a\", \"blink_unittests\": \"195e3bd8d3ab99c0024105af44ba312ce011012c\", \"boringssl_crypto_tests\": \"e453d8fb80cee363177f3af7c343f6c3a86b3d13\", \"boringssl_ssl_tests\": \"afa4a155e532b35133044e699839888f5d9bf45d\", \"browser_switcher_bho_unittests\": \"4d769f0b7cd5a4f8a51cf51be4378f0de55d1d20\", \"browser_tests\": \"eef7da91ae4b46232b5650b1963632ae6295788a\", \"capture_unittests\": \"99d4ec7d988dae384545183c8f5bf06a67873e96\", \"cast_unittests\": \"4adb890bf4b38efe4982ae261ce123b0efa27a93\", \"cc_unittests\": \"ebf3a27253944d868883c665a0a52718bad4bfff\", \"chrome_app_unittests\": \"926c44f79c7c4ecddb566c39e30134daeda7dea1\", \"chrome_cleaner_unittests\": \"f4eb7b8d399758abfbdb635be533a7a535629417\", \"chrome_elf_unittests\": \"00e861ea5c7b3490790e0a17771b33d2adc6b635\", \"chromedriver_unittests\": \"8d4c71537de213efe144cb1e2f19660086b855a7\", \"components_browsertests\": \"c0f1456a80e631e0b8b3f36655e3423afc2cb80f\", \"components_unittests\": \"c603c133754d5cce8fc37843ac2a310f227168d9\", \"compositor_unittests\": \"76c5567b7e899d13d30ccb422480a61d28a530ce\", \"content_browsertests\": \"b9812f607f083a19bab699ee62e86dc5e1038782\", \"content_unittests\": \"8a2e555f4b95fe22c46f4a62b0415052e52add5b\", \"courgette_unittests\": \"13418605631c010b5ace8d92ca36d5dc9f1fa485\", \"crashpad_tests\": \"c13ba8aa0d80dbd6fce4a1754159b76557e9f399\", \"cronet_tests\": \"fc0ac87e795fb3858b22290c9c5775f3a85b0e55\", \"cronet_unittests\": \"e87ede121216ad430a70d9906225494cd92a5f39\", \"crypto_unittests\": \"13812472fc3d571411430677e0f45e97d1bc6d50\", \"delayloads_unittests\": \"c20423cdab9162575d4a0b872fcb666b47d7b41a\", \"device_unittests\": \"bf8e2708152bc622d47b189ae3a20a3c92c60788\", \"display_unittests\": \"213de280977e43505ce7aa82e9ff7d169363657e\", \"elevation_service_unittests\": \"7d4ec503387d4a1d0f4b43c11b912e1321af9819\", \"events_unittests\": \"b523ced6b221638821726ebe7c442017f712e46d\", \"extensions_browsertests\": \"febb18449826be757dc02486f1bebdd015678d30\", \"extensions_unittests\": \"5395bc671ec4cbf36ba52cab41aa1832478d6831\", \"filesystem_service_unittests\": \"0b09869a337516f997ea8b25807ca91ec24a1ac4\", \"gcm_unit_tests\": \"18e937e6dc270ea8494156260ddde15068bbf0cd\", \"gcp_unittests\": \"0267e0f1b02a5c4c941254663178025d3a9457a7\", \"gfx_unittests\": \"54b76b4f20c5257f12a9b83935899e7dac7bd2ac\", \"gin_unittests\": \"8962850f51be3f38dd69ca9811c8d90f3f1bdf9b\", \"google_apis_unittests\": \"26ef3a5baff8cf2324910529623104243ecb1a72\", \"gpu_unittests\": \"ab4c025db3d3bfa1ecd87f3c1e01104cd8094329\", \"gwp_asan_unittests\": \"dbbd3ab0fa39fe49942932361a2ca26e23b0f886\", \"headless_browsertests\": \"cdd9bb09a71a93cc4fb5640827583aed9f43fcfa\", \"headless_unittests\": \"2c04043c064f989b551e172b13b7b03972c44583\", \"install_static_unittests\": \"ca6f27a19be4579ee38f46cb51a092f58a82ced0\", \"installer_util_unittests\": \"c944d9d5e4ffa4fc99239c7d075e97ed10cbfd6a\", \"interactive_ui_tests\": \"9a812307fc958c9cfd0c3b822ec04a363636b96e\", \"ipc_tests\": \"3a969fb9c9463f171c50676932a0b41c4e095673\", \"jingle_unittests\": \"55e3c31c5e97416419ea192d5cff78f57ab97f27\", \"latency_unittests\": \"69d274308ed126b4cca940ff557a2c0236a95c32\", \"libjingle_xmpp_unittests\": \"bdb664e4f2dc2c8af3840bacd5c27ceb59e49980\", \"liburlpattern_unittests\": \"e844e823a53d1a78b907d636fdb20126a9af5cfa\", \"media_blink_unittests\": \"efb5dadd59cfc352694181d4f01969abe6912c31\", \"media_unittests\": \"6f55567095ac3f1c6b474372a869d148dbd66fc4\", \"message_center_unittests\": \"6605af1b375a1755cee6de6a0dbdbbb3647f7db0\", \"midi_unittests\": \"483334dadc3fefa6a77250a5852e162ca8138214\", \"mojo_core_unittests\": \"8c818f763b75103bddf464ddd44804b3a7d6309a\", \"mojo_unittests\": \"0834a859a72b038347687b3ac62bddcc86679039\", \"nacl_loader_unittests\": \"3710766d0b470361ff9a7e340d7e753bbf11791d\", \"native_theme_unittests\": \"c087feca606704d23f04785aef4c68b1cf166712\", \"net_unittests\": \"11d1fde15e58cd36b5c063f53e8234898c02f6ae\", \"notification_helper_unittests\": \"309aaf48245a0d4336d3bcc05485a7c30ad969c8\", \"pdf_unittests\": \"ca5774b0ecce7a8ccdea2fcbbf1e7a49b2311b52\", \"perfetto_unittests\": \"39895e2b2700a8648315c2c2826544055acfbbd1\", \"ppapi_unittests\": \"776f5c9e85396c4d0d9863340de3749496eb298a\", \"printing_unittests\": \"501d9cbd78ec5e0a0de5b65a7e884b1a63d4f606\", \"remoting_unittests\": \"e8a262d9d57845c77ab23014e47753e7d08b746f\", \"sbox_integration_tests\": \"860de998907bec8da442ece4ad0b0283c51c0dd8\", \"sbox_unittests\": \"630e831656cd65b1214c301b476fdb005e79deb0\", \"sbox_validation_tests\": \"7f7cdb2c92977ba383951e8b9c44295eb5a9260f\", \"service_manager_unittests\": \"672d41d4bd6c75491b5ac482386eb00d58caf736\", \"services_unittests\": \"cf9b675d1f660967d8f91a7c6fdd91a2051a13ee\", \"setup_unittests\": \"4d832c0a30e8d74edf71e8234084480a62b6f0ef\", \"shell_dialogs_unittests\": \"fc8edc6e867e65437b87324d728845f0777d0447\", \"skia_unittests\": \"aa39681752e724255a56d8af013c046725ee4c84\", \"snapshot_unittests\": \"18bde3bc57f594262b086c49b402cbd95d4fb8dc\", \"sql_unittests\": \"e0cfcc8283db937a9caaed5706adde1a98beca2c\", \"storage_unittests\": \"55cc827b7bc0ac281a3ff33a6810a7a4060fabcf\", \"sync_integration_tests\": \"35e4044c371d6be54c9592a7bfdfa9cb34f54180\", \"traffic_annotation_auditor_unittests\": \"84ad7c2b2b7fcb937c2dbb97af067788c6f55a3a\", \"ui_base_unittests\": \"215fb0cfd0cc418f12f4043ea8096ecf72554c7e\", \"ui_touch_selection_unittests\": \"a1762bb80680767fb0b52393a2a399e4ecb8e0d5\", \"unit_tests\": \"0315c5150f5f4349b5c2b284bc236a9c24203c7f\", \"updater_tests\": \"1b5dd5b2b8a056afde3abd3eb10db328438c8590\", \"url_unittests\": \"c38d24df0fa4634830ed808cc203dfd5781584b3\", \"views_unittests\": \"f8c4ec98349a60023223f35e8540f74346c435df\", \"viz_unittests\": \"92150d995c2e88809a57162fe26699b09cf9ad3b\", \"vr_common_unittests\": \"933921c9e1355056a6c046dbb174f1d75d749e5c\", \"vr_pixeltests\": \"d4f2ce7c8956211f96e2d73a65caa33c7d5c954d\", \"weblayer_browsertests\": \"191a41e4d5b8e20d671595f8d28dd35f78b06192\", \"weblayer_unittests\": \"02521eacfc4fb1de5a835b00786db8ed435a3fcb\", \"wm_unittests\": \"f13f775ddea6f335be711a1b1ff346bdc7dc5e99\", \"wtf_unittests\": \"4e6c66e2e2235cefdfa1d1fd4d3d3e86da37c154\", \"zlib_unittests\": \"2ec4857729edb34bc28a3e39ded86866b4a2c7b7\", \"zucchini_unittests\": \"d5fb8bac5ae5af03a2b58b0579838ff6ce2b83cf\"}}, \"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-0-6l07\"], \"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\": [\"5700-b36f18b\"], \"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": "1613444193995585",
"status": "COMPLETED",
"status_changed_ts": "1613447918601098",
"tags": [
"build_address:luci.chromium.ci/win-asan/17367",
"builder:win-asan",
"buildset:commit/git/055cf2142b6a0ac90529d24fd626a463295ebd05",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/055cf2142b6a0ac90529d24fd626a463295ebd05",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020604920940308112",
"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/8855174858999399872/+/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:51c19621d988f010",
"user_agent:luci-scheduler"
],
"updated_ts": "1613447918605434",
"url": "https://ci.chromium.org/b/8855174858999399872",
"utcnow_ts": "1613447919288185"
}
}
[03:58:39.307] Invocation finished in 1h2m8.647603372s with status SUCCEEDED