[19:11:21.011] New invocation is queued and will start shortly
[19:11:22.140] Starting the invocation (attempt 1)
[19:11:22.175] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:11:22.175] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:11:22.175] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021540156007533952",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"dfe1c7846332b81fa7cc1ca3d1c7a2b857e06637\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021540156007533952",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/dfe1c7846332b81fa7cc1ca3d1c7a2b857e06637",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dfe1c7846332b81fa7cc1ca3d1c7a2b857e06637",
"gitiles_ref:refs/heads/master"
]
}
[19:11:22.810] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612552282303879",
"id": "8856110093989716608",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dfe1c7846332b81fa7cc1ca3d1c7a2b857e06637\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612552282707521",
"tags": [
"build_address:luci.chromium.ci/win-asan/17185",
"builder:win-asan",
"buildset:commit/git/dfe1c7846332b81fa7cc1ca3d1c7a2b857e06637",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dfe1c7846332b81fa7cc1ca3d1c7a2b857e06637",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021540156007533952",
"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/8856110093989716608/+/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": "1612552282707629",
"url": "https://ci.chromium.org/b/8856110093989716608",
"utcnow_ts": "1612552282802752"
}
}
[19:11:22.810] Task URL: https://ci.chromium.org/b/8856110093989716608
[19:11:22.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:2:0) after 1m0s
[19:11:26.169] Received PubSub notification, asking Buildbucket for the build status
[19:11:26.258] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:22.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:2:0)
[19:12:22.967] Timer tick, asking Buildbucket for the build status
[19:12:23.212] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:23.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:4:0) after 1m0s
[19:13:23.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:4:0)
[19:13:23.332] Timer tick, asking Buildbucket for the build status
[19:13:23.509] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:23.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:5:0) after 1m0s
[19:14:23.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:5:0)
[19:14:23.601] Timer tick, asking Buildbucket for the build status
[19:14:23.943] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:23.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:6:0) after 1m0s
[19:15:23.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:6:0)
[19:15:23.987] Timer tick, asking Buildbucket for the build status
[19:15:24.215] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:24.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:7:0) after 1m0s
[19:16:24.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:7:0)
[19:16:24.284] Timer tick, asking Buildbucket for the build status
[19:16:24.427] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:24.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:8:0) after 1m0s
[19:17:24.559] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:8:0)
[19:17:24.559] Timer tick, asking Buildbucket for the build status
[19:17:24.698] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:24.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:9:0) after 1m0s
[19:18:24.739] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:9:0)
[19:18:24.739] Timer tick, asking Buildbucket for the build status
[19:18:24.899] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:24.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:10:0) after 1m0s
[19:19:24.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:10:0)
[19:19:24.965] Timer tick, asking Buildbucket for the build status
[19:19:25.140] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:25.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:11:0) after 1m0s
[19:20:25.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:11:0)
[19:20:25.133] Timer tick, asking Buildbucket for the build status
[19:20:25.416] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:25.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:12:0) after 1m0s
[19:21:25.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:12:0)
[19:21:25.487] Timer tick, asking Buildbucket for the build status
[19:21:26.106] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:26.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:13:0) after 1m0s
[19:22:26.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:13:0)
[19:22:26.324] Timer tick, asking Buildbucket for the build status
[19:22:26.499] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:26.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:14:0) after 1m0s
[19:23:26.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:14:0)
[19:23:26.643] Timer tick, asking Buildbucket for the build status
[19:23:26.829] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:26.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:15:0) after 1m0s
[19:24:26.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:15:0)
[19:24:26.853] Timer tick, asking Buildbucket for the build status
[19:24:26.916] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:26.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:16:0) after 1m0s
[19:25:26.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:16:0)
[19:25:26.968] Timer tick, asking Buildbucket for the build status
[19:25:27.407] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:27.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:17:0) after 1m0s
[19:26:27.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:17:0)
[19:26:27.556] Timer tick, asking Buildbucket for the build status
[19:26:27.737] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:27.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:18:0) after 1m0s
[19:27:28.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:18:0)
[19:27:28.036] Timer tick, asking Buildbucket for the build status
[19:27:28.171] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:28.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:19:0) after 1m0s
[19:28:28.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:19:0)
[19:28:28.272] Timer tick, asking Buildbucket for the build status
[19:28:28.354] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:28.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:20:0) after 1m0s
[19:29:28.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:20:0)
[19:29:28.389] Timer tick, asking Buildbucket for the build status
[19:29:28.649] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:28.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:21:0) after 1m0s
[19:30:28.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:21:0)
[19:30:28.705] Timer tick, asking Buildbucket for the build status
[19:30:28.924] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:28.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:22:0) after 1m0s
[19:31:29.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:22:0)
[19:31:29.012] Timer tick, asking Buildbucket for the build status
[19:31:29.127] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:29.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:23:0) after 1m0s
[19:32:29.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:23:0)
[19:32:29.215] Timer tick, asking Buildbucket for the build status
[19:32:29.311] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:29.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:24:0) after 1m0s
[19:33:29.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:24:0)
[19:33:29.336] Timer tick, asking Buildbucket for the build status
[19:33:29.606] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:29.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:25:0) after 1m0s
[19:34:29.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:25:0)
[19:34:29.675] Timer tick, asking Buildbucket for the build status
[19:34:29.803] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:29.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:26:0) after 1m0s
[19:35:29.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:26:0)
[19:35:29.853] Timer tick, asking Buildbucket for the build status
[19:35:30.133] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:30.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:27:0) after 1m0s
[19:36:30.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:27:0)
[19:36:30.218] Timer tick, asking Buildbucket for the build status
[19:36:30.337] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:30.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:28:0) after 1m0s
[19:37:30.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:28:0)
[19:37:30.403] Timer tick, asking Buildbucket for the build status
[19:37:30.612] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:30.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:29:0) after 1m0s
[19:38:30.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:29:0)
[19:38:30.621] Timer tick, asking Buildbucket for the build status
[19:38:30.778] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:30.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:30:0) after 1m0s
[19:39:30.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:30:0)
[19:39:30.780] Timer tick, asking Buildbucket for the build status
[19:39:30.910] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:30.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:31:0) after 1m0s
[19:40:30.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:31:0)
[19:40:30.936] Timer tick, asking Buildbucket for the build status
[19:40:31.055] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:31.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:32:0) after 1m0s
[19:41:31.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:32:0)
[19:41:31.171] Timer tick, asking Buildbucket for the build status
[19:41:31.437] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:31.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:33:0) after 1m0s
[19:42:31.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:33:0)
[19:42:31.483] Timer tick, asking Buildbucket for the build status
[19:42:31.604] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:31.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:34:0) after 1m0s
[19:43:31.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:34:0)
[19:43:31.818] Timer tick, asking Buildbucket for the build status
[19:43:31.932] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:31.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:35:0) after 1m0s
[19:44:31.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:35:0)
[19:44:31.946] Timer tick, asking Buildbucket for the build status
[19:44:32.706] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:32.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:36:0) after 1m0s
[19:45:32.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:36:0)
[19:45:32.753] Timer tick, asking Buildbucket for the build status
[19:45:33.391] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:33.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:37:0) after 1m0s
[19:46:33.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:37:0)
[19:46:33.409] Timer tick, asking Buildbucket for the build status
[19:46:33.518] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:33.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:38:0) after 1m0s
[19:47:33.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:38:0)
[19:47:33.536] Timer tick, asking Buildbucket for the build status
[19:47:33.650] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:33.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:39:0) after 1m0s
[19:48:33.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:39:0)
[19:48:33.647] Timer tick, asking Buildbucket for the build status
[19:48:33.720] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:33.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:40:0) after 1m0s
[19:49:33.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:40:0)
[19:49:33.781] Timer tick, asking Buildbucket for the build status
[19:49:33.864] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:33.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:41:0) after 1m0s
[19:50:33.970] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:41:0)
[19:50:33.970] Timer tick, asking Buildbucket for the build status
[19:50:34.510] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:34.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:42:0) after 1m0s
[19:51:34.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:42:0)
[19:51:34.587] Timer tick, asking Buildbucket for the build status
[19:51:34.804] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:34.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:43:0) after 1m0s
[19:52:34.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:43:0)
[19:52:34.810] Timer tick, asking Buildbucket for the build status
[19:52:34.930] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:34.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:44:0) after 1m0s
[19:53:34.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:44:0)
[19:53:34.986] Timer tick, asking Buildbucket for the build status
[19:53:35.211] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:35.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:45:0) after 1m0s
[19:54:35.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:45:0)
[19:54:35.234] Timer tick, asking Buildbucket for the build status
[19:54:35.310] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:35.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:46:0) after 1m0s
[19:55:35.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:46:0)
[19:55:35.324] Timer tick, asking Buildbucket for the build status
[19:55:35.385] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:35.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:47:0) after 1m0s
[19:56:35.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:47:0)
[19:56:35.388] Timer tick, asking Buildbucket for the build status
[19:56:35.839] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:35.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:48:0) after 1m0s
[19:57:35.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:48:0)
[19:57:35.883] Timer tick, asking Buildbucket for the build status
[19:57:36.048] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:36.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:49:0) after 1m0s
[19:58:36.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:49:0)
[19:58:36.063] Timer tick, asking Buildbucket for the build status
[19:58:36.247] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:36.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:50:0) after 1m0s
[19:59:36.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:50:0)
[19:59:36.268] Timer tick, asking Buildbucket for the build status
[19:59:36.484] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:36.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:51:0) after 1m0s
[20:00:36.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:51:0)
[20:00:36.631] Timer tick, asking Buildbucket for the build status
[20:00:36.723] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:36.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:52:0) after 1m0s
[20:01:36.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:52:0)
[20:01:36.763] Timer tick, asking Buildbucket for the build status
[20:01:36.948] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:36.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:53:0) after 1m0s
[20:02:36.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:53:0)
[20:02:36.977] Timer tick, asking Buildbucket for the build status
[20:02:37.136] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:37.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:54:0) after 1m0s
[20:03:37.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:54:0)
[20:03:37.206] Timer tick, asking Buildbucket for the build status
[20:03:37.421] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:37.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:55:0) after 1m0s
[20:04:37.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:55:0)
[20:04:37.522] Timer tick, asking Buildbucket for the build status
[20:04:37.819] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:37.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:56:0) after 1m0s
[20:05:37.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:56:0)
[20:05:37.916] Timer tick, asking Buildbucket for the build status
[20:05:37.994] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:37.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:57:0) after 1m0s
[20:06:38.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:57:0)
[20:06:38.168] Timer tick, asking Buildbucket for the build status
[20:06:38.534] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:38.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:58:0) after 1m0s
[20:07:38.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:58:0)
[20:07:38.598] Timer tick, asking Buildbucket for the build status
[20:07:39.516] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:39.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:59:0) after 1m0s
[20:08:39.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:59:0)
[20:08:39.603] Timer tick, asking Buildbucket for the build status
[20:08:39.818] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:39.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:60:0) after 1m0s
[20:09:39.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:60:0)
[20:09:39.824] Timer tick, asking Buildbucket for the build status
[20:09:40.165] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:40.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:61:0) after 1m0s
[20:10:40.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:61:0)
[20:10:40.235] Timer tick, asking Buildbucket for the build status
[20:10:40.396] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:40.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:62:0) after 1m0s
[20:11:40.431] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:62:0)
[20:11:40.431] Timer tick, asking Buildbucket for the build status
[20:11:41.060] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:41.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:63:0) after 1m0s
[20:12:41.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:63:0)
[20:12:41.078] Timer tick, asking Buildbucket for the build status
[20:12:41.160] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:41.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:64:0) after 1m0s
[20:13:41.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:64:0)
[20:13:41.203] Timer tick, asking Buildbucket for the build status
[20:13:41.274] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:41.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:65:0) after 1m0s
[20:14:41.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:65:0)
[20:14:41.287] Timer tick, asking Buildbucket for the build status
[20:14:41.600] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:41.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:66:0) after 1m0s
[20:15:41.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:66:0)
[20:15:41.605] Timer tick, asking Buildbucket for the build status
[20:15:41.716] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:41.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:67:0) after 1m0s
[20:16:41.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:67:0)
[20:16:41.743] Timer tick, asking Buildbucket for the build status
[20:16:41.857] Build 8856110093989716608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:41.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021540156007533952:68:0) after 1m0s
[20:17:28.118] Received PubSub notification, asking Buildbucket for the build status
[20:17:28.687] Build 8856110093989716608: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:17:28.687] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612556247167725",
"created_by": "project:chromium",
"created_ts": "1612552282303879",
"id": "8856110093989716608",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dfe1c7846332b81fa7cc1ca3d1c7a2b857e06637\"}}",
"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\": \"dfe1c7846332b81fa7cc1ca3d1c7a2b857e06637\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-0-be5b\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17185, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"b2de0688d7c95c58b4bf1182f53bf43e42a2d99c\", \"got_dawn_revision\": \"185c6a5b0f358c115e3d3ef43afbb157d4c4b591\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"dfe1c7846332b81fa7cc1ca3d1c7a2b857e06637\", \"got_revision_cp\": \"refs/heads/master@{#851222}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a8da847d13385bb1c4322ae3c456e4896976c93b\", \"got_v8_revision\": \"bf60416e680c7eab4f2dd7589563f2145fbfdd0a\", \"got_v8_revision_cp\": \"refs/heads/9.0.136@{#1}\", \"got_webrtc_revision\": \"1184b5537fdc37535cb3d099db6a49a2b759f20b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33176}\", \"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\": \"dfe1c7846332b81fa7cc1ca3d1c7a2b857e06637\", \"swarm_hashes_refs/heads/master(at){#851222}_without_patch\": {\"absl_hardening_tests\": \"15e23a031634774cf971990ff05b2a595b8aee70\", \"accessibility_unittests\": \"dff4d13a3cbc25f0e33507f2cc2b5811c39293e6\", \"angle_unittests\": \"f8953f84dd71569f13d24e741e256b98c1863d5a\", \"app_shell_unittests\": \"2d4ea332690ceb4c05c5e67b62fca2810d8ab86c\", \"aura_unittests\": \"ae542b9b50e3e0a5e6c5d7f49f31de4a715a3b45\", \"base_unittests\": \"e95dc519b1424e893deefe0d01e6001d0511484f\", \"base_util_unittests\": \"515727837c819f38f70384b2579aee0ca04438f8\", \"blink_common_unittests\": \"1e31c00194bc5c259d1ea09359632967b0bb1e99\", \"blink_fuzzer_unittests\": \"1cd5cda3c2f9c9319cb85a7691638115ad333e39\", \"blink_heap_unittests\": \"e419d89946146c00ee16742a1a5a5f8cf6ad1685\", \"blink_platform_unittests\": \"6f134983cf0f793b5b7c9e906ba81a9313948e4a\", \"blink_unittests\": \"1a12c16dc260a16d29aae0d49d73ba991db4eb7a\", \"boringssl_crypto_tests\": \"38b5bffc3767aff6a96d115ca898dc5e455ac675\", \"boringssl_ssl_tests\": \"d4c1e9cabfd1b61e50f41c93b74f09d2f7cd05a1\", \"browser_switcher_bho_unittests\": \"ec21494dbb83c7eb164ef86d8df9d96583c9bf0d\", \"browser_tests\": \"df27b83939687c70a8af754b6a064220be1cc83b\", \"capture_unittests\": \"1ee67fcd53063e0923766cb66ebeedf5e8a0b501\", \"cast_unittests\": \"49816a7f448399089088ed1d35bae923c3d175f4\", \"cc_unittests\": \"cb769fc2f51670bbb38791964d14cb98b1abddbd\", \"chrome_app_unittests\": \"1bf64a5d32eaadbf3684d4b85df0027806236a11\", \"chrome_cleaner_unittests\": \"b5e5f7f8d9593802f531b92bd178eb1cd869a8b2\", \"chrome_elf_unittests\": \"80ce13438a4606d21cfb41cf93305819375aa1c6\", \"chromedriver_unittests\": \"e16801d7ba016d283b59118a35f5dfc03d2e088d\", \"components_browsertests\": \"8759b1d067c9965d93b862d13523d0a23654df1a\", \"components_unittests\": \"ab42b6369d1666ab4161f273697a18b7ea710297\", \"compositor_unittests\": \"63fd6c753fdcce722fb2f8a16af2fd1c4fbe201b\", \"content_browsertests\": \"dc373f1505db52754f87883f6b5dc3386b4a224f\", \"content_unittests\": \"ad1a35825a8e8f910b57135630ec9106700fd1f4\", \"courgette_unittests\": \"67855ef127343d337d3b06ae040e1418baa9e184\", \"crashpad_tests\": \"8e7247df4f0951505fd77a7787516143a7becd0b\", \"cronet_tests\": \"24c4d58ae41c52585af8b42eabd7e6773f7295f8\", \"cronet_unittests\": \"631b7f69ab2e3b0abf7ca032ffb9769413d4dfe0\", \"crypto_unittests\": \"5db28a9c5915aacdb9f5c52c8ac42d9123533992\", \"delayloads_unittests\": \"5f87669ef263e0963d6d46318a5264ce74192964\", \"device_unittests\": \"91cce3586696dcb9ae63bccb20e817d9f352b722\", \"display_unittests\": \"2ed254457bfff3bfd8936cc18334721ab22b303b\", \"elevation_service_unittests\": \"c73f275065cf7850880db4cb8b570d1435ae402d\", \"events_unittests\": \"8f97725abe390c659aa75eb5a5b098cb1d0ccf2d\", \"extensions_browsertests\": \"bb393efef78330925564d75bf70774df69c3c8bc\", \"extensions_unittests\": \"4399e86506b66d55ad02f88ceb8144522892c99e\", \"filesystem_service_unittests\": \"78b3f3356afd719989f4a64326accb535e2ad149\", \"gcm_unit_tests\": \"a3523c6216fe48e61c063e2475d2dd5047299db4\", \"gcp_unittests\": \"da8a8fa39a62bbb29bef6bdd7fc8435a4e9d80ad\", \"gfx_unittests\": \"a11b83e7ff69a48e8e564911f475076e03f32e93\", \"gin_unittests\": \"f499dcf9b97d7501e161c64a77ba9d91ac385b74\", \"google_apis_unittests\": \"fad7c70f028d0ad3be5d3f969012b951a88c60c1\", \"gpu_unittests\": \"9a47fdfda0965100b2ecd3d6f4ba79e68f04bca3\", \"gwp_asan_unittests\": \"aca4d4412cb06608159b357b7440e65b1e4eca8e\", \"headless_browsertests\": \"f1399b151e6eff584b6152ff533bdc1b5789ea23\", \"headless_unittests\": \"542a97e29cff308e500d099f71bf2c3b93aa247b\", \"install_static_unittests\": \"009cac48f3aeaceb075da266f58353add260a41b\", \"installer_util_unittests\": \"cff3011a8d1e9a91a25e9e88364499d8517d489b\", \"interactive_ui_tests\": \"f1502cbbb4f84c39ba75f4f3a844b6dbca6a7335\", \"ipc_tests\": \"7dfaa7f739d49b9b3f889a7c6c7a8c5ec1d809bf\", \"jingle_unittests\": \"9d13c5bc3ab9b8fe832a64b52dcf1b51ff78f3d2\", \"latency_unittests\": \"899aaaee1e71368dfd82a232b7313f634c290b76\", \"libjingle_xmpp_unittests\": \"4180420e898d44bd7d6ef5f6cd68533f8712919c\", \"liburlpattern_unittests\": \"1fe99c87816ebb5903a0f64f98e1deed3b0acc50\", \"media_blink_unittests\": \"d38058742f82d2d0685190c5435b6e3c4cdcdda6\", \"media_unittests\": \"cabc3f143a35c1d4723ec4c9ae5d8ed6f76f93ae\", \"message_center_unittests\": \"3bc08099de169e23db3d02fceac91cc186798382\", \"midi_unittests\": \"cec33539145782d71ede4bda80653e53fbe6e814\", \"mojo_core_unittests\": \"907fe599f962dc427f3fdc5fb1b9daf058e3bb12\", \"mojo_unittests\": \"64458ee6cec85b2e439d3c76e3c5ba51dce99338\", \"nacl_loader_unittests\": \"b847be99165287eb517964e19e6a0684cb9adb07\", \"native_theme_unittests\": \"bebaa0f2495cccfe52204ab3eb6e1d0ee4faeb73\", \"net_unittests\": \"2ed12f843bd95e11020df821d7160ee3024a8175\", \"notification_helper_unittests\": \"d6b83a67df1709dd040b113eafc2a1201acbef9d\", \"pdf_unittests\": \"c858529bf028ce9b35c04e4093d684134b1368dd\", \"perfetto_unittests\": \"704c02fde16f6df7a5d7a2aedc870550e3e8f049\", \"ppapi_unittests\": \"b5740250716aa31144a9d16d1da068a27eee9786\", \"printing_unittests\": \"26d7ae1b7958a9adb96b070a4d6936edd3ec8e29\", \"remoting_unittests\": \"54591279c07c800442d2369574937d2cf48ad15c\", \"sbox_integration_tests\": \"07d1e16b7b5bc37146f66bca0471c8cab441735f\", \"sbox_unittests\": \"517bf6b6f45af2f76c43ab5465871a6425ab6ed9\", \"sbox_validation_tests\": \"903ad8d1f950e7f30f62c097482609109363da8b\", \"service_manager_unittests\": \"9b6d2715421d325b147d9c5199a0a54c08c426be\", \"services_unittests\": \"8467dbfb8940f19cdce83d5554f373b1deb4572c\", \"setup_unittests\": \"d97064296d9dd0df45bea4ee0e1549ae2803bc12\", \"shell_dialogs_unittests\": \"ef3f36293c37649fe0ac91279b05ae76a3c630b2\", \"skia_unittests\": \"563afc07def3fbc6ecf38338aa9144fee4b754db\", \"snapshot_unittests\": \"dd1cf1f8842c9b735795c95c0bc47b100b29d2ad\", \"sql_unittests\": \"8f7398450129d7ccd376c0b542c21f5283ab859c\", \"storage_unittests\": \"358ff09599b4f126a97d68789a7118bf0f8d4b26\", \"sync_integration_tests\": \"a8f428370b4b0c5bdc4f7ed108cc62e0fcccad18\", \"traffic_annotation_auditor_unittests\": \"04392c6b3565bd3f3d289ca0eb225297747886a9\", \"ui_base_unittests\": \"5d05dc2e5b983be97f25fda43d549a756a53fecc\", \"ui_touch_selection_unittests\": \"6597afca3721afa3936cc9ab93de4ee6f9bcc01e\", \"unit_tests\": \"59a83d27293ed6c0ff46b0aaf9d9cb14e9f23afb\", \"updater_tests\": \"0757f7db2e63822531932cbdbc48a11d822308f7\", \"url_unittests\": \"12eda86321c24b6e1463817c53583675305c8191\", \"views_unittests\": \"545d4efaa30fbd523e8a2dc0a014d5e6a98de116\", \"viz_unittests\": \"8245800490a7bf52d9e2d95e2075fcdcdfa2e6d2\", \"vr_common_unittests\": \"4d63ff1cac2858ca054d9b3ebe3f4b7265fd384e\", \"vr_pixeltests\": \"7aacc92bf0d5c5e4c2a5688cbaa7842ab489ada6\", \"weblayer_browsertests\": \"50f9956e81776f4fdcade18945e6a826cba5c2c8\", \"weblayer_unittests\": \"b088781dd012217f29ce4e0a5da3871cdab44435\", \"wm_unittests\": \"9590ecd858edf93903319c39b9be1936541557d8\", \"wtf_unittests\": \"bd9c7f836e52fe706f28b574649d0fb97fbdd32f\", \"zlib_unittests\": \"23bbd143a2f656d1799623b1753af9bd2e3bf8c4\", \"zucchini_unittests\": \"5931adca8e74471bc7a6d8528c5e1f7138a868be\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-0-be5b\"], \"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\": [\"5676-c76598c\"], \"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": "1612552285339978",
"status": "COMPLETED",
"status_changed_ts": "1612556247532498",
"tags": [
"build_address:luci.chromium.ci/win-asan/17185",
"builder:win-asan",
"buildset:commit/git/dfe1c7846332b81fa7cc1ca3d1c7a2b857e06637",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dfe1c7846332b81fa7cc1ca3d1c7a2b857e06637",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021540156007533952",
"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/8856110093989716608/+/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:518c6cabc0f04810",
"user_agent:luci-scheduler"
],
"updated_ts": "1612556247537520",
"url": "https://ci.chromium.org/b/8856110093989716608",
"utcnow_ts": "1612556248618080"
}
}
[20:17:28.687] Invocation finished in 1h6m7.691759092s with status SUCCEEDED