[02:48:23.189] New invocation is queued and will start shortly
[02:48:24.289] Starting the invocation (attempt 1)
[02:48:24.328] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:48:24.328] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:48:24.328] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019427671542834880",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"72a3f3a08fee7e9369b350af453770a688f0b1d0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019427671542834880",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/72a3f3a08fee7e9369b350af453770a688f0b1d0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/72a3f3a08fee7e9369b350af453770a688f0b1d0",
"gitiles_ref:refs/heads/master"
]
}
[02:48:25.082] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614566904432750",
"id": "8853997609576040464",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"72a3f3a08fee7e9369b350af453770a688f0b1d0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614566904973030",
"tags": [
"build_address:luci.chromium.ci/win-asan/17566",
"builder:win-asan",
"buildset:commit/git/72a3f3a08fee7e9369b350af453770a688f0b1d0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/72a3f3a08fee7e9369b350af453770a688f0b1d0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019427671542834880",
"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/8853997609576040464/+/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": "1614566904973139",
"url": "https://ci.chromium.org/b/8853997609576040464",
"utcnow_ts": "1614566905073169"
}
}
[02:48:25.082] Task URL: https://ci.chromium.org/b/8853997609576040464
[02:48:25.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:2:0) after 1m0s
[02:48:27.324] Received PubSub notification, asking Buildbucket for the build status
[02:48:27.392] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:25.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:2:0)
[02:49:25.259] Timer tick, asking Buildbucket for the build status
[02:49:25.325] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:25.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:4:0) after 1m0s
[02:50:25.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:4:0)
[02:50:25.450] Timer tick, asking Buildbucket for the build status
[02:50:25.530] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:25.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:5:0) after 1m0s
[02:51:25.600] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:5:0)
[02:51:25.600] Timer tick, asking Buildbucket for the build status
[02:51:25.717] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:25.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:6:0) after 1m0s
[02:52:25.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:6:0)
[02:52:25.733] Timer tick, asking Buildbucket for the build status
[02:52:26.032] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:26.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:7:0) after 1m0s
[02:53:26.160] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:7:0)
[02:53:26.160] Timer tick, asking Buildbucket for the build status
[02:53:26.312] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:26.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:8:0) after 1m0s
[02:54:26.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:8:0)
[02:54:26.405] Timer tick, asking Buildbucket for the build status
[02:54:26.609] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:26.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:9:0) after 1m0s
[02:55:26.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:9:0)
[02:55:26.627] Timer tick, asking Buildbucket for the build status
[02:55:26.715] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:26.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:10:0) after 1m0s
[02:56:26.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:10:0)
[02:56:26.989] Timer tick, asking Buildbucket for the build status
[02:56:27.136] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:27.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:11:0) after 1m0s
[02:57:27.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:11:0)
[02:57:27.150] Timer tick, asking Buildbucket for the build status
[02:57:27.229] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:27.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:12:0) after 1m0s
[02:58:27.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:12:0)
[02:58:27.266] Timer tick, asking Buildbucket for the build status
[02:58:27.368] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:27.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:13:0) after 1m0s
[02:59:27.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:13:0)
[02:59:27.389] Timer tick, asking Buildbucket for the build status
[02:59:27.487] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:27.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:14:0) after 1m0s
[03:00:27.514] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:14:0)
[03:00:27.514] Timer tick, asking Buildbucket for the build status
[03:00:27.789] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:27.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:15:0) after 1m0s
[03:01:27.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:15:0)
[03:01:27.802] Timer tick, asking Buildbucket for the build status
[03:01:28.149] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:28.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:16:0) after 1m0s
[03:02:28.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:16:0)
[03:02:28.272] Timer tick, asking Buildbucket for the build status
[03:02:28.355] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:28.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:17:0) after 1m0s
[03:03:28.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:17:0)
[03:03:28.371] Timer tick, asking Buildbucket for the build status
[03:03:28.519] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:28.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:18:0) after 1m0s
[03:04:28.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:18:0)
[03:04:28.553] Timer tick, asking Buildbucket for the build status
[03:04:28.650] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:28.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:19:0) after 1m0s
[03:05:28.754] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:19:0)
[03:05:28.754] Timer tick, asking Buildbucket for the build status
[03:05:28.971] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:28.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:20:0) after 1m0s
[03:06:28.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:20:0)
[03:06:28.988] Timer tick, asking Buildbucket for the build status
[03:06:29.092] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:29.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:21:0) after 1m0s
[03:07:29.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:21:0)
[03:07:29.138] Timer tick, asking Buildbucket for the build status
[03:07:29.268] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:29.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:22:0) after 1m0s
[03:08:29.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:22:0)
[03:08:29.298] Timer tick, asking Buildbucket for the build status
[03:08:29.395] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:29.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:23:0) after 1m0s
[03:09:30.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:23:0)
[03:09:30.168] Timer tick, asking Buildbucket for the build status
[03:09:30.238] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:30.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:24:0) after 1m0s
[03:10:30.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:24:0)
[03:10:30.359] Timer tick, asking Buildbucket for the build status
[03:10:30.456] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:30.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:25:0) after 1m0s
[03:11:30.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:25:0)
[03:11:30.572] Timer tick, asking Buildbucket for the build status
[03:11:30.654] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:30.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:26:0) after 1m0s
[03:12:30.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:26:0)
[03:12:30.897] Timer tick, asking Buildbucket for the build status
[03:12:30.984] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:30.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:27:0) after 1m0s
[03:13:31.344] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:27:0)
[03:13:31.344] Timer tick, asking Buildbucket for the build status
[03:13:31.473] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:31.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:28:0) after 1m0s
[03:14:31.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:28:0)
[03:14:31.593] Timer tick, asking Buildbucket for the build status
[03:14:31.686] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:31.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:29:0) after 1m0s
[03:15:31.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:29:0)
[03:15:31.826] Timer tick, asking Buildbucket for the build status
[03:15:32.012] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:32.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:30:0) after 1m0s
[03:16:32.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:30:0)
[03:16:32.136] Timer tick, asking Buildbucket for the build status
[03:16:32.213] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:32.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:31:0) after 1m0s
[03:17:32.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:31:0)
[03:17:32.465] Timer tick, asking Buildbucket for the build status
[03:17:32.553] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:32.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:32:0) after 1m0s
[03:18:32.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:32:0)
[03:18:32.704] Timer tick, asking Buildbucket for the build status
[03:18:32.829] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:32.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:33:0) after 1m0s
[03:19:33.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:33:0)
[03:19:33.058] Timer tick, asking Buildbucket for the build status
[03:19:33.175] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:33.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:34:0) after 1m0s
[03:20:33.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:34:0)
[03:20:33.379] Timer tick, asking Buildbucket for the build status
[03:20:33.531] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:33.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:35:0) after 1m0s
[03:21:33.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:35:0)
[03:21:33.676] Timer tick, asking Buildbucket for the build status
[03:21:33.772] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:33.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:36:0) after 1m0s
[03:22:33.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:36:0)
[03:22:33.909] Timer tick, asking Buildbucket for the build status
[03:22:34.028] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:34.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:37:0) after 1m0s
[03:23:34.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:37:0)
[03:23:34.127] Timer tick, asking Buildbucket for the build status
[03:23:34.273] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:34.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:38:0) after 1m0s
[03:24:34.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:38:0)
[03:24:34.359] Timer tick, asking Buildbucket for the build status
[03:24:34.683] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:34.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:39:0) after 1m0s
[03:25:34.819] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:39:0)
[03:25:34.819] Timer tick, asking Buildbucket for the build status
[03:25:35.289] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:35.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:40:0) after 1m0s
[03:26:35.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:40:0)
[03:26:35.360] Timer tick, asking Buildbucket for the build status
[03:26:35.437] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:35.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:41:0) after 1m0s
[03:27:35.423] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:41:0)
[03:27:35.423] Timer tick, asking Buildbucket for the build status
[03:27:35.508] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:35.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:42:0) after 1m0s
[03:28:35.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:42:0)
[03:28:35.524] Timer tick, asking Buildbucket for the build status
[03:28:35.817] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:35.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:43:0) after 1m0s
[03:29:35.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:43:0)
[03:29:35.898] Timer tick, asking Buildbucket for the build status
[03:29:36.002] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:36.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:44:0) after 1m0s
[03:30:36.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:44:0)
[03:30:36.056] Timer tick, asking Buildbucket for the build status
[03:30:36.413] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:36.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:45:0) after 1m0s
[03:31:36.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:45:0)
[03:31:36.436] Timer tick, asking Buildbucket for the build status
[03:31:36.697] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:36.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:46:0) after 1m0s
[03:32:36.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:46:0)
[03:32:36.771] Timer tick, asking Buildbucket for the build status
[03:32:36.863] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:36.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:47:0) after 1m0s
[03:33:36.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:47:0)
[03:33:36.958] Timer tick, asking Buildbucket for the build status
[03:33:37.140] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:37.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:48:0) after 1m0s
[03:34:37.213] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:48:0)
[03:34:37.213] Timer tick, asking Buildbucket for the build status
[03:34:37.303] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:37.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:49:0) after 1m0s
[03:35:37.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:49:0)
[03:35:37.330] Timer tick, asking Buildbucket for the build status
[03:35:37.554] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:37.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:50:0) after 1m0s
[03:36:37.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:50:0)
[03:36:37.622] Timer tick, asking Buildbucket for the build status
[03:36:37.714] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:37.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:51:0) after 1m0s
[03:37:37.799] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:51:0)
[03:37:37.799] Timer tick, asking Buildbucket for the build status
[03:37:37.878] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:37.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:52:0) after 1m0s
[03:38:37.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:52:0)
[03:38:37.957] Timer tick, asking Buildbucket for the build status
[03:38:38.119] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:38.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:53:0) after 1m0s
[03:39:38.114] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:53:0)
[03:39:38.114] Timer tick, asking Buildbucket for the build status
[03:39:38.275] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:38.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:54:0) after 1m0s
[03:40:38.342] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:54:0)
[03:40:38.342] Timer tick, asking Buildbucket for the build status
[03:40:38.425] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:38.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:55:0) after 1m0s
[03:41:38.441] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:55:0)
[03:41:38.441] Timer tick, asking Buildbucket for the build status
[03:41:38.650] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:38.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:56:0) after 1m0s
[03:42:38.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:56:0)
[03:42:38.708] Timer tick, asking Buildbucket for the build status
[03:42:38.794] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:38.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:57:0) after 1m0s
[03:43:38.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:57:0)
[03:43:38.859] Timer tick, asking Buildbucket for the build status
[03:43:38.975] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:38.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:58:0) after 1m0s
[03:44:42.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:58:0)
[03:44:42.517] Timer tick, asking Buildbucket for the build status
[03:44:42.723] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:42.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:59:0) after 1m0s
[03:45:42.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:59:0)
[03:45:42.877] Timer tick, asking Buildbucket for the build status
[03:45:43.004] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:43.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:60:0) after 1m0s
[03:46:43.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:60:0)
[03:46:43.154] Timer tick, asking Buildbucket for the build status
[03:46:43.273] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:43.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:61:0) after 1m0s
[03:47:43.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:61:0)
[03:47:43.457] Timer tick, asking Buildbucket for the build status
[03:47:43.737] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:43.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:62:0) after 1m0s
[03:48:43.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:62:0)
[03:48:43.789] Timer tick, asking Buildbucket for the build status
[03:48:44.015] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:44.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:63:0) after 1m0s
[03:49:44.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:63:0)
[03:49:44.802] Timer tick, asking Buildbucket for the build status
[03:49:45.113] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:45.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:64:0) after 1m0s
[03:50:45.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:64:0)
[03:50:45.113] Timer tick, asking Buildbucket for the build status
[03:50:45.281] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:45.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:65:0) after 1m0s
[03:51:45.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:65:0)
[03:51:45.624] Timer tick, asking Buildbucket for the build status
[03:51:45.888] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:45.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:66:0) after 1m0s
[03:52:45.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:66:0)
[03:52:45.962] Timer tick, asking Buildbucket for the build status
[03:52:46.041] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:46.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:67:0) after 1m0s
[03:53:46.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:67:0)
[03:53:46.089] Timer tick, asking Buildbucket for the build status
[03:53:46.202] Build 8853997609576040464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:46.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019427671542834880:68:0) after 1m0s
[03:54:37.038] Received PubSub notification, asking Buildbucket for the build status
[03:54:37.137] Build 8853997609576040464: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:54:37.137] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614570875723318",
"created_by": "project:chromium",
"created_ts": "1614566904432750",
"id": "8853997609576040464",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"72a3f3a08fee7e9369b350af453770a688f0b1d0\"}}",
"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\": \"72a3f3a08fee7e9369b350af453770a688f0b1d0\", \"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\": 17566, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"a6d4623970eda91638bd3c3638c9104bffcaa316\", \"got_dawn_revision\": \"329c1c98fef964ea532a0579ae6f2553ff3382c5\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"72a3f3a08fee7e9369b350af453770a688f0b1d0\", \"got_revision_cp\": \"refs/heads/master@{#858461}\", \"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\": \"72a3f3a08fee7e9369b350af453770a688f0b1d0\", \"swarm_hashes_refs/heads/master(at){#858461}_without_patch\": {\"absl_hardening_tests\": \"f5e1ce4ad7e40415ddea9e8ef815f5b57a0d2c18\", \"accessibility_unittests\": \"05317cf343633c61441eeb311f11a4a6e733321b\", \"angle_unittests\": \"4eb07e3ff199dd50f11dd697d0a681c033b02a81\", \"app_shell_unittests\": \"c358f2ce225734f4ae5fb936122ad9e461492271\", \"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\": \"0f2ed0f802e4cfd4b86e890e9232faf211661369\", \"capture_unittests\": \"45fa9ac6c0240cb7e0d9f1635d3fa0168a4c12fb\", \"cast_unittests\": \"1f4e38ba7e0e5a00c0eaeeae466fad6dcc418f36\", \"cc_unittests\": \"c278e9abef3a6868fa88b49ef7a286cb5312496a\", \"chrome_app_unittests\": \"27f645b76d646b317078172e6fc8c60c473a56d7\", \"chrome_cleaner_unittests\": \"45a6b57bc14ef7480fba5e1d4e677bbcd4d1827a\", \"chrome_elf_unittests\": \"048783036b3fedcc487baf11ea97ede42f1e7b8f\", \"chromedriver_unittests\": \"94434b16065b82df221c6bac45a73c18f49894f8\", \"color_unittests\": \"dc68095caa4a37e03b09dc30a30728207dce598e\", \"components_browsertests\": \"27e8530bdb18c0d29a863a8b0d11e99c3b6a4da2\", \"components_unittests\": \"7a3ff3d35b1b84498c161e98f62d1ea68db2fbbb\", \"compositor_unittests\": \"3298cb5a52294438787c8924821ec2131b27fc51\", \"content_browsertests\": \"84f8dbaeb031002cfc343b6b9ee3ba98ab5d998e\", \"content_unittests\": \"125543912735f152d2aaf84fa1c9a3a671fabe74\", \"courgette_unittests\": \"59c59bab3c51ca8e94da45218a5dde1d2ef5ab67\", \"crashpad_tests\": \"1b9426b7d4fc6473e0a161573de9a49794f93a07\", \"cronet_tests\": \"9f15a2df98a7a1f60fa5d4ab2c729b8300292474\", \"cronet_unittests\": \"7ef5ff1a22d69c992d1a836b55e33b67c07289f2\", \"crypto_unittests\": \"cddc7164b413377d2e880e5ca5487d9c924cb581\", \"delayloads_unittests\": \"f2c6ae127be298bee4f4a7b2b1dfd460e1c48176\", \"device_unittests\": \"424b048b35f0ccd8f4e012697ddcb02dc3eff2d2\", \"display_unittests\": \"443c6de642bf566bdcf8fbfe0f9b98f49548b7e5\", \"elevation_service_unittests\": \"f771ac132dd84556e095a3c81678f355325bfc61\", \"events_unittests\": \"0423cb1f6e50c2c915f376f228e34c0f1e952337\", \"extensions_browsertests\": \"79fd478a942591eb595c2e6ba06ce01966354a6e\", \"extensions_unittests\": \"03df15a3cadbc2a58ef3df829e116828819e7530\", \"filesystem_service_unittests\": \"f81ed029515b192d62855f5128d6f4dafde23ae3\", \"gcm_unit_tests\": \"79c562273c7486685050f67e91e420adbae5ab56\", \"gcp_unittests\": \"6f1a2de21715b5322ad29ba0bf2cda7b5f68cefa\", \"gfx_unittests\": \"6fbd115af57ba8a5251733894ebe27cd249bab80\", \"gin_unittests\": \"15e9e19310b2e9e4d3570b5c40b1de28de387dbf\", \"google_apis_unittests\": \"355dcdab285eb6c2172bec93d629fd9433d6a2d6\", \"gpu_unittests\": \"8d10b8ed2cea12cc9e0265e05eef53cad40c6778\", \"gwp_asan_unittests\": \"6deb2b30fcdd3958858f8ec97653377a5dca3a2f\", \"headless_browsertests\": \"8ad19e11d22ea171476bd484078ee4ef78e43a4e\", \"headless_unittests\": \"d9225fccb7226fd0d71e79721fd876120dc4fffa\", \"install_static_unittests\": \"cf4b93be4d79f37bbec3ce30e482c81040b9ff3e\", \"installer_util_unittests\": \"7c0fe5aa23d389fba6cac4a431cf3d8cf5b18b3d\", \"interactive_ui_tests\": \"acac6880ff7cf381192ca5ac2735d61a0a0be3a1\", \"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\": \"ec9ffb2858d3bec793fdf1ccdbec00ba4e908b68\", \"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\": \"aa26bc213b5f11e7d9dcd0c316cc548532dfe98f\", \"traffic_annotation_auditor_unittests\": \"db28e429fb6781c752c695f797614042bcddc7b7\", \"ui_base_unittests\": \"8fc642e8f84334dbeec28cba20d9bd2cc72bf910\", \"ui_touch_selection_unittests\": \"9de4eded2b237cb5c9de9ca486f23e9aa181772b\", \"unit_tests\": \"c8cdd2ced32d7c930d02091d79fabced50c41bd8\", \"updater_tests\": \"8fde6b8cc6d94878b253c50f2e43aa673142b52f\", \"url_unittests\": \"f6476b583e5fea9211322a5ee9ea31c836017311\", \"views_unittests\": \"f7bd5b7ce057a45986b54859fc2c7ba909779096\", \"viz_unittests\": \"4d6cc978002a0e595acb6884c55bd6edbea13376\", \"vr_common_unittests\": \"9cf26f7d942071cce490cf3a9666a340beb8c4ee\", \"vr_pixeltests\": \"b3fe4955ed4516e34d04c1b422664a77d75cc9f0\", \"weblayer_browsertests\": \"545bfd86d8734fa702f48164c4f25bd1d7672fee\", \"weblayer_unittests\": \"4a07767c37e6a99143c542ff7def1c7a3cdd4eca\", \"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": "1614566906837833",
"status": "COMPLETED",
"status_changed_ts": "1614570876047792",
"tags": [
"build_address:luci.chromium.ci/win-asan/17566",
"builder:win-asan",
"buildset:commit/git/72a3f3a08fee7e9369b350af453770a688f0b1d0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/72a3f3a08fee7e9369b350af453770a688f0b1d0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019427671542834880",
"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/8853997609576040464/+/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:5204815d4011a310",
"user_agent:luci-scheduler"
],
"updated_ts": "1614570876051998",
"url": "https://ci.chromium.org/b/8853997609576040464",
"utcnow_ts": "1614570877125594"
}
}
[03:54:37.137] Invocation finished in 1h6m13.96370706s with status SUCCEEDED