[17:16:26.690] New invocation is queued and will start shortly
[17:16:27.919] Starting the invocation (attempt 1)
[17:16:27.960] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:16:27.960] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:16:27.960] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030244694007232240",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ff0230804ed267fc6638e20d9df1846370bde825\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9030244694007232240",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/ff0230804ed267fc6638e20d9df1846370bde825",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ff0230804ed267fc6638e20d9df1846370bde825",
"gitiles_ref:refs/heads/master"
]
}
[17:16:28.739] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604250988046881",
"id": "8864814631917172336",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ff0230804ed267fc6638e20d9df1846370bde825\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604250988630144",
"tags": [
"build_address:luci.chromium.ci/win-asan/15397",
"builder:win-asan",
"buildset:commit/git/ff0230804ed267fc6638e20d9df1846370bde825",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ff0230804ed267fc6638e20d9df1846370bde825",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030244694007232240",
"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/8864814631917172336/+/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": "1604250988630319",
"url": "https://ci.chromium.org/b/8864814631917172336",
"utcnow_ts": "1604250988729195"
}
}
[17:16:28.739] Task URL: https://ci.chromium.org/b/8864814631917172336
[17:16:28.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:2:0) after 1m0s
[17:16:30.320] Received PubSub notification, asking Buildbucket for the build status
[17:16:30.381] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:28.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:2:0)
[17:17:28.768] Timer tick, asking Buildbucket for the build status
[17:17:29.003] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:29.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:4:0) after 1m0s
[17:18:29.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:4:0)
[17:18:29.027] Timer tick, asking Buildbucket for the build status
[17:18:29.312] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:29.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:5:0) after 1m0s
[17:19:29.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:5:0)
[17:19:29.346] Timer tick, asking Buildbucket for the build status
[17:19:29.524] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:29.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:6:0) after 1m0s
[17:20:29.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:6:0)
[17:20:29.606] Timer tick, asking Buildbucket for the build status
[17:20:29.699] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:29.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:7:0) after 1m0s
[17:21:29.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:7:0)
[17:21:29.726] Timer tick, asking Buildbucket for the build status
[17:21:29.786] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:29.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:8:0) after 1m0s
[17:22:29.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:8:0)
[17:22:29.802] Timer tick, asking Buildbucket for the build status
[17:22:30.047] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:30.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:9:0) after 1m0s
[17:23:30.083] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:9:0)
[17:23:30.083] Timer tick, asking Buildbucket for the build status
[17:23:30.177] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:30.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:10:0) after 1m0s
[17:24:30.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:10:0)
[17:24:30.205] Timer tick, asking Buildbucket for the build status
[17:24:30.322] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:30.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:11:0) after 1m0s
[17:25:30.366] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:11:0)
[17:25:30.366] Timer tick, asking Buildbucket for the build status
[17:25:30.545] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:30.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:12:0) after 1m0s
[17:26:30.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:12:0)
[17:26:30.634] Timer tick, asking Buildbucket for the build status
[17:26:30.842] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:30.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:13:0) after 1m0s
[17:27:30.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:13:0)
[17:27:30.870] Timer tick, asking Buildbucket for the build status
[17:27:31.013] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:31.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:14:0) after 1m0s
[17:28:31.096] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:14:0)
[17:28:31.096] Timer tick, asking Buildbucket for the build status
[17:28:31.223] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:31.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:15:0) after 1m0s
[17:29:31.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:15:0)
[17:29:31.298] Timer tick, asking Buildbucket for the build status
[17:29:31.575] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:31.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:16:0) after 1m0s
[17:30:31.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:16:0)
[17:30:31.724] Timer tick, asking Buildbucket for the build status
[17:30:31.908] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:31.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:17:0) after 1m0s
[17:31:31.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:17:0)
[17:31:31.946] Timer tick, asking Buildbucket for the build status
[17:31:32.260] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:32.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:18:0) after 1m0s
[17:32:32.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:18:0)
[17:32:32.361] Timer tick, asking Buildbucket for the build status
[17:32:32.858] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:32.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:19:0) after 1m0s
[17:33:32.966] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:19:0)
[17:33:32.966] Timer tick, asking Buildbucket for the build status
[17:33:33.168] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:33.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:20:0) after 1m0s
[17:34:33.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:20:0)
[17:34:33.241] Timer tick, asking Buildbucket for the build status
[17:34:33.433] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:33.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:21:0) after 1m0s
[17:35:33.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:21:0)
[17:35:33.455] Timer tick, asking Buildbucket for the build status
[17:35:33.738] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:33.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:22:0) after 1m0s
[17:36:33.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:22:0)
[17:36:33.761] Timer tick, asking Buildbucket for the build status
[17:36:33.926] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:33.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:23:0) after 1m0s
[17:37:33.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:23:0)
[17:37:33.947] Timer tick, asking Buildbucket for the build status
[17:37:34.048] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:34.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:24:0) after 1m0s
[17:38:34.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:24:0)
[17:38:34.088] Timer tick, asking Buildbucket for the build status
[17:38:34.169] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:34.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:25:0) after 1m0s
[17:39:34.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:25:0)
[17:39:34.203] Timer tick, asking Buildbucket for the build status
[17:39:34.303] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:34.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:26:0) after 1m0s
[17:40:34.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:26:0)
[17:40:34.375] Timer tick, asking Buildbucket for the build status
[17:40:34.480] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:34.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:27:0) after 1m0s
[17:41:34.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:27:0)
[17:41:34.555] Timer tick, asking Buildbucket for the build status
[17:41:34.727] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:34.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:28:0) after 1m0s
[17:42:34.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:28:0)
[17:42:34.749] Timer tick, asking Buildbucket for the build status
[17:42:34.864] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:34.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:29:0) after 1m0s
[17:43:34.862] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:29:0)
[17:43:34.862] Timer tick, asking Buildbucket for the build status
[17:43:35.075] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:35.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:30:0) after 1m0s
[17:44:35.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:30:0)
[17:44:35.088] Timer tick, asking Buildbucket for the build status
[17:44:35.169] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:35.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:31:0) after 1m0s
[17:45:35.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:31:0)
[17:45:35.246] Timer tick, asking Buildbucket for the build status
[17:45:35.798] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:35.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:32:0) after 1m0s
[17:46:35.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:32:0)
[17:46:35.855] Timer tick, asking Buildbucket for the build status
[17:46:36.180] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:36.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:33:0) after 1m0s
[17:47:36.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:33:0)
[17:47:36.205] Timer tick, asking Buildbucket for the build status
[17:47:36.263] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:36.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:34:0) after 1m0s
[17:48:36.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:34:0)
[17:48:36.288] Timer tick, asking Buildbucket for the build status
[17:48:36.358] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:36.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:35:0) after 1m0s
[17:49:36.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:35:0)
[17:49:36.384] Timer tick, asking Buildbucket for the build status
[17:49:36.725] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:36.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:36:0) after 1m0s
[17:50:36.784] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:36:0)
[17:50:36.784] Timer tick, asking Buildbucket for the build status
[17:50:37.196] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:37.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:37:0) after 1m0s
[17:51:37.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:37:0)
[17:51:37.216] Timer tick, asking Buildbucket for the build status
[17:51:37.320] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:37.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:38:0) after 1m0s
[17:52:37.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:38:0)
[17:52:37.397] Timer tick, asking Buildbucket for the build status
[17:52:37.500] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:37.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:39:0) after 1m0s
[17:53:37.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:39:0)
[17:53:37.526] Timer tick, asking Buildbucket for the build status
[17:53:37.588] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:37.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:40:0) after 1m0s
[17:54:37.590] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:40:0)
[17:54:37.590] Timer tick, asking Buildbucket for the build status
[17:54:37.704] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:37.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:41:0) after 1m0s
[17:55:37.729] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:41:0)
[17:55:37.729] Timer tick, asking Buildbucket for the build status
[17:55:37.884] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:37.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:42:0) after 1m0s
[17:56:37.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:42:0)
[17:56:37.912] Timer tick, asking Buildbucket for the build status
[17:56:38.262] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:38.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:43:0) after 1m0s
[17:57:38.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:43:0)
[17:57:38.276] Timer tick, asking Buildbucket for the build status
[17:57:38.607] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:38.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:44:0) after 1m0s
[17:58:38.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:44:0)
[17:58:38.631] Timer tick, asking Buildbucket for the build status
[17:58:38.998] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:38.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:45:0) after 1m0s
[17:59:39.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:45:0)
[17:59:39.097] Timer tick, asking Buildbucket for the build status
[17:59:39.484] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:39.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:46:0) after 1m0s
[18:00:39.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:46:0)
[18:00:39.526] Timer tick, asking Buildbucket for the build status
[18:00:39.894] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:39.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:47:0) after 1m0s
[18:01:40.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:47:0)
[18:01:40.043] Timer tick, asking Buildbucket for the build status
[18:01:40.168] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:40.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:48:0) after 1m0s
[18:02:40.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:48:0)
[18:02:40.189] Timer tick, asking Buildbucket for the build status
[18:02:40.313] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:40.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:49:0) after 1m0s
[18:03:40.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:49:0)
[18:03:40.336] Timer tick, asking Buildbucket for the build status
[18:03:40.444] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:40.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:50:0) after 1m0s
[18:04:40.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:50:0)
[18:04:40.593] Timer tick, asking Buildbucket for the build status
[18:04:40.814] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:40.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:51:0) after 1m0s
[18:05:40.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:51:0)
[18:05:40.854] Timer tick, asking Buildbucket for the build status
[18:05:41.037] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:41.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:52:0) after 1m0s
[18:06:41.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:52:0)
[18:06:41.056] Timer tick, asking Buildbucket for the build status
[18:06:41.164] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:41.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:53:0) after 1m0s
[18:07:41.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:53:0)
[18:07:41.185] Timer tick, asking Buildbucket for the build status
[18:07:41.450] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:41.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:54:0) after 1m0s
[18:08:41.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:54:0)
[18:08:41.529] Timer tick, asking Buildbucket for the build status
[18:08:41.593] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:41.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:55:0) after 1m0s
[18:09:41.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:55:0)
[18:09:41.605] Timer tick, asking Buildbucket for the build status
[18:09:41.685] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:41.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:56:0) after 1m0s
[18:10:41.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:56:0)
[18:10:41.759] Timer tick, asking Buildbucket for the build status
[18:10:42.276] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:42.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:57:0) after 1m0s
[18:11:42.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:57:0)
[18:11:42.304] Timer tick, asking Buildbucket for the build status
[18:11:42.608] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:42.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:58:0) after 1m0s
[18:12:42.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:58:0)
[18:12:42.639] Timer tick, asking Buildbucket for the build status
[18:12:42.715] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:42.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:59:0) after 1m0s
[18:13:42.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:59:0)
[18:13:42.778] Timer tick, asking Buildbucket for the build status
[18:13:42.881] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:42.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:60:0) after 1m0s
[18:14:42.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:60:0)
[18:14:42.918] Timer tick, asking Buildbucket for the build status
[18:14:43.087] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:43.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:61:0) after 1m0s
[18:15:43.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:61:0)
[18:15:43.109] Timer tick, asking Buildbucket for the build status
[18:15:43.182] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:43.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:62:0) after 1m0s
[18:16:43.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:62:0)
[18:16:43.215] Timer tick, asking Buildbucket for the build status
[18:16:43.377] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:43.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:63:0) after 1m0s
[18:17:43.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:63:0)
[18:17:43.447] Timer tick, asking Buildbucket for the build status
[18:17:43.585] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:43.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:64:0) after 1m0s
[18:18:43.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:64:0)
[18:18:43.649] Timer tick, asking Buildbucket for the build status
[18:18:43.723] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:43.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:65:0) after 1m0s
[18:19:43.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:65:0)
[18:19:43.822] Timer tick, asking Buildbucket for the build status
[18:19:43.975] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:43.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:66:0) after 1m0s
[18:20:44.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:66:0)
[18:20:44.115] Timer tick, asking Buildbucket for the build status
[18:20:44.269] Build 8864814631917172336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:44.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030244694007232240:67:0) after 1m0s
[18:21:33.598] Received PubSub notification, asking Buildbucket for the build status
[18:21:33.675] Build 8864814631917172336: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:21:33.675] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604254892184766",
"created_by": "project:chromium",
"created_ts": "1604250988046881",
"id": "8864814631917172336",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ff0230804ed267fc6638e20d9df1846370bde825\"}}",
"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\": \"ff0230804ed267fc6638e20d9df1846370bde825\", \"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-5-387x\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15397, \"clang_revision\": \"llvmorg-12-init-5627-gf086e85e-2\", \"got_angle_revision\": \"6c618ce31c0099c71ba0279153cbf6d04a2429d4\", \"got_dawn_revision\": \"0a374dce665328391ab3b68fd9e667b018dd9dcd\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"ff0230804ed267fc6638e20d9df1846370bde825\", \"got_revision_cp\": \"refs/heads/master@{#823022}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"e0de282e678b72dac25b81f8d25305231aec01f8\", \"got_v8_revision\": \"69c856dc816ff1dd65fcc536095b44399a7434b9\", \"got_v8_revision_cp\": \"refs/heads/8.8.214@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ff0230804ed267fc6638e20d9df1846370bde825\", \"swarm_hashes_refs/heads/master(at){#823022}_without_patch\": {\"absl_hardening_tests\": \"d5a86944f2a750fb455a9a2699620e68bdd97190\", \"accessibility_unittests\": \"730a781315426445a00fdf2c7f1992b2cac922e7\", \"angle_unittests\": \"bfeca859b95f4e61f5861de5d4d77a887aee5913\", \"app_shell_unittests\": \"4c43c5699c9548cf80a6870828580c74937a9488\", \"aura_unittests\": \"68feb087936944d5223da1619be92ca2b151424b\", \"base_unittests\": \"dff8dc06b5c39f36d1f7122678558135b60c9fdf\", \"base_util_unittests\": \"5ebd7ae8e2bf642902d1e8348fb5b7c271f5c80e\", \"blink_common_unittests\": \"c5932bb1aa36bdbe3eccc06d6cc70df2efa902e5\", \"blink_fuzzer_unittests\": \"3e621b0be8936f0a447cd4cd5e64888633d26b12\", \"blink_heap_unittests\": \"2ddaaa445800b6eb247a7570395168967a9be5ed\", \"blink_platform_unittests\": \"76d60ab2cc3b85524cd28389bf8cb0d1ad1fbbd5\", \"blink_unittests\": \"f68bc0ad224ff970c0fe867dfa7079de46164999\", \"boringssl_crypto_tests\": \"88cbfece12d9cb6fc9b8ac4db1e012d8775d31a2\", \"boringssl_ssl_tests\": \"bd1f7c2582a548a6144caa5ea57061db5d3159d5\", \"browser_switcher_bho_unittests\": \"1ed50446122794a2fa7b2c38f63f8972cffd5b8c\", \"browser_tests\": \"ec9192389ccb06c3588f564df6d8155f519130dd\", \"capture_unittests\": \"605346b2c7e6dc9b6a2caaed0150db0de2b43a93\", \"cast_unittests\": \"d6c3f9cce250f160b40dfcfbc1c91c60124a348f\", \"cc_unittests\": \"d55af38b2c49d76e7f08e5efba087b63534a5574\", \"chrome_app_unittests\": \"6f973f2a7c430b62cb67e1049649c0ac23fd7f1b\", \"chrome_cleaner_unittests\": \"3f537172fb6446f3f452691d486cc1c973469521\", \"chrome_elf_unittests\": \"7d8c9a7d23ade551113d8234ba59ecc1e04267ea\", \"chromedriver_unittests\": \"92c036db900b6663f41fb9298b7965c4988fec82\", \"components_browsertests\": \"018a00a44a08666ee0d4310cab31e64a0019eeb7\", \"components_unittests\": \"45dee03d59591f2f710701498bc5e9f76af6a460\", \"compositor_unittests\": \"f885dab93c33ee6646acc75681d4f88bf0f51059\", \"content_browsertests\": \"5c6d6b79dd91cf23e7082b9551db9e97c0335aa1\", \"content_unittests\": \"64641b29b6afa72e6d06b4852de3a382d54eeb05\", \"courgette_unittests\": \"7d13ee8c04571136cc677c437e6d5ca9b3b07f6f\", \"crashpad_tests\": \"7d50ff645dde37d0f12477bec3b0ebc2ff9c6556\", \"cronet_tests\": \"9a2ce8a697496ad3fcd0c7357d08f4796320c41c\", \"cronet_unittests\": \"4a148f87d1453cc757af61d98b3cc30c5d6cc41c\", \"crypto_unittests\": \"6719b0c5b489d31b48daea1b5cdbee159cda404f\", \"delayloads_unittests\": \"db5b81784ef7faa0ee1f2ef1b8b2127d5e4c5231\", \"device_unittests\": \"f9a3361bb3ef4b11be87ddeec0708294a9451aa6\", \"display_unittests\": \"48e36a521c81286942c8f880fef43a49e6ba4777\", \"elevation_service_unittests\": \"c1619906096f977ecd22e385ba463c7fadcdb180\", \"events_unittests\": \"3b89cfc3303268f3a893d7ae4b0fd6755065703a\", \"extensions_browsertests\": \"ea16b3494dfad97a337aa0348e691d63a6e55fc9\", \"extensions_unittests\": \"05861a2a637771c1047b2391cb3c8342aa250eb7\", \"filesystem_service_unittests\": \"056340320eca3f9739cad04f49dae9cf335c66b7\", \"gcm_unit_tests\": \"8e28098e895246f8d09dd61723d49786dea497f2\", \"gcp_unittests\": \"fbfdcf169293b955be8d06f2324e67937f2da7e5\", \"gfx_unittests\": \"ea78ac04ab5f4208f55e011e0efbc9b58573c2f3\", \"gin_unittests\": \"423efac58365bece43917c4d0cfdb4ab20899643\", \"google_apis_unittests\": \"4b0f71e27d1ae8ba2e61d134f610ff60c20ad715\", \"gpu_unittests\": \"a8270457a6527cbe79bba9e2bb82c0a1a22bb2dd\", \"gwp_asan_unittests\": \"38a832f8d8d57c9f0f126591410bd172beca474d\", \"headless_browsertests\": \"e47c0994c8205a65510f0c04926fe022e6c5cddf\", \"headless_unittests\": \"74b9a6ddfaaa5737876ad5bd8deaf537d2a02c84\", \"install_static_unittests\": \"69d2cffbb392c81bd6141e9d66645a01b93f66bb\", \"installer_util_unittests\": \"75a5233c676ab816293005fefe1843664867899c\", \"interactive_ui_tests\": \"45e38d143041dc41c05a8aca09218bbab75c8b18\", \"ipc_tests\": \"b85f3053e75d959540a1f23ad2fcac415ffdea01\", \"jingle_unittests\": \"2ab56f48a9f1081932b5ceece05ede913230c135\", \"latency_unittests\": \"a52eaa20aa6a4718d7010eebb63f37c0c133cb5a\", \"libjingle_xmpp_unittests\": \"1b08636bc77c9f7b7932280b93f38f72c7560990\", \"liburlpattern_unittests\": \"9be2c250e7759e35a7fb76719aee63f52d108561\", \"media_blink_unittests\": \"f959717c49ef093a9d2d6337f277858d9d1bb3d9\", \"media_unittests\": \"f2ac9513f04002394a13f0424cd4f2092c5242e1\", \"message_center_unittests\": \"a6730850ae893ddf2c6cfd4698c0993381c2c28e\", \"midi_unittests\": \"a3a4f1a223c28d6734ed71a02aa0d935ec9aa952\", \"mojo_core_unittests\": \"e48c911080a6f5f560be8c1afea807a97a9275b0\", \"mojo_unittests\": \"b8e16afef4a7b10ead3bff9f89ada9d712c06e21\", \"nacl_loader_unittests\": \"4397db5f9cd52ffddf9f403a3a32215915b606eb\", \"native_theme_unittests\": \"5733d8be35da31eb4d371c5520cb706e8e0bb722\", \"net_unittests\": \"f25a0ab68735471605bc807c379e10ade0cf2b3b\", \"notification_helper_unittests\": \"cbb3c96971397d40e3a22fd968585a40245123f1\", \"pdf_unittests\": \"1573d3980f933bbe7aa3b19ae7b3e36d3b05a568\", \"perfetto_unittests\": \"c95d5f0a9c3aa8a18b77d8ad5835e9a6b6f25c35\", \"ppapi_unittests\": \"75595dee7f54fdd7e01fb9949f134a71f0762232\", \"printing_unittests\": \"ec3a007089eb00473cca6b37391a1b65837ec6fe\", \"remoting_unittests\": \"9499bf40bb1c3b6cdc409d285f2a40227ef730a8\", \"sbox_integration_tests\": \"dbceea529608fd7babb570c35b15b1cbfb279d3c\", \"sbox_unittests\": \"b80b87758729672cffa9968eeb2c811496263cea\", \"sbox_validation_tests\": \"6769eb943f975f2910eab84bf63d18e826b46922\", \"service_manager_unittests\": \"22dd36c766fe5919023ebe9bfc921c86201b310c\", \"services_unittests\": \"f69ed774f0246423c42a9f77329ffa9e9d153c9e\", \"setup_unittests\": \"ad9276c5f80cd9268c307f26c9b3d96c111085d4\", \"shell_dialogs_unittests\": \"5a28a62dbf02e77d33b01683600b8ba6cec9be05\", \"skia_unittests\": \"d9e0b36aeb1a6d5f1866db2fd0fa7a74e78343b0\", \"snapshot_unittests\": \"2f1c8645703197d03bf3c72176c5160f6ddab1f3\", \"sql_unittests\": \"37c52cd19638482955dd59cf4266d5d6cdb033e9\", \"storage_unittests\": \"5df9b117d944acebae9729dee144d12d2b9a9033\", \"sync_integration_tests\": \"6e305dc74eaff50d88779b6299e9fa0a58b434f9\", \"traffic_annotation_auditor_unittests\": \"ebd284495324c2bff70aaa79502b39da6769d3c0\", \"ui_base_unittests\": \"b1f4fe3b2ae797e250c6976c20ba78ebaa6a7635\", \"ui_touch_selection_unittests\": \"4e093c29b9de70205a7e55bfb68f54a0d6135be7\", \"unit_tests\": \"9a3325e402b05c6251e56e48953d2c4ec0ab3eac\", \"updater_tests\": \"97c36a335b8e1e10a7c8240940c4d8e3c3770abf\", \"url_unittests\": \"acb7805b970e463382afbcbc7aa13c8b644b97cf\", \"views_unittests\": \"ebe4e38353784bead1be31de7735c2580dec5d4d\", \"viz_unittests\": \"03a219d25b8e44ce9dc5bfb51219f335a72cb150\", \"vr_common_unittests\": \"54c494fa8ba7892c91ec3b5f52a5e8a087354c76\", \"vr_pixeltests\": \"7e553802a41982824c2fc8a505385c04568b87d5\", \"weblayer_browsertests\": \"82ef540df1b5533c38984bf1e21ea729c74b21af\", \"weblayer_unittests\": \"f9a54baaef88fdcbad934a062cb46630740d7410\", \"wm_unittests\": \"2bc72577a6f9171abd1a7e95e44e51a31e4a91e1\", \"wtf_unittests\": \"6183ad331123d27096c9595d69f5ff5ff6c358d6\", \"zlib_unittests\": \"68dcde7ea2dc3f534e393188e9561c32ecd644c8\", \"zucchini_unittests\": \"944d862ac9b593887a7d63a508f6d5ea9e14c8e6\"}}, \"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-5-387x\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"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\": [\"5614-d862eaa\"], \"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": "1604250989711364",
"status": "COMPLETED",
"status_changed_ts": "1604254892650797",
"tags": [
"build_address:luci.chromium.ci/win-asan/15397",
"builder:win-asan",
"buildset:commit/git/ff0230804ed267fc6638e20d9df1846370bde825",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ff0230804ed267fc6638e20d9df1846370bde825",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030244694007232240",
"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/8864814631917172336/+/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:4f9da0f95369a510",
"user_agent:luci-scheduler"
],
"updated_ts": "1604254892662370",
"url": "https://ci.chromium.org/b/8864814631917172336",
"utcnow_ts": "1604254893665224"
}
}
[18:21:33.675] Invocation finished in 1h5m7.005641343s with status SUCCEEDED