[23:35:40.660] New invocation is queued and will start shortly
[23:35:42.004] Starting the invocation (attempt 1)
[23:35:42.046] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:35:42.046] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:35:42.046] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021976510791885920",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"098e477af2b812515458393e03d549d3e178422d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021976510791885920",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/098e477af2b812515458393e03d549d3e178422d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/098e477af2b812515458393e03d549d3e178422d",
"gitiles_ref:refs/heads/master"
]
}
[23:35:43.509] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612136142136944",
"id": "8856546448582195104",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"098e477af2b812515458393e03d549d3e178422d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612136143196241",
"tags": [
"build_address:luci.chromium.ci/win-asan/17101",
"builder:win-asan",
"buildset:commit/git/098e477af2b812515458393e03d549d3e178422d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/098e477af2b812515458393e03d549d3e178422d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021976510791885920",
"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/8856546448582195104/+/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": "1612136143196381",
"url": "https://ci.chromium.org/b/8856546448582195104",
"utcnow_ts": "1612136143494382"
}
}
[23:35:43.509] Task URL: https://ci.chromium.org/b/8856546448582195104
[23:35:43.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:2:0) after 1m0s
[23:35:47.922] Received PubSub notification, asking Buildbucket for the build status
[23:35:48.193] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:43.549] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:2:0)
[23:36:43.549] Timer tick, asking Buildbucket for the build status
[23:36:44.063] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:44.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:4:0) after 1m0s
[23:37:44.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:4:0)
[23:37:44.108] Timer tick, asking Buildbucket for the build status
[23:37:44.220] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:44.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:5:0) after 1m0s
[23:38:44.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:5:0)
[23:38:44.238] Timer tick, asking Buildbucket for the build status
[23:38:44.342] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:44.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:6:0) after 1m0s
[23:39:44.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:6:0)
[23:39:44.330] Timer tick, asking Buildbucket for the build status
[23:39:44.451] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:44.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:7:0) after 1m0s
[23:40:44.559] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:7:0)
[23:40:44.559] Timer tick, asking Buildbucket for the build status
[23:40:44.651] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:44.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:8:0) after 1m0s
[23:41:44.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:8:0)
[23:41:44.790] Timer tick, asking Buildbucket for the build status
[23:41:44.995] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:44.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:9:0) after 1m0s
[23:42:45.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:9:0)
[23:42:45.125] Timer tick, asking Buildbucket for the build status
[23:42:45.186] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:45.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:10:0) after 1m0s
[23:43:45.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:10:0)
[23:43:45.254] Timer tick, asking Buildbucket for the build status
[23:43:45.461] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:45.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:11:0) after 1m0s
[23:44:45.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:11:0)
[23:44:45.481] Timer tick, asking Buildbucket for the build status
[23:44:45.567] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:45.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:12:0) after 1m0s
[23:45:45.652] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:12:0)
[23:45:45.652] Timer tick, asking Buildbucket for the build status
[23:45:45.840] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:45.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:13:0) after 1m0s
[23:46:46.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:13:0)
[23:46:46.447] Timer tick, asking Buildbucket for the build status
[23:46:46.947] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:46.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:14:0) after 1m0s
[23:47:47.017] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:14:0)
[23:47:47.017] Timer tick, asking Buildbucket for the build status
[23:47:47.120] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:47.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:15:0) after 1m0s
[23:48:47.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:15:0)
[23:48:47.156] Timer tick, asking Buildbucket for the build status
[23:48:47.258] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:47.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:16:0) after 1m0s
[23:49:47.366] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:16:0)
[23:49:47.366] Timer tick, asking Buildbucket for the build status
[23:49:47.445] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:47.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:17:0) after 1m0s
[23:50:47.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:17:0)
[23:50:47.583] Timer tick, asking Buildbucket for the build status
[23:50:47.694] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:47.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:18:0) after 1m0s
[23:51:47.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:18:0)
[23:51:47.866] Timer tick, asking Buildbucket for the build status
[23:51:47.997] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:47.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:19:0) after 1m0s
[23:52:48.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:19:0)
[23:52:48.115] Timer tick, asking Buildbucket for the build status
[23:52:48.180] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:48.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:20:0) after 1m0s
[23:53:48.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:20:0)
[23:53:48.234] Timer tick, asking Buildbucket for the build status
[23:53:48.298] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:48.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:21:0) after 1m0s
[23:54:48.347] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:21:0)
[23:54:48.347] Timer tick, asking Buildbucket for the build status
[23:54:48.611] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:48.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:22:0) after 1m0s
[23:55:48.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:22:0)
[23:55:48.779] Timer tick, asking Buildbucket for the build status
[23:55:48.905] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:48.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:23:0) after 1m0s
[23:56:49.091] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:23:0)
[23:56:49.091] Timer tick, asking Buildbucket for the build status
[23:56:49.196] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:49.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:24:0) after 1m0s
[23:57:49.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:24:0)
[23:57:49.444] Timer tick, asking Buildbucket for the build status
[23:57:49.549] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:49.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:25:0) after 1m0s
[23:58:49.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:25:0)
[23:58:49.682] Timer tick, asking Buildbucket for the build status
[23:58:49.787] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:49.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:26:0) after 1m0s
[23:59:50.024] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:26:0)
[23:59:50.024] Timer tick, asking Buildbucket for the build status
[23:59:50.317] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:50.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:27:0) after 1m0s
[00:00:50.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:27:0)
[00:00:50.475] Timer tick, asking Buildbucket for the build status
[00:00:50.596] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:50.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:28:0) after 1m0s
[00:01:50.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:28:0)
[00:01:50.930] Timer tick, asking Buildbucket for the build status
[00:01:51.107] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:51.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:29:0) after 1m0s
[00:02:51.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:29:0)
[00:02:51.198] Timer tick, asking Buildbucket for the build status
[00:02:51.636] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:51.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:30:0) after 1m0s
[00:03:51.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:30:0)
[00:03:51.678] Timer tick, asking Buildbucket for the build status
[00:03:51.780] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:51.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:31:0) after 1m0s
[00:04:51.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:31:0)
[00:04:51.856] Timer tick, asking Buildbucket for the build status
[00:04:51.978] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:51.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:32:0) after 1m0s
[00:05:52.082] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:32:0)
[00:05:52.082] Timer tick, asking Buildbucket for the build status
[00:05:52.191] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:52.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:33:0) after 1m0s
[00:06:52.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:33:0)
[00:06:52.337] Timer tick, asking Buildbucket for the build status
[00:06:52.411] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:52.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:34:0) after 1m0s
[00:07:52.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:34:0)
[00:07:52.485] Timer tick, asking Buildbucket for the build status
[00:07:52.568] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:52.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:35:0) after 1m0s
[00:08:52.821] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:35:0)
[00:08:52.821] Timer tick, asking Buildbucket for the build status
[00:08:52.921] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:52.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:36:0) after 1m0s
[00:09:53.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:36:0)
[00:09:53.008] Timer tick, asking Buildbucket for the build status
[00:09:53.637] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:53.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:37:0) after 1m0s
[00:10:53.744] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:37:0)
[00:10:53.744] Timer tick, asking Buildbucket for the build status
[00:10:53.935] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:53.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:38:0) after 1m0s
[00:11:54.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:38:0)
[00:11:54.148] Timer tick, asking Buildbucket for the build status
[00:11:54.639] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:54.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:39:0) after 1m0s
[00:12:54.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:39:0)
[00:12:54.743] Timer tick, asking Buildbucket for the build status
[00:12:54.812] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:54.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:40:0) after 1m0s
[00:13:54.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:40:0)
[00:13:54.890] Timer tick, asking Buildbucket for the build status
[00:13:54.959] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:54.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:41:0) after 1m0s
[00:14:55.011] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:41:0)
[00:14:55.011] Timer tick, asking Buildbucket for the build status
[00:14:55.076] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:55.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:42:0) after 1m0s
[00:15:55.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:42:0)
[00:15:55.149] Timer tick, asking Buildbucket for the build status
[00:15:55.331] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:55.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:43:0) after 1m0s
[00:16:55.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:43:0)
[00:16:55.472] Timer tick, asking Buildbucket for the build status
[00:16:56.004] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:56.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:44:0) after 1m0s
[00:17:56.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:44:0)
[00:17:56.097] Timer tick, asking Buildbucket for the build status
[00:17:56.496] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:56.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:45:0) after 1m0s
[00:18:56.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:45:0)
[00:18:56.565] Timer tick, asking Buildbucket for the build status
[00:18:57.350] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:57.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:46:0) after 1m0s
[00:19:57.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:46:0)
[00:19:57.371] Timer tick, asking Buildbucket for the build status
[00:19:57.479] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:57.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:47:0) after 1m0s
[00:20:57.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:47:0)
[00:20:57.497] Timer tick, asking Buildbucket for the build status
[00:20:57.614] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:57.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:48:0) after 1m0s
[00:21:57.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:48:0)
[00:21:57.633] Timer tick, asking Buildbucket for the build status
[00:21:57.731] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:57.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:49:0) after 1m0s
[00:22:57.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:49:0)
[00:22:57.796] Timer tick, asking Buildbucket for the build status
[00:22:58.496] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:58.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:50:0) after 1m0s
[00:23:58.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:50:0)
[00:23:58.520] Timer tick, asking Buildbucket for the build status
[00:23:59.008] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:59.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:51:0) after 1m0s
[00:24:59.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:51:0)
[00:24:59.016] Timer tick, asking Buildbucket for the build status
[00:24:59.083] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:59.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:52:0) after 1m0s
[00:25:59.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:52:0)
[00:25:59.115] Timer tick, asking Buildbucket for the build status
[00:25:59.300] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:59.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:53:0) after 1m0s
[00:26:59.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:53:0)
[00:26:59.322] Timer tick, asking Buildbucket for the build status
[00:26:59.795] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:59.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:54:0) after 1m0s
[00:27:59.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:54:0)
[00:27:59.860] Timer tick, asking Buildbucket for the build status
[00:27:59.968] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:59.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:55:0) after 1m0s
[00:29:00.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:55:0)
[00:29:00.134] Timer tick, asking Buildbucket for the build status
[00:29:00.607] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:00.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:56:0) after 1m0s
[00:30:01.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:56:0)
[00:30:01.403] Timer tick, asking Buildbucket for the build status
[00:30:01.485] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:01.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:57:0) after 1m0s
[00:31:01.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:57:0)
[00:31:01.812] Timer tick, asking Buildbucket for the build status
[00:31:01.925] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:01.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:58:0) after 1m0s
[00:32:02.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:58:0)
[00:32:02.015] Timer tick, asking Buildbucket for the build status
[00:32:02.618] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:02.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:59:0) after 1m0s
[00:33:02.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:59:0)
[00:33:02.647] Timer tick, asking Buildbucket for the build status
[00:33:02.717] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:02.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:60:0) after 1m0s
[00:34:02.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:60:0)
[00:34:02.779] Timer tick, asking Buildbucket for the build status
[00:34:02.847] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:02.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:61:0) after 1m0s
[00:35:02.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:61:0)
[00:35:02.867] Timer tick, asking Buildbucket for the build status
[00:35:02.944] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:02.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:62:0) after 1m0s
[00:36:03.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:62:0)
[00:36:03.003] Timer tick, asking Buildbucket for the build status
[00:36:03.564] Build 8856546448582195104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:03.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021976510791885920:63:0) after 1m0s
[00:37:02.307] Received PubSub notification, asking Buildbucket for the build status
[00:37:02.476] Build 8856546448582195104: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:37:02.477] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612139820503549",
"created_by": "project:chromium",
"created_ts": "1612136142136944",
"id": "8856546448582195104",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"098e477af2b812515458393e03d549d3e178422d\"}}",
"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\": \"098e477af2b812515458393e03d549d3e178422d\", \"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\": 17101, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"0be050a44a4308ace01229b89bd4560457356007\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"098e477af2b812515458393e03d549d3e178422d\", \"got_revision_cp\": \"refs/heads/master@{#848972}\", \"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\": \"41bfcf4a63611409220fcd458a03deaa2cd23619\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33116}\", \"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\": \"098e477af2b812515458393e03d549d3e178422d\", \"swarm_hashes_refs/heads/master(at){#848972}_without_patch\": {\"absl_hardening_tests\": \"54efe653eb29ffd0408a4310ba6170cd3f723d26\", \"accessibility_unittests\": \"7b09de4016baf5728a4c613e4cd45208fa5d1da1\", \"angle_unittests\": \"4cd792690eeb1b221fbda31e6b5762998eae92a3\", \"app_shell_unittests\": \"dec78654abeb46de0d1d88ffd449c0096d6c5f17\", \"aura_unittests\": \"908e5801f262242173ffcaa3eb253ded55c260f0\", \"base_unittests\": \"59ca6465fde0e52df071d0976cc81c47fa039154\", \"base_util_unittests\": \"b8a938daa05583c3356c2b6658659f4a4496dc76\", \"blink_common_unittests\": \"6bf0ce12bd2ae087c852559fa86326e5fa430e08\", \"blink_fuzzer_unittests\": \"4a2fc81028b132c78ac4fafc2b84ab548dfeae64\", \"blink_heap_unittests\": \"b96523ed16af7bdc2e94ebb10d32854be884d73e\", \"blink_platform_unittests\": \"7bb00ea75dffad88714417f6907c5d5673e79029\", \"blink_unittests\": \"763710d0f54a9aec7fd0f0228b05c825b1951a9c\", \"boringssl_crypto_tests\": \"3c8d2559da392fae7f9aeef8f796d574e470b898\", \"boringssl_ssl_tests\": \"537a3ff703d15263942349feea13c0091e9cebd5\", \"browser_switcher_bho_unittests\": \"5aa750a9772e4e130f025c48361cb62cba0e5597\", \"browser_tests\": \"c6d7d3f0555924cd841c177aafd5b843f5493f49\", \"capture_unittests\": \"df47d2f118533d0b046f9e00b0c96e301c650346\", \"cast_unittests\": \"15cab57b5547670e2eeb5fb4e2f1d02d3fefbb10\", \"cc_unittests\": \"c2f1ae2e6172a46c74e4da8d3a53147fe4d3ee34\", \"chrome_app_unittests\": \"36479e94febaaa438b76753166532eac9fab66f3\", \"chrome_cleaner_unittests\": \"6972b673cb403d105861b1178e2697ce14fa15aa\", \"chrome_elf_unittests\": \"42f83eabadca1376378c8e2238a55e31abd5082d\", \"chromedriver_unittests\": \"97e3e0c1a637f6d012bd1a8a21a87067dce6369b\", \"components_browsertests\": \"aea44f21368efe97e1db31919aff6fd8b6ea3a5d\", \"components_unittests\": \"9251c63fc10c9d3a29e9757d12bb55bf24090be1\", \"compositor_unittests\": \"c64c7e4e0f206038435be4a37274b131f8dbf865\", \"content_browsertests\": \"60305ba6c7c0bbd06de843799891061fffa8cda3\", \"content_unittests\": \"bbb863bcc86562f372c231b803fdb606fd98a2f2\", \"courgette_unittests\": \"01836ce5c6dd616175cd1913a7397fdb7b914055\", \"crashpad_tests\": \"1bc71d38c563b9953a09ced2dfb7195ee06cb3a2\", \"cronet_tests\": \"73f0fabeda4c9846ee859151c39ee2c344423fbd\", \"cronet_unittests\": \"46e3fcf02c4c5415e64dc3f12c7a987b416e03c7\", \"crypto_unittests\": \"9ba1caa01ef2664436cec9cc0327c9eaeae91602\", \"delayloads_unittests\": \"6506a5f9cdb9375be03f0b4c7d5a9338919a10d4\", \"device_unittests\": \"0993f7ff4273ca7db15aa9c3ec40507ebc158324\", \"display_unittests\": \"33ace8f1410d3f6349d8bb23ad9723ba50bcdeaf\", \"elevation_service_unittests\": \"8f66b7c8ed9e8b17f0764c20e87fa31c76b6c644\", \"events_unittests\": \"e775887ba2ca2903d52e6c2d491efe11b55d8cb5\", \"extensions_browsertests\": \"40f3ecbfd8fab646613dc6ae54fe9ce12058775a\", \"extensions_unittests\": \"1bf245d87fd51ecefe5a7104f57e8e3ec5cad7b3\", \"filesystem_service_unittests\": \"0b7c53b96121ba682338d67bd8759716655adc23\", \"gcm_unit_tests\": \"a91d9924e16451ce6c329a12439907c4c8e3dc73\", \"gcp_unittests\": \"da5ae185cbfb4a39aa6a6e3c3556dc3a03b80104\", \"gfx_unittests\": \"8e3ef9d8434edcc742bf09d37fb257204bd3e9c3\", \"gin_unittests\": \"c01087ea85cda169438957138e62a4ec5694e889\", \"google_apis_unittests\": \"979ecdec49e5763134b5799756aaf63dfa968e90\", \"gpu_unittests\": \"95250902c081571bb16bd97f4f5b99b0f11bc1e5\", \"gwp_asan_unittests\": \"d922626f4d63943e2b30c1dee963555c20d07e61\", \"headless_browsertests\": \"326d031e1c98f78031476185c0a611f8c46cf06a\", \"headless_unittests\": \"ffb3aa789db831a8cdfc403c2e6511c580420f11\", \"install_static_unittests\": \"c7cf8633cf329daefb3d7093c646612b8487ef0d\", \"installer_util_unittests\": \"32ea4a8d36895883ee3a6f904ba9d436af813524\", \"interactive_ui_tests\": \"dd8abbc2e5d2fb838c815999a894c7860f8f514d\", \"ipc_tests\": \"981e28a98a0c6f9b882f687ff7e4e9ba113a9173\", \"jingle_unittests\": \"0733c551b68cab5934fbc7b843acdb3a651a956b\", \"latency_unittests\": \"bad79be6996af3b0b26dd0e79769c12905ab7d26\", \"libjingle_xmpp_unittests\": \"1327d25f5573d93a4c711cfc01541512a5a724c5\", \"liburlpattern_unittests\": \"9d8a436360cafe877a842ac86e172549ed43b66e\", \"media_blink_unittests\": \"c65ce35ca0fe591c37db5ced4407d6fc9d0fca83\", \"media_unittests\": \"a20b6ee0bcccf0743e16c7d7d9f6f222041f6e37\", \"message_center_unittests\": \"a3af172ce96a3a2ff89290b635593fb3524c785b\", \"midi_unittests\": \"dc4bb666d6c61f615fd61dbc61d09eb0c2bcce7c\", \"mojo_core_unittests\": \"5e99d6e01d751cfa0fc244a36c25fcf7bf6954db\", \"mojo_unittests\": \"f893fcd9170974d7decc795c4890130c7c7ccb2a\", \"nacl_loader_unittests\": \"99d936705ecab4a2c8fc3192f3863dcf978ac903\", \"native_theme_unittests\": \"e89203da97e05274d997cecc37457a7c6691d95d\", \"net_unittests\": \"a0e7352c4ce4612013d432b30e9253a554632145\", \"notification_helper_unittests\": \"f3344db885f72871c0118b318b5b022f4143ea0a\", \"pdf_unittests\": \"b381433ffae15aa5b8c1d20c5295cbb9bd239264\", \"perfetto_unittests\": \"250b8a7a7bdb2c8185a5d9ec0f70562d44005242\", \"ppapi_unittests\": \"dcc5ddd6f70013438d529f7e506947a9e4f55343\", \"printing_unittests\": \"5c013c790d79fef9e1114bfd5198ad81b1692208\", \"remoting_unittests\": \"756dd7d0d72ff540c3f06fd0085981272befeec9\", \"sbox_integration_tests\": \"b8e746a2f9de2048eed46435e17f422ecd6e14d0\", \"sbox_unittests\": \"e9728144e8bd0110aedb71bf34553d0b3c45f525\", \"sbox_validation_tests\": \"80fbe13df3a07fe477a7450b65ddc4aa83b228d2\", \"service_manager_unittests\": \"0d31728ee44eb17bad3bfff468c1e8e26b25c5fd\", \"services_unittests\": \"80669d5a5a03863416a27cb39f01e05597d65870\", \"setup_unittests\": \"1a8051bae7db17a94c8658d0d7fcfdb666173994\", \"shell_dialogs_unittests\": \"c4baea57210c41e3529cf07e6ef7f81329a9560c\", \"skia_unittests\": \"4836d03443606b0cd0ca7efee7718cb5952ff45b\", \"snapshot_unittests\": \"7474338b8f3d2d6d8def49ae06d310018e47842b\", \"sql_unittests\": \"267040b731bfc2161f3dfa7b233b4d5d604cb3bf\", \"storage_unittests\": \"fd349436e9d74e7384d8bf09dc11e601656467c2\", \"sync_integration_tests\": \"654a28a2c3b23c814ff169fa71bdd021cf110f0d\", \"traffic_annotation_auditor_unittests\": \"ec5d35c2e34616b972c0e51487143429415f061a\", \"ui_base_unittests\": \"e715a05c36280005c83f69f57eb1997e0b77e7d5\", \"ui_touch_selection_unittests\": \"f5f3b592fc8f322b23da4131dd2756e7d076a160\", \"unit_tests\": \"5735cdf0a9539dbf6197aa2dffecc6cbb760d837\", \"updater_tests\": \"5f4ed626fb8be7acc58c7f76d30d3d922dffd554\", \"url_unittests\": \"07bb83330d8d20976982bf9e382019f2e8f41efe\", \"views_unittests\": \"14a7293e650ea1ec6d5b91b6022a3c75fab11d05\", \"viz_unittests\": \"bcb5a1d031e6263286cfe5d24b791c628a05e1b6\", \"vr_common_unittests\": \"f662f3aeee98bb80bb241d43bcd03d0ea3671d9e\", \"vr_pixeltests\": \"72118daa3d057cbc61e4f84f749b06dfdf41e25e\", \"weblayer_browsertests\": \"fd8d317d4f837bec4928cd0695b376dff4d2d59c\", \"weblayer_unittests\": \"958ec1f732190683547e78fb92205658c13dd461\", \"wm_unittests\": \"6e6a608b2dfaead931684151049dc7e3d469d400\", \"wtf_unittests\": \"1b34fe72f3bd8f17820e53521105443b9eb3c120\", \"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": "1612136147440706",
"status": "COMPLETED",
"status_changed_ts": "1612139821106612",
"tags": [
"build_address:luci.chromium.ci/win-asan/17101",
"builder:win-asan",
"buildset:commit/git/098e477af2b812515458393e03d549d3e178422d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/098e477af2b812515458393e03d549d3e178422d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021976510791885920",
"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/8856546448582195104/+/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:51739ee2b1ff7d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612139821124200",
"url": "https://ci.chromium.org/b/8856546448582195104",
"utcnow_ts": "1612139822461745"
}
}
[00:37:02.477] Invocation finished in 1h1m21.831444987s with status SUCCEEDED