[03:54:38.890] New invocation is queued and will start shortly
[03:54:40.007] Starting the invocation (attempt 1)
[03:54:40.076] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:54:40.076] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:54:40.076] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019423502724974640",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6435d619fc27b53b5ceecbf8b03fc16547972552\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019423502724974640",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/6435d619fc27b53b5ceecbf8b03fc16547972552",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6435d619fc27b53b5ceecbf8b03fc16547972552",
"gitiles_ref:refs/heads/master"
]
}
[03:54:40.645] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1614570880180234",
"id": "8853993440702904656",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6435d619fc27b53b5ceecbf8b03fc16547972552\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614570880534118",
"tags": [
"build_address:luci.chromium.ci/win-asan/17567",
"builder:win-asan",
"buildset:commit/git/6435d619fc27b53b5ceecbf8b03fc16547972552",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6435d619fc27b53b5ceecbf8b03fc16547972552",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019423502724974640",
"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/8853993440702904656/+/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": "1614570880534220",
"url": "https://ci.chromium.org/b/8853993440702904656",
"utcnow_ts": "1614570880637652"
}
}
[03:54:40.645] Task URL: https://ci.chromium.org/b/8853993440702904656
[03:54:40.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:2:0) after 1m0s
[03:54:42.506] Received PubSub notification, asking Buildbucket for the build status
[03:54:42.680] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:40.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:2:0)
[03:55:40.660] Timer tick, asking Buildbucket for the build status
[03:55:40.968] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:40.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:4:0) after 1m0s
[03:56:40.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:4:0)
[03:56:40.984] Timer tick, asking Buildbucket for the build status
[03:56:41.181] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:41.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:5:0) after 1m0s
[03:57:41.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:5:0)
[03:57:41.195] Timer tick, asking Buildbucket for the build status
[03:57:41.275] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:41.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:6:0) after 1m0s
[03:58:41.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:6:0)
[03:58:41.291] Timer tick, asking Buildbucket for the build status
[03:58:41.361] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:41.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:7:0) after 1m0s
[03:59:41.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:7:0)
[03:59:41.377] Timer tick, asking Buildbucket for the build status
[03:59:42.127] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:42.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:8:0) after 1m0s
[04:00:42.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:8:0)
[04:00:42.142] Timer tick, asking Buildbucket for the build status
[04:00:42.244] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:42.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:9:0) after 1m0s
[04:01:42.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:9:0)
[04:01:42.258] Timer tick, asking Buildbucket for the build status
[04:01:42.525] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:42.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:10:0) after 1m0s
[04:02:42.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:10:0)
[04:02:42.553] Timer tick, asking Buildbucket for the build status
[04:02:42.632] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:42.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:11:0) after 1m0s
[04:03:42.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:11:0)
[04:03:42.627] Timer tick, asking Buildbucket for the build status
[04:03:42.702] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:42.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:12:0) after 1m0s
[04:04:42.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:12:0)
[04:04:42.811] Timer tick, asking Buildbucket for the build status
[04:04:42.906] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:42.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:13:0) after 1m0s
[04:05:43.001] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:13:0)
[04:05:43.001] Timer tick, asking Buildbucket for the build status
[04:05:43.123] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:43.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:14:0) after 1m0s
[04:06:43.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:14:0)
[04:06:43.226] Timer tick, asking Buildbucket for the build status
[04:06:43.313] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:43.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:15:0) after 1m0s
[04:07:43.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:15:0)
[04:07:43.384] Timer tick, asking Buildbucket for the build status
[04:07:43.475] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:43.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:16:0) after 1m0s
[04:08:43.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:16:0)
[04:08:43.531] Timer tick, asking Buildbucket for the build status
[04:08:43.634] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:43.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:17:0) after 1m0s
[04:09:43.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:17:0)
[04:09:43.701] Timer tick, asking Buildbucket for the build status
[04:09:43.817] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:43.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:18:0) after 1m0s
[04:10:44.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:18:0)
[04:10:44.025] Timer tick, asking Buildbucket for the build status
[04:10:44.108] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:44.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:19:0) after 1m0s
[04:11:44.174] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:19:0)
[04:11:44.174] Timer tick, asking Buildbucket for the build status
[04:11:44.253] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:44.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:20:0) after 1m0s
[04:12:47.204] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:20:0)
[04:12:47.204] Timer tick, asking Buildbucket for the build status
[04:12:47.331] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:47.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:21:0) after 1m0s
[04:13:47.341] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:21:0)
[04:13:47.341] Timer tick, asking Buildbucket for the build status
[04:13:47.486] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:47.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:22:0) after 1m0s
[04:14:47.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:22:0)
[04:14:47.499] Timer tick, asking Buildbucket for the build status
[04:14:47.760] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:47.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:23:0) after 1m0s
[04:15:47.835] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:23:0)
[04:15:47.835] Timer tick, asking Buildbucket for the build status
[04:15:47.917] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:47.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:24:0) after 1m0s
[04:16:48.030] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:24:0)
[04:16:48.030] Timer tick, asking Buildbucket for the build status
[04:16:48.228] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:48.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:25:0) after 1m0s
[04:17:48.300] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:25:0)
[04:17:48.300] Timer tick, asking Buildbucket for the build status
[04:17:48.407] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:48.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:26:0) after 1m0s
[04:18:48.468] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:26:0)
[04:18:48.468] Timer tick, asking Buildbucket for the build status
[04:18:48.633] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:48.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:27:0) after 1m0s
[04:19:48.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:27:0)
[04:19:48.801] Timer tick, asking Buildbucket for the build status
[04:19:48.888] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:48.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:28:0) after 1m0s
[04:20:49.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:28:0)
[04:20:49.049] Timer tick, asking Buildbucket for the build status
[04:20:49.142] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:49.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:29:0) after 1m0s
[04:21:49.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:29:0)
[04:21:49.263] Timer tick, asking Buildbucket for the build status
[04:21:49.432] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:49.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:30:0) after 1m0s
[04:22:49.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:30:0)
[04:22:49.483] Timer tick, asking Buildbucket for the build status
[04:22:49.789] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:49.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:31:0) after 1m0s
[04:23:49.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:31:0)
[04:23:49.806] Timer tick, asking Buildbucket for the build status
[04:23:49.936] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:49.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:32:0) after 1m0s
[04:24:49.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:32:0)
[04:24:49.950] Timer tick, asking Buildbucket for the build status
[04:24:50.064] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:50.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:33:0) after 1m0s
[04:25:50.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:33:0)
[04:25:50.079] Timer tick, asking Buildbucket for the build status
[04:25:50.429] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:50.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:34:0) after 1m0s
[04:26:50.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:34:0)
[04:26:50.453] Timer tick, asking Buildbucket for the build status
[04:26:50.896] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:50.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:35:0) after 1m0s
[04:27:51.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:35:0)
[04:27:51.023] Timer tick, asking Buildbucket for the build status
[04:27:51.129] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:51.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:36:0) after 1m0s
[04:28:51.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:36:0)
[04:28:51.210] Timer tick, asking Buildbucket for the build status
[04:28:51.338] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:51.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:37:0) after 1m0s
[04:29:51.307] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:37:0)
[04:29:51.307] Timer tick, asking Buildbucket for the build status
[04:29:51.448] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:51.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:38:0) after 1m0s
[04:30:51.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:38:0)
[04:30:51.446] Timer tick, asking Buildbucket for the build status
[04:30:51.554] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:51.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:39:0) after 1m0s
[04:31:51.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:39:0)
[04:31:51.571] Timer tick, asking Buildbucket for the build status
[04:31:51.832] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:51.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:40:0) after 1m0s
[04:32:51.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:40:0)
[04:32:51.878] Timer tick, asking Buildbucket for the build status
[04:32:52.124] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:52.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:41:0) after 1m0s
[04:33:52.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:41:0)
[04:33:52.118] Timer tick, asking Buildbucket for the build status
[04:33:52.205] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:52.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:42:0) after 1m0s
[04:34:52.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:42:0)
[04:34:52.228] Timer tick, asking Buildbucket for the build status
[04:34:52.357] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:52.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:43:0) after 1m0s
[04:35:52.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:43:0)
[04:35:52.378] Timer tick, asking Buildbucket for the build status
[04:35:52.456] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:52.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:44:0) after 1m0s
[04:36:52.468] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:44:0)
[04:36:52.468] Timer tick, asking Buildbucket for the build status
[04:36:52.562] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:52.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:45:0) after 1m0s
[04:37:52.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:45:0)
[04:37:52.653] Timer tick, asking Buildbucket for the build status
[04:37:52.752] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:52.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:46:0) after 1m0s
[04:38:52.773] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:46:0)
[04:38:52.773] Timer tick, asking Buildbucket for the build status
[04:38:52.864] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:52.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:47:0) after 1m0s
[04:39:52.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:47:0)
[04:39:52.944] Timer tick, asking Buildbucket for the build status
[04:39:53.067] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:53.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:48:0) after 1m0s
[04:40:53.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:48:0)
[04:40:53.203] Timer tick, asking Buildbucket for the build status
[04:40:53.280] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:53.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:49:0) after 1m0s
[04:41:53.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:49:0)
[04:41:53.360] Timer tick, asking Buildbucket for the build status
[04:41:53.454] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:53.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:50:0) after 1m0s
[04:42:53.549] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:50:0)
[04:42:53.549] Timer tick, asking Buildbucket for the build status
[04:42:53.636] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:53.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:51:0) after 1m0s
[04:43:53.677] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:51:0)
[04:43:53.677] Timer tick, asking Buildbucket for the build status
[04:43:53.769] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:53.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:52:0) after 1m0s
[04:44:53.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:52:0)
[04:44:53.900] Timer tick, asking Buildbucket for the build status
[04:44:54.004] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:54.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:53:0) after 1m0s
[04:45:54.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:53:0)
[04:45:54.049] Timer tick, asking Buildbucket for the build status
[04:45:54.161] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:54.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:54:0) after 1m0s
[04:46:54.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:54:0)
[04:46:54.222] Timer tick, asking Buildbucket for the build status
[04:46:54.366] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:54.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:55:0) after 1m0s
[04:47:54.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:55:0)
[04:47:54.410] Timer tick, asking Buildbucket for the build status
[04:47:54.518] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:54.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:56:0) after 1m0s
[04:48:54.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:56:0)
[04:48:54.576] Timer tick, asking Buildbucket for the build status
[04:48:54.672] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:54.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:57:0) after 1m0s
[04:49:54.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:57:0)
[04:49:54.732] Timer tick, asking Buildbucket for the build status
[04:49:54.856] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:54.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:58:0) after 1m0s
[04:50:55.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:58:0)
[04:50:55.672] Timer tick, asking Buildbucket for the build status
[04:50:55.836] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:55.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:59:0) after 1m0s
[04:51:56.005] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:59:0)
[04:51:56.005] Timer tick, asking Buildbucket for the build status
[04:51:56.430] Build 8853993440702904656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:56.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019423502724974640:60:0) after 1m0s
[04:52:44.399] Received PubSub notification, asking Buildbucket for the build status
[04:52:44.564] Build 8853993440702904656: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:52:44.564] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1614574363235402",
"created_by": "project:chromium",
"created_ts": "1614570880180234",
"id": "8853993440702904656",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6435d619fc27b53b5ceecbf8b03fc16547972552\"}}",
"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\": \"6435d619fc27b53b5ceecbf8b03fc16547972552\", \"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-1-qhp0\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17567, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"a6d4623970eda91638bd3c3638c9104bffcaa316\", \"got_dawn_revision\": \"329c1c98fef964ea532a0579ae6f2553ff3382c5\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"6435d619fc27b53b5ceecbf8b03fc16547972552\", \"got_revision_cp\": \"refs/heads/master@{#858463}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"106e01583a3ac2fb950777390dde2dcc31a8f161\", \"got_v8_revision\": \"45e415150419491d6d12428ba6ee98876a405ba4\", \"got_v8_revision_cp\": \"refs/heads/9.1.1@{#1}\", \"got_webrtc_revision\": \"376cf384ac689eca0bf8d4d1d3a3cf80888d3fbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33348}\", \"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\": \"6435d619fc27b53b5ceecbf8b03fc16547972552\", \"swarm_hashes_refs/heads/master(at){#858463}_without_patch\": {\"absl_hardening_tests\": \"f5e1ce4ad7e40415ddea9e8ef815f5b57a0d2c18\", \"accessibility_unittests\": \"05317cf343633c61441eeb311f11a4a6e733321b\", \"angle_unittests\": \"4eb07e3ff199dd50f11dd697d0a681c033b02a81\", \"app_shell_unittests\": \"00989ce57956defe146e114adda70b4de5bfcf98\", \"aura_unittests\": \"e33b52871e81298e4fa04846100a3abf7c320096\", \"base_unittests\": \"ceaa086d9e454bdf4d59cf86f1a79270d0158055\", \"base_util_unittests\": \"390d6530e4fa409c433121b77371dd212ad1af41\", \"blink_common_unittests\": \"969971bc6bea52850deede9faf617ef3f440285a\", \"blink_fuzzer_unittests\": \"e013325f612537f3e53308230261c9b5b550e811\", \"blink_heap_unittests\": \"b4e806edde0e1435bc675a10913d050aa84ecf12\", \"blink_platform_unittests\": \"09b5275b30e9d89c7a39ea2ebc4b350fe78527ef\", \"blink_unittests\": \"da0049ea3397119f5a6ded2e08fdfe6dc13deb0b\", \"boringssl_crypto_tests\": \"528ebea48188e1814a6ee22def74c8231dc74003\", \"boringssl_ssl_tests\": \"d596a7b849e989259aeec311e9cc7773ee3c68b4\", \"browser_switcher_bho_unittests\": \"3dbf17372a522a319ccc3069a550a9967bdaf8cb\", \"browser_tests\": \"288a8305e02ab6ca9de0aba21fcf78f98ab09719\", \"capture_unittests\": \"45fa9ac6c0240cb7e0d9f1635d3fa0168a4c12fb\", \"cast_unittests\": \"1f4e38ba7e0e5a00c0eaeeae466fad6dcc418f36\", \"cc_unittests\": \"c278e9abef3a6868fa88b49ef7a286cb5312496a\", \"chrome_app_unittests\": \"27f645b76d646b317078172e6fc8c60c473a56d7\", \"chrome_cleaner_unittests\": \"45a6b57bc14ef7480fba5e1d4e677bbcd4d1827a\", \"chrome_elf_unittests\": \"fab484b3f989979b612afdb5b434aee5e84c4b6a\", \"chromedriver_unittests\": \"9c9a0cf755d59e418ac58145192f5c8b244ea18f\", \"color_unittests\": \"dc68095caa4a37e03b09dc30a30728207dce598e\", \"components_browsertests\": \"2552fc6cbe73cb084085598ef4edcb95a3d19497\", \"components_unittests\": \"bfd194381a9224402eb8e13f3f966a8cce9730d2\", \"compositor_unittests\": \"3298cb5a52294438787c8924821ec2131b27fc51\", \"content_browsertests\": \"1b4c02235a83884fe6aed463c3931ee066b8a774\", \"content_unittests\": \"b293406e40d1690c44da14e379486a11e24972c7\", \"courgette_unittests\": \"59c59bab3c51ca8e94da45218a5dde1d2ef5ab67\", \"crashpad_tests\": \"1b9426b7d4fc6473e0a161573de9a49794f93a07\", \"cronet_tests\": \"9f15a2df98a7a1f60fa5d4ab2c729b8300292474\", \"cronet_unittests\": \"7ef5ff1a22d69c992d1a836b55e33b67c07289f2\", \"crypto_unittests\": \"cddc7164b413377d2e880e5ca5487d9c924cb581\", \"delayloads_unittests\": \"9fe6c09b61473ef767d38bcdee93d0f7f5237213\", \"device_unittests\": \"424b048b35f0ccd8f4e012697ddcb02dc3eff2d2\", \"display_unittests\": \"443c6de642bf566bdcf8fbfe0f9b98f49548b7e5\", \"elevation_service_unittests\": \"38eea1df320cede0ceb9f4d372b517fbc64d3dea\", \"events_unittests\": \"0423cb1f6e50c2c915f376f228e34c0f1e952337\", \"extensions_browsertests\": \"05620fe6d1203d342224750cefa9cfe70e93c135\", \"extensions_unittests\": \"1b359e1c63157f6a168b9070b5feee09dd5d194a\", \"filesystem_service_unittests\": \"f81ed029515b192d62855f5128d6f4dafde23ae3\", \"gcm_unit_tests\": \"79c562273c7486685050f67e91e420adbae5ab56\", \"gcp_unittests\": \"3f375852de78f3a772bcd9b6a5118c7e15f289a5\", \"gfx_unittests\": \"6fbd115af57ba8a5251733894ebe27cd249bab80\", \"gin_unittests\": \"15e9e19310b2e9e4d3570b5c40b1de28de387dbf\", \"google_apis_unittests\": \"355dcdab285eb6c2172bec93d629fd9433d6a2d6\", \"gpu_unittests\": \"8d10b8ed2cea12cc9e0265e05eef53cad40c6778\", \"gwp_asan_unittests\": \"6deb2b30fcdd3958858f8ec97653377a5dca3a2f\", \"headless_browsertests\": \"0497f01923637f9bcbd4109541e801b42573bea5\", \"headless_unittests\": \"d9225fccb7226fd0d71e79721fd876120dc4fffa\", \"install_static_unittests\": \"cf4b93be4d79f37bbec3ce30e482c81040b9ff3e\", \"installer_util_unittests\": \"26b855e39f2c5b8fdff77d8c813af53922e9dd49\", \"interactive_ui_tests\": \"ac82d36ee09972bb1bc583e637e543c4d47cf931\", \"ipc_tests\": \"40a7327f0a89fbfb006d502d26b24ffe6d19692a\", \"jingle_unittests\": \"6f18da3d9560f5a75973d6b9ab287c4a17ac3c8b\", \"latency_unittests\": \"86825f79a21e37efc3ee67d4a43c0c78d054f3cd\", \"libjingle_xmpp_unittests\": \"fae9c8c71df405a6f926c4fcd79bc03fed54a728\", \"liburlpattern_unittests\": \"978bab83b37b4e12fcc9513bcc904ee465d152ac\", \"media_blink_unittests\": \"8384e3c9af50487e7c2e218866c0b6fdf686eec5\", \"media_unittests\": \"c543ec605808a8b544f88f2b68c96a864b6594e7\", \"message_center_unittests\": \"1839e0cb3b701e210f6a67b858ac03489002f5d6\", \"midi_unittests\": \"948beec888f31195e333b5429fe05c5a317460fe\", \"mojo_core_unittests\": \"ca9350603b6c3e09a40423b98aecac4bed421aa6\", \"mojo_unittests\": \"4603afd4d0db1dbc6e80b759616eb40912c6fdfc\", \"nacl_loader_unittests\": \"35b2f6a8d815337425311e5b695bf9ef209c8847\", \"native_theme_unittests\": \"aaa71abf773e40e1793d4fee826741cf5d0cccf4\", \"net_unittests\": \"b447b1b0d498b5461bbbc80bbb41531f516b1009\", \"notification_helper_unittests\": \"24a706ed05595322d14b638ec49e8fe777f249fc\", \"pdf_unittests\": \"7902cd59142fef9db5c7799fddb415bd7daa035c\", \"perfetto_unittests\": \"05d2c7111aa4ba8c37b26a3013e1090d026aeecf\", \"ppapi_unittests\": \"c3ec649e1be12a49f72ede73f118f795f85d5ab0\", \"printing_unittests\": \"235af4efc87aefca1af0401559bf723e001a5325\", \"remoting_unittests\": \"d0c2eab419aa61bb64f2e80f1bfe71253f321f0f\", \"sbox_integration_tests\": \"d6a13f3919e3bfcb0c4410a459492d3dad92ca01\", \"sbox_unittests\": \"4f542d709d703943db53b68924d3f0bc0ac786c0\", \"sbox_validation_tests\": \"8d4b53e9f495aa47eae28d07f5ff7c3586da1251\", \"service_manager_unittests\": \"c764bdfeada08a38dbbf520cd201cdf964b96264\", \"services_unittests\": \"b4a9783c76c544b38129f79f38c450939191ab2f\", \"setup_unittests\": \"23c93c720ed0b67fbbd73b14dc2ecf4da0002197\", \"shell_dialogs_unittests\": \"2d0e9a0904bff54ad4300bc31ea28d2e5566f32b\", \"skia_unittests\": \"00b83de356d4f3f9ba26d97ab519ec19b526c6e6\", \"snapshot_unittests\": \"72184e88f6887dbcf577fd272ba00ca742d831f8\", \"sql_unittests\": \"42bfde58e860dffbc3c5d69c33dddbdf4ad6b498\", \"storage_unittests\": \"ea7c5d79e5220b291bb4403b8f7dfe6bbec98b1f\", \"sync_integration_tests\": \"15a715731983416db3b4e041ba9b82dfbede456c\", \"traffic_annotation_auditor_unittests\": \"db28e429fb6781c752c695f797614042bcddc7b7\", \"ui_base_unittests\": \"8fc642e8f84334dbeec28cba20d9bd2cc72bf910\", \"ui_touch_selection_unittests\": \"9de4eded2b237cb5c9de9ca486f23e9aa181772b\", \"unit_tests\": \"c2213d7165eaf98157fdbb440d4509db78b0462e\", \"updater_tests\": \"bffc2c3a9fc256cb0b6fff159f5b6a39f95b50ff\", \"url_unittests\": \"f6476b583e5fea9211322a5ee9ea31c836017311\", \"views_unittests\": \"f7bd5b7ce057a45986b54859fc2c7ba909779096\", \"viz_unittests\": \"4d6cc978002a0e595acb6884c55bd6edbea13376\", \"vr_common_unittests\": \"9cf26f7d942071cce490cf3a9666a340beb8c4ee\", \"vr_pixeltests\": \"b3fe4955ed4516e34d04c1b422664a77d75cc9f0\", \"weblayer_browsertests\": \"84f314cd13e2f6a5a923094b609286a838a1e29b\", \"weblayer_unittests\": \"ce2190bf16276c696663da28991449b69d897e10\", \"wm_unittests\": \"fcb478fcbcfd5f8dcf1b01bc39f1d9946b292de5\", \"wtf_unittests\": \"3dd803d79fe5f6c6d5a4f56067077f93bad32278\", \"zlib_unittests\": \"2d4ff3fa2ab1390d2327e6f5a9a147984bfad37e\", \"zucchini_unittests\": \"312d32fda38a462031f96f88a44c6a8f9e23ae22\"}}, \"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-1-qhp0\"], \"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": "1614570882046208",
"status": "COMPLETED",
"status_changed_ts": "1614574363629015",
"tags": [
"build_address:luci.chromium.ci/win-asan/17567",
"builder:win-asan",
"buildset:commit/git/6435d619fc27b53b5ceecbf8b03fc16547972552",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6435d619fc27b53b5ceecbf8b03fc16547972552",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019423502724974640",
"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/8853993440702904656/+/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:5204be07ad1ae910",
"user_agent:luci-scheduler"
],
"updated_ts": "1614574363687700",
"url": "https://ci.chromium.org/b/8853993440702904656",
"utcnow_ts": "1614574364551198"
}
}
[04:52:44.564] Invocation finished in 58m5.69612674s with status SUCCEEDED