[06:19:25.663] New invocation is queued and will start shortly
[06:19:26.819] Starting the invocation (attempt 1)
[06:19:26.854] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:19:26.855] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:19:26.855] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022041706001279152",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ce1ae543afe555671da69f09bd5d089afddeb97f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022041706001279152",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/ce1ae543afe555671da69f09bd5d089afddeb97f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ce1ae543afe555671da69f09bd5d089afddeb97f",
"gitiles_ref:refs/heads/master"
]
}
[06:19:27.419] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1612073966936623",
"id": "8856611644004163568",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ce1ae543afe555671da69f09bd5d089afddeb97f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612073967275077",
"tags": [
"build_address:luci.chromium.ci/win-asan/17087",
"builder:win-asan",
"buildset:commit/git/ce1ae543afe555671da69f09bd5d089afddeb97f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ce1ae543afe555671da69f09bd5d089afddeb97f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022041706001279152",
"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/8856611644004163568/+/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": "1612073967275197",
"url": "https://ci.chromium.org/b/8856611644004163568",
"utcnow_ts": "1612073967391891"
}
}
[06:19:27.419] Task URL: https://ci.chromium.org/b/8856611644004163568
[06:19:27.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:2:0) after 1m0s
[06:19:29.215] Received PubSub notification, asking Buildbucket for the build status
[06:19:29.411] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:27.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:2:0)
[06:20:27.479] Timer tick, asking Buildbucket for the build status
[06:20:27.585] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:27.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:4:0) after 1m0s
[06:21:27.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:4:0)
[06:21:27.621] Timer tick, asking Buildbucket for the build status
[06:21:27.733] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:27.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:5:0) after 1m0s
[06:22:27.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:5:0)
[06:22:27.787] Timer tick, asking Buildbucket for the build status
[06:22:27.893] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:27.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:6:0) after 1m0s
[06:23:27.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:6:0)
[06:23:27.948] Timer tick, asking Buildbucket for the build status
[06:23:28.129] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:28.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:7:0) after 1m0s
[06:24:28.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:7:0)
[06:24:28.176] Timer tick, asking Buildbucket for the build status
[06:24:28.264] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:28.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:8:0) after 1m0s
[06:25:28.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:8:0)
[06:25:28.282] Timer tick, asking Buildbucket for the build status
[06:25:28.622] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:28.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:9:0) after 1m0s
[06:26:28.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:9:0)
[06:26:28.617] Timer tick, asking Buildbucket for the build status
[06:26:28.695] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:28.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:10:0) after 1m0s
[06:27:28.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:10:0)
[06:27:28.767] Timer tick, asking Buildbucket for the build status
[06:27:28.832] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:28.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:11:0) after 1m0s
[06:28:28.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:11:0)
[06:28:28.915] Timer tick, asking Buildbucket for the build status
[06:28:29.239] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:29.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:12:0) after 1m0s
[06:29:29.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:12:0)
[06:29:29.309] Timer tick, asking Buildbucket for the build status
[06:29:29.540] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:29.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:13:0) after 1m0s
[06:30:29.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:13:0)
[06:30:29.582] Timer tick, asking Buildbucket for the build status
[06:30:29.646] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:29.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:14:0) after 1m0s
[06:31:29.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:14:0)
[06:31:29.663] Timer tick, asking Buildbucket for the build status
[06:31:29.731] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:29.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:15:0) after 1m0s
[06:32:29.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:15:0)
[06:32:29.781] Timer tick, asking Buildbucket for the build status
[06:32:29.846] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:29.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:16:0) after 1m0s
[06:33:29.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:16:0)
[06:33:29.865] Timer tick, asking Buildbucket for the build status
[06:33:30.150] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:30.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:17:0) after 1m0s
[06:34:30.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:17:0)
[06:34:30.262] Timer tick, asking Buildbucket for the build status
[06:34:30.799] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:30.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:18:0) after 1m0s
[06:35:30.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:18:0)
[06:35:30.878] Timer tick, asking Buildbucket for the build status
[06:35:31.047] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:31.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:19:0) after 1m0s
[06:36:31.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:19:0)
[06:36:31.075] Timer tick, asking Buildbucket for the build status
[06:36:31.189] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:31.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:20:0) after 1m0s
[06:37:31.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:20:0)
[06:37:31.207] Timer tick, asking Buildbucket for the build status
[06:37:31.326] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:31.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:21:0) after 1m0s
[06:38:31.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:21:0)
[06:38:31.448] Timer tick, asking Buildbucket for the build status
[06:38:31.606] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:31.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:22:0) after 1m0s
[06:39:31.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:22:0)
[06:39:31.636] Timer tick, asking Buildbucket for the build status
[06:39:31.959] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:31.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:23:0) after 1m0s
[06:40:31.943] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:23:0)
[06:40:31.943] Timer tick, asking Buildbucket for the build status
[06:40:32.537] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:32.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:24:0) after 1m0s
[06:41:32.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:24:0)
[06:41:32.555] Timer tick, asking Buildbucket for the build status
[06:41:33.247] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:33.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:25:0) after 1m0s
[06:42:33.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:25:0)
[06:42:33.312] Timer tick, asking Buildbucket for the build status
[06:42:33.426] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:33.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:26:0) after 1m0s
[06:43:33.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:26:0)
[06:43:33.413] Timer tick, asking Buildbucket for the build status
[06:43:33.807] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:33.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:27:0) after 1m0s
[06:44:33.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:27:0)
[06:44:33.811] Timer tick, asking Buildbucket for the build status
[06:44:34.555] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:34.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:28:0) after 1m0s
[06:45:34.642] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:28:0)
[06:45:34.642] Timer tick, asking Buildbucket for the build status
[06:45:34.908] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:34.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:29:0) after 1m0s
[06:46:34.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:29:0)
[06:46:34.983] Timer tick, asking Buildbucket for the build status
[06:46:35.055] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:35.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:30:0) after 1m0s
[06:47:35.091] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:30:0)
[06:47:35.091] Timer tick, asking Buildbucket for the build status
[06:47:35.210] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:35.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:31:0) after 1m0s
[06:48:35.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:31:0)
[06:48:35.248] Timer tick, asking Buildbucket for the build status
[06:48:35.367] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:35.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:32:0) after 1m0s
[06:49:35.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:32:0)
[06:49:35.446] Timer tick, asking Buildbucket for the build status
[06:49:35.581] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:35.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:33:0) after 1m0s
[06:50:35.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:33:0)
[06:50:35.703] Timer tick, asking Buildbucket for the build status
[06:50:35.778] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:35.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:34:0) after 1m0s
[06:51:35.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:34:0)
[06:51:35.869] Timer tick, asking Buildbucket for the build status
[06:51:35.994] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:35.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:35:0) after 1m0s
[06:52:36.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:35:0)
[06:52:36.018] Timer tick, asking Buildbucket for the build status
[06:52:36.086] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:36.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:36:0) after 1m0s
[06:53:36.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:36:0)
[06:53:36.172] Timer tick, asking Buildbucket for the build status
[06:53:36.253] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:36.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:37:0) after 1m0s
[06:54:36.325] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:37:0)
[06:54:36.325] Timer tick, asking Buildbucket for the build status
[06:54:36.701] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:36.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:38:0) after 1m0s
[06:55:36.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:38:0)
[06:55:36.721] Timer tick, asking Buildbucket for the build status
[06:55:36.791] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:36.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:39:0) after 1m0s
[06:56:36.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:39:0)
[06:56:36.786] Timer tick, asking Buildbucket for the build status
[06:56:36.871] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:36.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:40:0) after 1m0s
[06:57:36.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:40:0)
[06:57:36.894] Timer tick, asking Buildbucket for the build status
[06:57:36.965] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:36.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:41:0) after 1m0s
[06:58:36.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:41:0)
[06:58:36.986] Timer tick, asking Buildbucket for the build status
[06:58:37.096] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:37.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:42:0) after 1m0s
[06:59:37.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:42:0)
[06:59:37.117] Timer tick, asking Buildbucket for the build status
[06:59:37.234] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:37.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:43:0) after 1m0s
[07:00:37.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:43:0)
[07:00:37.279] Timer tick, asking Buildbucket for the build status
[07:00:37.433] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:37.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:44:0) after 1m0s
[07:01:37.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:44:0)
[07:01:37.492] Timer tick, asking Buildbucket for the build status
[07:01:37.561] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:37.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:45:0) after 1m0s
[07:02:37.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:45:0)
[07:02:37.579] Timer tick, asking Buildbucket for the build status
[07:02:37.645] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:37.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:46:0) after 1m0s
[07:03:37.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:46:0)
[07:03:37.664] Timer tick, asking Buildbucket for the build status
[07:03:37.743] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:37.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:47:0) after 1m0s
[07:04:37.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:47:0)
[07:04:37.762] Timer tick, asking Buildbucket for the build status
[07:04:37.878] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:37.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:48:0) after 1m0s
[07:05:37.893] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:48:0)
[07:05:37.893] Timer tick, asking Buildbucket for the build status
[07:05:38.117] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:38.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:49:0) after 1m0s
[07:06:38.137] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:49:0)
[07:06:38.137] Timer tick, asking Buildbucket for the build status
[07:06:38.502] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:38.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:50:0) after 1m0s
[07:07:38.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:50:0)
[07:07:38.561] Timer tick, asking Buildbucket for the build status
[07:07:38.733] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:38.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:51:0) after 1m0s
[07:08:38.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:51:0)
[07:08:38.790] Timer tick, asking Buildbucket for the build status
[07:08:38.869] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:38.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:52:0) after 1m0s
[07:09:38.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:52:0)
[07:09:38.896] Timer tick, asking Buildbucket for the build status
[07:09:39.032] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:39.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:53:0) after 1m0s
[07:10:39.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:53:0)
[07:10:39.057] Timer tick, asking Buildbucket for the build status
[07:10:39.526] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:39.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:54:0) after 1m0s
[07:11:39.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:54:0)
[07:11:39.508] Timer tick, asking Buildbucket for the build status
[07:11:39.876] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:39.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:55:0) after 1m0s
[07:12:39.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:55:0)
[07:12:39.894] Timer tick, asking Buildbucket for the build status
[07:12:39.985] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:39.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:56:0) after 1m0s
[07:13:40.020] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:56:0)
[07:13:40.020] Timer tick, asking Buildbucket for the build status
[07:13:40.085] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:40.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:57:0) after 1m0s
[07:14:40.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:57:0)
[07:14:40.134] Timer tick, asking Buildbucket for the build status
[07:14:40.283] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:40.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:58:0) after 1m0s
[07:15:40.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:58:0)
[07:15:40.312] Timer tick, asking Buildbucket for the build status
[07:15:40.383] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:40.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:59:0) after 1m0s
[07:16:40.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:59:0)
[07:16:40.374] Timer tick, asking Buildbucket for the build status
[07:16:40.530] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:40.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:60:0) after 1m0s
[07:17:40.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:60:0)
[07:17:40.601] Timer tick, asking Buildbucket for the build status
[07:17:40.703] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:40.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:61:0) after 1m0s
[07:18:40.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:61:0)
[07:18:40.756] Timer tick, asking Buildbucket for the build status
[07:18:40.840] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:40.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:62:0) after 1m0s
[07:19:40.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:62:0)
[07:19:40.892] Timer tick, asking Buildbucket for the build status
[07:19:41.294] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:41.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:63:0) after 1m0s
[07:20:41.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:63:0)
[07:20:41.355] Timer tick, asking Buildbucket for the build status
[07:20:43.488] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:43.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:64:0) after 1m0s
[07:21:43.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:64:0)
[07:21:43.536] Timer tick, asking Buildbucket for the build status
[07:21:43.828] Build 8856611644004163568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:43.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022041706001279152:65:0) after 1m0s
[07:22:09.396] Received PubSub notification, asking Buildbucket for the build status
[07:22:09.647] Build 8856611644004163568: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:22:09.647] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1612077728431990",
"created_by": "project:chromium",
"created_ts": "1612073966936623",
"id": "8856611644004163568",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ce1ae543afe555671da69f09bd5d089afddeb97f\"}}",
"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\": \"ce1ae543afe555671da69f09bd5d089afddeb97f\", \"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-us-central1-b-32-1-y2bx\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17087, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"eae262e7fa3d375d8a62e95ec4326d59040704de\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"ce1ae543afe555671da69f09bd5d089afddeb97f\", \"got_revision_cp\": \"refs/heads/master@{#848924}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"8b4cf1c2491d56cf9eda153b5455e4b295732b7c\", \"got_v8_revision\": \"cbbe339c1ca9754b3123fe2bdb83c0f58ee5d728\", \"got_v8_revision_cp\": \"refs/heads/9.0.101@{#1}\", \"got_webrtc_revision\": \"5ac4212bec6faf20250d4632d0ced86c774b9e3e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33114}\", \"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\": \"ce1ae543afe555671da69f09bd5d089afddeb97f\", \"swarm_hashes_refs/heads/master(at){#848924}_without_patch\": {\"absl_hardening_tests\": \"54efe653eb29ffd0408a4310ba6170cd3f723d26\", \"accessibility_unittests\": \"5c2133b28276dde369456d62018a0c498abec674\", \"angle_unittests\": \"e6f62f54f3cca034e5ced2d9401abe0dc3f194f4\", \"app_shell_unittests\": \"3b69c61048954dcb4230473562ebe24a69ea64d6\", \"aura_unittests\": \"b7cd3a532b24b5c7c4ea174558c99b1ab4e960ed\", \"base_unittests\": \"cb9a5512177782fd46758f092efce914e7805fca\", \"base_util_unittests\": \"b8a938daa05583c3356c2b6658659f4a4496dc76\", \"blink_common_unittests\": \"a3f3262c79a8492e794225e39cf16cede6031aa9\", \"blink_fuzzer_unittests\": \"ce9041381886fe855212bcf9e8712e76ffbb7af2\", \"blink_heap_unittests\": \"a62a6d8e84bb04ce304ce1aa6644cdd5245b50a1\", \"blink_platform_unittests\": \"5f7c549b0bdf9032b3eaf07a8c0e622d0851e632\", \"blink_unittests\": \"ef3aa755337ab6afc1367d3c6235e58176063a85\", \"boringssl_crypto_tests\": \"3c8d2559da392fae7f9aeef8f796d574e470b898\", \"boringssl_ssl_tests\": \"537a3ff703d15263942349feea13c0091e9cebd5\", \"browser_switcher_bho_unittests\": \"5aa750a9772e4e130f025c48361cb62cba0e5597\", \"browser_tests\": \"c00303c44758f01b2feace2267179ad5e060f993\", \"capture_unittests\": \"eb86498a3d3deb881c28a9533faa2c91f54f9242\", \"cast_unittests\": \"04ae18edac3abe015535cb22aa7fd6f28f448849\", \"cc_unittests\": \"93400faf4d79260df02241de2873a0b0a051045d\", \"chrome_app_unittests\": \"60990094b8fe38638999cae87cac6ad3908df917\", \"chrome_cleaner_unittests\": \"29bdde449f4c871405fc6d9bfdba1d490eb29e3a\", \"chrome_elf_unittests\": \"7171974f2c3bb9c3d28bd1da1c03b26e9afc1c99\", \"chromedriver_unittests\": \"f7cf1a2f820ed478661173f8a73d7561fb549a9d\", \"components_browsertests\": \"d11fda7bcb165711f2bbd2ea61ad2133d4d2a82e\", \"components_unittests\": \"323a33ab4ff01cf1f3989a8313715be8d6940a4a\", \"compositor_unittests\": \"288ab1934926a613ca4bc7edf08ad6755c4db377\", \"content_browsertests\": \"4eb8dc798d022cfb996b4f6d77e6322c370b29ea\", \"content_unittests\": \"c4e397ac6f168af50e477d41636540376065fb51\", \"courgette_unittests\": \"01836ce5c6dd616175cd1913a7397fdb7b914055\", \"crashpad_tests\": \"1bc71d38c563b9953a09ced2dfb7195ee06cb3a2\", \"cronet_tests\": \"73f0fabeda4c9846ee859151c39ee2c344423fbd\", \"cronet_unittests\": \"46e3fcf02c4c5415e64dc3f12c7a987b416e03c7\", \"crypto_unittests\": \"9ba1caa01ef2664436cec9cc0327c9eaeae91602\", \"delayloads_unittests\": \"8dba6b63e0b0f1a97deca00b427352aea576105f\", \"device_unittests\": \"f5d14df0778eb4febdd9aec5de8aab831a25184f\", \"display_unittests\": \"bb65b955085ef85a053a63057c4c94c4ce974cfa\", \"elevation_service_unittests\": \"c123f815f4ef037fcc74d12665d8f05817d470ee\", \"events_unittests\": \"adf223d8adf7cbdbb2fbd6fe14b4796a5c1c239a\", \"extensions_browsertests\": \"51356ad71f61503dae818ef31ba0b7864af9f14a\", \"extensions_unittests\": \"653f74b91fe88d31bd1259e385b7e1d4de172bb3\", \"filesystem_service_unittests\": \"0b7c53b96121ba682338d67bd8759716655adc23\", \"gcm_unit_tests\": \"ec0fc278bc87e541123fca00ababb9e6dfe9ee05\", \"gcp_unittests\": \"7fcde00ba08618194650b1cfaf58dd4efacc97c6\", \"gfx_unittests\": \"3f31812a0aefd91bf6a6f4b704c4a78890c08a7f\", \"gin_unittests\": \"c01087ea85cda169438957138e62a4ec5694e889\", \"google_apis_unittests\": \"a8fac1325125e0ef5b93fc78ce35da7397a565a2\", \"gpu_unittests\": \"eb75bc948c24d7bf68666c12851a652979071da5\", \"gwp_asan_unittests\": \"d922626f4d63943e2b30c1dee963555c20d07e61\", \"headless_browsertests\": \"236466b3c4f835432a759d657be3d628a822705e\", \"headless_unittests\": \"3d07dd8a9462eb9023ac858b6c174508ca485eb8\", \"install_static_unittests\": \"c7cf8633cf329daefb3d7093c646612b8487ef0d\", \"installer_util_unittests\": \"40ce825f38e49e65631a6b3053aac9fc1b9d3ace\", \"interactive_ui_tests\": \"d12c5ea44fd35419907cbd11520144d33a850611\", \"ipc_tests\": \"981e28a98a0c6f9b882f687ff7e4e9ba113a9173\", \"jingle_unittests\": \"f6b971b4a035bd0e7832e2554dae0d74bf176b80\", \"latency_unittests\": \"36265d0ae3265aafd560e5cae7dcfa69017922ab\", \"libjingle_xmpp_unittests\": \"1327d25f5573d93a4c711cfc01541512a5a724c5\", \"liburlpattern_unittests\": \"9d8a436360cafe877a842ac86e172549ed43b66e\", \"media_blink_unittests\": \"de38a9618878a9152be6bcd1f4db816dd4983dcd\", \"media_unittests\": \"a1022f5201103dfa435a08958dd496aa9d2b12b3\", \"message_center_unittests\": \"4de62c84b2c0e8c90f328a93c2405b40f952e5fa\", \"midi_unittests\": \"dc4bb666d6c61f615fd61dbc61d09eb0c2bcce7c\", \"mojo_core_unittests\": \"5e99d6e01d751cfa0fc244a36c25fcf7bf6954db\", \"mojo_unittests\": \"3d1f4bf19a078925e8628d01596a7412d91dbc3a\", \"nacl_loader_unittests\": \"ca5fb9e14a334f0c0b22ed31e3acb87f8453d99f\", \"native_theme_unittests\": \"539e24c104ae3e4a0e35135677cb681c6639fcfa\", \"net_unittests\": \"a0e7352c4ce4612013d432b30e9253a554632145\", \"notification_helper_unittests\": \"89b35e0606cc39485576b39c5a7749a5bf649371\", \"pdf_unittests\": \"e85ab3722ad25975180abc6c3d097af593f3522a\", \"perfetto_unittests\": \"250b8a7a7bdb2c8185a5d9ec0f70562d44005242\", \"ppapi_unittests\": \"c8c6e7ad2db2521f22c9df8748be0b3b96764a09\", \"printing_unittests\": \"b5d35719069fb2f724c3146fd307ae419b3ff4da\", \"remoting_unittests\": \"327b96ca55e4960a2c5cf934c490b5a4c812cac6\", \"sbox_integration_tests\": \"b8e746a2f9de2048eed46435e17f422ecd6e14d0\", \"sbox_unittests\": \"e9728144e8bd0110aedb71bf34553d0b3c45f525\", \"sbox_validation_tests\": \"80fbe13df3a07fe477a7450b65ddc4aa83b228d2\", \"service_manager_unittests\": \"9e9915aceee7b047b38e38104e10d50872ce3069\", \"services_unittests\": \"9bed97bcb5e486dc361aa2b9704ef52f98e6b6dd\", \"setup_unittests\": \"be97d42cb9f1ad83702996b4f3213e6ddbdf42f8\", \"shell_dialogs_unittests\": \"bad3805f056254975bfeef060b48379d67226d6f\", \"skia_unittests\": \"ec0e6beca9c0b473806c3a40542300134423aa19\", \"snapshot_unittests\": \"f5b3e97025315dd54021be1a75bcbf8f34742554\", \"sql_unittests\": \"267040b731bfc2161f3dfa7b233b4d5d604cb3bf\", \"storage_unittests\": \"3460f1a0e4078b9d5855b08eda070868bcb5a9be\", \"sync_integration_tests\": \"03433638ec98c1daa6176d3843a773139e5fe2f5\", \"traffic_annotation_auditor_unittests\": \"ec5d35c2e34616b972c0e51487143429415f061a\", \"ui_base_unittests\": \"656bb0acf6256b2336ae0226c796b76ac906a995\", \"ui_touch_selection_unittests\": \"1d9028f94f68ffb97d73a27699382bcb8cf84317\", \"unit_tests\": \"848134630fb8294a512400da7229cb5cb4f6ecaf\", \"updater_tests\": \"56e856c8e7b1b1125c5b84d85068d5db418fcf38\", \"url_unittests\": \"07bb83330d8d20976982bf9e382019f2e8f41efe\", \"views_unittests\": \"d40dda1a3f392447922f86bc24455e24a3766559\", \"viz_unittests\": \"197b9e15fa2abd06653d088af8f70daa59d675c4\", \"vr_common_unittests\": \"19d6c19ceddba587c79068f413e030e60b47433a\", \"vr_pixeltests\": \"1f0abc09e6724d6b3eeb3bb8a199601464a266d3\", \"weblayer_browsertests\": \"8c701e1c85a8d7c76e19758845a122e3ad1eaf22\", \"weblayer_unittests\": \"bb39bf85603fcbf425e84dd72870646b1b974939\", \"wm_unittests\": \"c77771c9f69c8ef85621f33339965593896dea46\", \"wtf_unittests\": \"b4a0a638a804588eff6053ee3177ed9a47a85ddc\", \"zlib_unittests\": \"e5553eff791df2decc8f7026c12654aaaa409792\", \"zucchini_unittests\": \"84d957c3537bb5c8a9b4834f7e2c8e032ca6792d\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-y2bx\"], \"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\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612073968297034",
"status": "COMPLETED",
"status_changed_ts": "1612077728725618",
"tags": [
"build_address:luci.chromium.ci/win-asan/17087",
"builder:win-asan",
"buildset:commit/git/ce1ae543afe555671da69f09bd5d089afddeb97f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ce1ae543afe555671da69f09bd5d089afddeb97f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022041706001279152",
"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/8856611644004163568/+/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:516fea27b557bf10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612077728730863",
"url": "https://ci.chromium.org/b/8856611644004163568",
"utcnow_ts": "1612077729630076"
}
}
[07:22:09.647] Invocation finished in 1h2m43.998767764s with status SUCCEEDED