[08:26:20.385] New invocation is queued and will start shortly
[08:26:25.821] Starting the invocation (attempt 1)
[08:26:25.870] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:26:25.870] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:26:25.870] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021218348689684352",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"cc4c0a07d9388eb042c0674c3e06da1d5bb16e9f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021218348689684352",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/cc4c0a07d9388eb042c0674c3e06da1d5bb16e9f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc4c0a07d9388eb042c0674c3e06da1d5bb16e9f",
"gitiles_ref:refs/heads/master"
]
}
[08:26:27.362] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612859186017888",
"id": "8855788282121701152",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cc4c0a07d9388eb042c0674c3e06da1d5bb16e9f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612859186884669",
"tags": [
"build_address:luci.chromium.ci/win-asan/17251",
"builder:win-asan",
"buildset:commit/git/cc4c0a07d9388eb042c0674c3e06da1d5bb16e9f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc4c0a07d9388eb042c0674c3e06da1d5bb16e9f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021218348689684352",
"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/8855788282121701152/+/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": "1612859186886235",
"url": "https://ci.chromium.org/b/8855788282121701152",
"utcnow_ts": "1612859187293776"
}
}
[08:26:27.362] Task URL: https://ci.chromium.org/b/8855788282121701152
[08:26:27.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:2:0) after 1m0s
[08:26:35.654] Received PubSub notification, asking Buildbucket for the build status
[08:26:35.832] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:28.445] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:2:0)
[08:27:28.445] Timer tick, asking Buildbucket for the build status
[08:27:28.549] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:28.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:4:0) after 1m0s
[08:28:28.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:4:0)
[08:28:28.755] Timer tick, asking Buildbucket for the build status
[08:28:28.868] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:28.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:5:0) after 1m0s
[08:29:29.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:5:0)
[08:29:29.061] Timer tick, asking Buildbucket for the build status
[08:29:29.949] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:29.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:6:0) after 1m0s
[08:30:30.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:6:0)
[08:30:30.119] Timer tick, asking Buildbucket for the build status
[08:30:30.260] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:30.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:7:0) after 1m0s
[08:31:30.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:7:0)
[08:31:30.277] Timer tick, asking Buildbucket for the build status
[08:31:30.479] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:30.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:8:0) after 1m0s
[08:32:30.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:8:0)
[08:32:30.532] Timer tick, asking Buildbucket for the build status
[08:32:30.758] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:30.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:9:0) after 1m0s
[08:33:30.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:9:0)
[08:33:30.737] Timer tick, asking Buildbucket for the build status
[08:33:30.793] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:30.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:10:0) after 1m0s
[08:34:30.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:10:0)
[08:34:30.855] Timer tick, asking Buildbucket for the build status
[08:34:30.938] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:30.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:11:0) after 1m0s
[08:35:30.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:11:0)
[08:35:30.937] Timer tick, asking Buildbucket for the build status
[08:35:31.020] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:31.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:12:0) after 1m0s
[08:36:31.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:12:0)
[08:36:31.072] Timer tick, asking Buildbucket for the build status
[08:36:31.693] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:31.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:13:0) after 1m0s
[08:37:31.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:13:0)
[08:37:31.781] Timer tick, asking Buildbucket for the build status
[08:37:32.242] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:32.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:14:0) after 1m0s
[08:38:32.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:14:0)
[08:38:32.258] Timer tick, asking Buildbucket for the build status
[08:38:32.409] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:32.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:15:0) after 1m0s
[08:39:32.464] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:15:0)
[08:39:32.464] Timer tick, asking Buildbucket for the build status
[08:39:32.999] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:32.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:16:0) after 1m0s
[08:40:33.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:16:0)
[08:40:33.059] Timer tick, asking Buildbucket for the build status
[08:40:33.143] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:33.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:17:0) after 1m0s
[08:41:33.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:17:0)
[08:41:33.116] Timer tick, asking Buildbucket for the build status
[08:41:33.179] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:33.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:18:0) after 1m0s
[08:42:33.160] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:18:0)
[08:42:33.160] Timer tick, asking Buildbucket for the build status
[08:42:33.309] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:33.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:19:0) after 1m0s
[08:43:33.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:19:0)
[08:43:33.359] Timer tick, asking Buildbucket for the build status
[08:43:33.429] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:33.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:20:0) after 1m0s
[08:44:33.445] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:20:0)
[08:44:33.446] Timer tick, asking Buildbucket for the build status
[08:44:34.219] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:34.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:21:0) after 1m0s
[08:45:34.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:21:0)
[08:45:34.272] Timer tick, asking Buildbucket for the build status
[08:45:34.399] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:34.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:22:0) after 1m0s
[08:46:34.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:22:0)
[08:46:34.462] Timer tick, asking Buildbucket for the build status
[08:46:35.169] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:35.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:23:0) after 1m0s
[08:47:35.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:23:0)
[08:47:35.254] Timer tick, asking Buildbucket for the build status
[08:47:36.594] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:36.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:24:0) after 1m0s
[08:48:36.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:24:0)
[08:48:36.605] Timer tick, asking Buildbucket for the build status
[08:48:36.823] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:36.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:25:0) after 1m0s
[08:49:36.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:25:0)
[08:49:36.860] Timer tick, asking Buildbucket for the build status
[08:49:36.942] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:36.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:26:0) after 1m0s
[08:50:36.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:26:0)
[08:50:36.962] Timer tick, asking Buildbucket for the build status
[08:50:37.089] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:37.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:27:0) after 1m0s
[08:51:37.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:27:0)
[08:51:37.111] Timer tick, asking Buildbucket for the build status
[08:51:37.228] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:37.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:28:0) after 1m0s
[08:52:37.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:28:0)
[08:52:37.255] Timer tick, asking Buildbucket for the build status
[08:52:37.779] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:37.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:29:0) after 1m0s
[08:53:37.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:29:0)
[08:53:37.767] Timer tick, asking Buildbucket for the build status
[08:53:37.907] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:37.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:30:0) after 1m0s
[08:54:37.920] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:30:0)
[08:54:37.920] Timer tick, asking Buildbucket for the build status
[08:54:38.150] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:38.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:31:0) after 1m0s
[08:55:38.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:31:0)
[08:55:38.202] Timer tick, asking Buildbucket for the build status
[08:55:38.477] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:38.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:32:0) after 1m0s
[08:56:38.560] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:32:0)
[08:56:38.560] Timer tick, asking Buildbucket for the build status
[08:56:38.811] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:38.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:33:0) after 1m0s
[08:57:38.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:33:0)
[08:57:38.854] Timer tick, asking Buildbucket for the build status
[08:57:40.596] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:40.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:34:0) after 1m0s
[08:58:40.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:34:0)
[08:58:40.797] Timer tick, asking Buildbucket for the build status
[08:58:41.000] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:41.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:35:0) after 1m0s
[08:59:41.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:35:0)
[08:59:41.178] Timer tick, asking Buildbucket for the build status
[08:59:41.510] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:41.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:36:0) after 1m0s
[09:00:41.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:36:0)
[09:00:41.723] Timer tick, asking Buildbucket for the build status
[09:00:42.000] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:42.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:37:0) after 1m0s
[09:01:42.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:37:0)
[09:01:42.132] Timer tick, asking Buildbucket for the build status
[09:01:42.308] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:42.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:38:0) after 1m0s
[09:02:42.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:38:0)
[09:02:42.397] Timer tick, asking Buildbucket for the build status
[09:02:42.737] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:42.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:39:0) after 1m0s
[09:03:42.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:39:0)
[09:03:42.826] Timer tick, asking Buildbucket for the build status
[09:03:43.007] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:43.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:40:0) after 1m0s
[09:04:43.100] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:40:0)
[09:04:43.100] Timer tick, asking Buildbucket for the build status
[09:04:43.725] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:43.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:41:0) after 1m0s
[09:05:43.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:41:0)
[09:05:43.849] Timer tick, asking Buildbucket for the build status
[09:05:44.004] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:44.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:42:0) after 1m0s
[09:06:44.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:42:0)
[09:06:44.115] Timer tick, asking Buildbucket for the build status
[09:06:44.544] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:44.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:43:0) after 1m0s
[09:07:44.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:43:0)
[09:07:44.588] Timer tick, asking Buildbucket for the build status
[09:07:44.710] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:44.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:44:0) after 1m0s
[09:08:44.764] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:44:0)
[09:08:44.764] Timer tick, asking Buildbucket for the build status
[09:08:44.837] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:44.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:45:0) after 1m0s
[09:09:44.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:45:0)
[09:09:44.930] Timer tick, asking Buildbucket for the build status
[09:09:45.029] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:45.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:46:0) after 1m0s
[09:10:45.091] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:46:0)
[09:10:45.091] Timer tick, asking Buildbucket for the build status
[09:10:45.513] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:45.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:47:0) after 1m0s
[09:11:45.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:47:0)
[09:11:45.607] Timer tick, asking Buildbucket for the build status
[09:11:45.716] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:45.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:48:0) after 1m0s
[09:12:45.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:48:0)
[09:12:45.770] Timer tick, asking Buildbucket for the build status
[09:12:45.901] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:45.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:49:0) after 1m0s
[09:13:45.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:49:0)
[09:13:45.949] Timer tick, asking Buildbucket for the build status
[09:13:46.143] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:46.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:50:0) after 1m0s
[09:14:46.197] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:50:0)
[09:14:46.197] Timer tick, asking Buildbucket for the build status
[09:14:46.716] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:46.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:51:0) after 1m0s
[09:15:46.735] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:51:0)
[09:15:46.735] Timer tick, asking Buildbucket for the build status
[09:15:47.002] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:47.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:52:0) after 1m0s
[09:16:47.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:52:0)
[09:16:47.025] Timer tick, asking Buildbucket for the build status
[09:16:47.541] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:47.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:53:0) after 1m0s
[09:17:47.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:53:0)
[09:17:47.571] Timer tick, asking Buildbucket for the build status
[09:17:47.943] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:47.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:54:0) after 1m0s
[09:18:48.013] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:54:0)
[09:18:48.013] Timer tick, asking Buildbucket for the build status
[09:18:48.641] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:48.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:55:0) after 1m0s
[09:19:48.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:55:0)
[09:19:48.660] Timer tick, asking Buildbucket for the build status
[09:19:49.141] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:49.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:56:0) after 1m0s
[09:20:49.184] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:56:0)
[09:20:49.184] Timer tick, asking Buildbucket for the build status
[09:20:49.248] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:49.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:57:0) after 1m0s
[09:21:49.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:57:0)
[09:21:49.271] Timer tick, asking Buildbucket for the build status
[09:21:49.431] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:49.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:58:0) after 1m0s
[09:22:49.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:58:0)
[09:22:49.483] Timer tick, asking Buildbucket for the build status
[09:22:49.617] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:49.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:59:0) after 1m0s
[09:23:49.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:59:0)
[09:23:49.648] Timer tick, asking Buildbucket for the build status
[09:23:49.797] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:49.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:60:0) after 1m0s
[09:24:49.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:60:0)
[09:24:49.861] Timer tick, asking Buildbucket for the build status
[09:24:50.423] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:50.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:61:0) after 1m0s
[09:25:50.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:61:0)
[09:25:50.515] Timer tick, asking Buildbucket for the build status
[09:25:50.650] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:50.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:62:0) after 1m0s
[09:26:50.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:62:0)
[09:26:50.697] Timer tick, asking Buildbucket for the build status
[09:26:51.114] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:51.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:63:0) after 1m0s
[09:27:51.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:63:0)
[09:27:51.226] Timer tick, asking Buildbucket for the build status
[09:27:51.397] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:51.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:64:0) after 1m0s
[09:28:51.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:64:0)
[09:28:51.480] Timer tick, asking Buildbucket for the build status
[09:28:51.741] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:51.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:65:0) after 1m0s
[09:29:51.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:65:0)
[09:29:51.889] Timer tick, asking Buildbucket for the build status
[09:29:52.013] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:52.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:66:0) after 1m0s
[09:30:52.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:66:0)
[09:30:52.084] Timer tick, asking Buildbucket for the build status
[09:30:52.154] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:52.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:67:0) after 1m0s
[09:31:52.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:67:0)
[09:31:52.240] Timer tick, asking Buildbucket for the build status
[09:31:52.421] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:52.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:68:0) after 1m0s
[09:32:52.431] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:68:0)
[09:32:52.431] Timer tick, asking Buildbucket for the build status
[09:32:52.632] Build 8855788282121701152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:52.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021218348689684352:69:0) after 1m0s
[09:33:28.081] Received PubSub notification, asking Buildbucket for the build status
[09:33:28.230] Build 8855788282121701152: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:33:28.231] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612863206825906",
"created_by": "project:chromium",
"created_ts": "1612859186017888",
"id": "8855788282121701152",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cc4c0a07d9388eb042c0674c3e06da1d5bb16e9f\"}}",
"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\": \"cc4c0a07d9388eb042c0674c3e06da1d5bb16e9f\", \"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-8-jw2s\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17251, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"281c65523a0a57d27caaabe4d1c7209026e12862\", \"got_dawn_revision\": \"2831f0584028114a8896059ce00e7bddaf3bba1f\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"cc4c0a07d9388eb042c0674c3e06da1d5bb16e9f\", \"got_revision_cp\": \"refs/heads/master@{#852120}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"9d35d544ce966098fdcb36107835d57a4dc1f32d\", \"got_v8_revision\": \"b19b2448ed8ecdc8965621750ac648a2331c0c18\", \"got_v8_revision_cp\": \"refs/heads/9.0.144@{#1}\", \"got_webrtc_revision\": \"54ea85c2cd3a2cb1b8f440a71aa9676a9b88135c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33190}\", \"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\": \"cc4c0a07d9388eb042c0674c3e06da1d5bb16e9f\", \"swarm_hashes_refs/heads/master(at){#852120}_without_patch\": {\"absl_hardening_tests\": \"f331f07e4f044c08d5c0d20f693676c4c1280438\", \"accessibility_unittests\": \"366d79ec42f1e389ea06e1518d8fbcdb2045eec1\", \"angle_unittests\": \"954db0928a7dc7edb9fff057812ccd80b3a3afd6\", \"app_shell_unittests\": \"e4661751da584aaf12d8e512150405b8736dd389\", \"aura_unittests\": \"c361011e64e200841c4808087bfb721ea6e42ab1\", \"base_unittests\": \"9fc6f3a9fa948a1b6e0ba242b10bad662d586379\", \"base_util_unittests\": \"7836d726d566757266ca0f74aa0b8e9ceabffe6f\", \"blink_common_unittests\": \"ee54f7f5cefb303f2843e3fca7e94b70d56dfe6f\", \"blink_fuzzer_unittests\": \"2cd3be5555aaeee21bb5b16a1b6a0752b016575e\", \"blink_heap_unittests\": \"c95cca4fe42b52ce7d6891d60397a2f3905f4091\", \"blink_platform_unittests\": \"d2ff044fd3acd49b4bc9fddaaf6d8f39cb41615f\", \"blink_unittests\": \"5708729e895ca384a274cfe773919b1b32d80aca\", \"boringssl_crypto_tests\": \"911304cf904d33614972214a63a87322a1e112a0\", \"boringssl_ssl_tests\": \"9475bd0d9cbfe45413a1c9d5ccc8c06685e90752\", \"browser_switcher_bho_unittests\": \"7058291fe52e761a0205ec7b04f1597874ca0236\", \"browser_tests\": \"a21b684cf6e2f672c88bc1362b56b8e7bef2f583\", \"capture_unittests\": \"c09ee383096f0cece0ab7a06f9d45f5bc119a844\", \"cast_unittests\": \"f58fea5a5f6bd8d71d35349cc3f0dc0d025bdeab\", \"cc_unittests\": \"3b36359fc5f1b8d0cdc9f0a6a53479db8203087b\", \"chrome_app_unittests\": \"e109c7cb7daf2cbd277ae4220a6db49338abf3d2\", \"chrome_cleaner_unittests\": \"067a8f5883ce21805e8d916c217aa1055269cb04\", \"chrome_elf_unittests\": \"0e5f572e6d53f6026a4aade6463b8e7243071f59\", \"chromedriver_unittests\": \"03ee23e261d9b01c08f644e9884c1e5406540482\", \"components_browsertests\": \"dca2d9e13ad6f7a41aa6c19f9cbd48d37d1278a0\", \"components_unittests\": \"8e62a7b31b5301a63f209f8025d19cfce19d2290\", \"compositor_unittests\": \"b23c19198033d0f124b4ce302ea1d7c28a3b5320\", \"content_browsertests\": \"c7ccd194b0b7b1b33df8379db81f7e4026b3ec16\", \"content_unittests\": \"634990c1773d7a6921c0776461fcaea5b3bfccce\", \"courgette_unittests\": \"87d32dea05815b1174d2e84749c4d9cacc6ab5b2\", \"crashpad_tests\": \"6f60c714bad4b25fe8ef51a684123dba85e35e03\", \"cronet_tests\": \"1b1e74f82a0555e71f97482123e504ef4e57434c\", \"cronet_unittests\": \"9aef8fa3f8ddced33885a380feafc95b290cbb52\", \"crypto_unittests\": \"0f201dd55aef6809f632c65053c1e37bd82b26b4\", \"delayloads_unittests\": \"d03b2a23fe912b7df04b62825f58ab5b754b766f\", \"device_unittests\": \"fbbe566c05e258d3e8141b0ae568d71f84deb933\", \"display_unittests\": \"1a05b2fb5d7ccd03646c9ac5444af9b6843b2461\", \"elevation_service_unittests\": \"6ad8071e23893c9a4aa2ecc431976acdf7c42043\", \"events_unittests\": \"2cded6be821096f37a839dbe0a8d699812c344f4\", \"extensions_browsertests\": \"d923282bcc646bc6ee49d9fc9bad0881f474885f\", \"extensions_unittests\": \"1f84981404db6303f27120ca8bba8b2ae3606906\", \"filesystem_service_unittests\": \"9ec2b7db0717684d76b80bd51f7d16e14c098187\", \"gcm_unit_tests\": \"22eb20b15a0bd2bf78ae8dcd76e5b992ce436712\", \"gcp_unittests\": \"973d4aa500f50490d7b45c7b376a1f8da8b0da88\", \"gfx_unittests\": \"14b7c664f7e9ebea93cd2d3d9c03c2790d5f8b5f\", \"gin_unittests\": \"0ec65cae1ed9ded1be84b2c0e27f5945b7073e44\", \"google_apis_unittests\": \"ec34b7402e1aff92acc0ccbaa6d7cf4c6d2f494d\", \"gpu_unittests\": \"c126fb6ee415dc3f986419ffa70363b83335f1f0\", \"gwp_asan_unittests\": \"8d76535ef664a4c41ce319e9c2134ebbe3715c83\", \"headless_browsertests\": \"e7d1c3085ebe2893ff38a6704dffc4f589ab1d28\", \"headless_unittests\": \"5e91378fff5e4d28f4cdf076f7636d1606b197cd\", \"install_static_unittests\": \"99636eeb7242f3f49034460fbbbdf7dd0f34829e\", \"installer_util_unittests\": \"d74bef8073a409b29134d08adccdcdd974399024\", \"interactive_ui_tests\": \"31ce89824b8fd588e0e42a19ef7e8b8b65b17516\", \"ipc_tests\": \"099f0ae01ad3960efb110e173cf4d35fb6f2c455\", \"jingle_unittests\": \"303969355d70c69ae1ff7d3b9b5d77a45230434b\", \"latency_unittests\": \"0880dd5c9c98c0176329154c83633c37bdf5cfc3\", \"libjingle_xmpp_unittests\": \"0d71a6aba8bbae36633a43291510bbca0c27b3a5\", \"liburlpattern_unittests\": \"ad593f62064d240095e5074f003b80fab4134ca8\", \"media_blink_unittests\": \"10877b2f3529140d8d6be7c60add30750befec24\", \"media_unittests\": \"6dd6108e8a612e3bddf8828001d6dd6e28384869\", \"message_center_unittests\": \"005daef64a17e5389c13e98dc979c2e2289a22bb\", \"midi_unittests\": \"0696ff047d751677bdbafdb867d50f4345176680\", \"mojo_core_unittests\": \"ba6590fa2625f65a18c0454c12cfec227e1d6eda\", \"mojo_unittests\": \"8e84d4e4b87aeccc84b53c75deb690d69dae3006\", \"nacl_loader_unittests\": \"71419db4cdd480a88687f042adbcec95ee0ebf10\", \"native_theme_unittests\": \"6b6cfc18233812f483ae074b91198dc334dce27f\", \"net_unittests\": \"ebfb394b95732d2e748c00bf37e2cbd1f11aff96\", \"notification_helper_unittests\": \"ae0889d8ae239a77c8c203a8d032ec0f28987b4d\", \"pdf_unittests\": \"7407aa24c0eb4ce7d4be79c9240364b3825891f3\", \"perfetto_unittests\": \"f543f582118a9110adbf9eef37e24c5f1068ab5c\", \"ppapi_unittests\": \"607005874e2bf88519753aa598f6fda6734aa08b\", \"printing_unittests\": \"ee03c0bb3e5bf754f9acfbb9c7f4b9960562cfae\", \"remoting_unittests\": \"d02ba335dcb25d02cbae3d7ab795e580b2a0edbe\", \"sbox_integration_tests\": \"d54c43f26216b11f7b347418b866628090d973cd\", \"sbox_unittests\": \"a7ffac1c2bdf296e359ff62247d4f54ef97d07e6\", \"sbox_validation_tests\": \"169a995a596dd8941fabb1b723a9aa340679f19c\", \"service_manager_unittests\": \"c31259474e3c40069093f6f4dc3db2ea48bf78a4\", \"services_unittests\": \"ab00a7fb62690d0059c361e74f97996b0b5849c9\", \"setup_unittests\": \"0e14d34b78cce409c4d237b4f5d261711777b9a9\", \"shell_dialogs_unittests\": \"163898678f258fe0d25a74a4dc3fbde26c354c6c\", \"skia_unittests\": \"75d493cbd0500c427fc9506aef3342f357cab9c4\", \"snapshot_unittests\": \"70f79e057066e06021c8f0abe8688227dca34f84\", \"sql_unittests\": \"1bf0618e639208cdc4fb05c781b4560723a1ebb6\", \"storage_unittests\": \"3be4b7e5fec2351417cadd734b48ef9d5dcb34eb\", \"sync_integration_tests\": \"ca69595fadaf69d3b62817cd59642f8002d695db\", \"traffic_annotation_auditor_unittests\": \"18abf95296726d10437c3c33a66df746c9287465\", \"ui_base_unittests\": \"2526193767e0dbee765973aee791ed9f22d31421\", \"ui_touch_selection_unittests\": \"a3e956267fe6886ac03620416ed0150eb9f90077\", \"unit_tests\": \"ca2caea5223f5379f9dc9828a569f55dc20f6d81\", \"updater_tests\": \"9c2fef6f3ed0060168a57c38a945179f2a85739a\", \"url_unittests\": \"f1fa5f09d62680717cdab275a559092e75f0af14\", \"views_unittests\": \"2d8b49edf2284241e39affc7d17b7932260a0825\", \"viz_unittests\": \"fcd01b7a0915bab480b94aa8c8d32a162f52d053\", \"vr_common_unittests\": \"8ae56b580f969a272f7713076be2d7f8d785d660\", \"vr_pixeltests\": \"fd914d37286edffa5971f12cb78a95a8ca807b7f\", \"weblayer_browsertests\": \"88ab8d5ce3896a4d0d88b22a91fc7de1487d7276\", \"weblayer_unittests\": \"4bf19e673c131432a429b04340eb4183194833b5\", \"wm_unittests\": \"eb5d4730b5fe7dec2f0cc7caf8c8038e07f33bdc\", \"wtf_unittests\": \"115c836741917739794552228f100efb3e8f460b\", \"zlib_unittests\": \"1e93b0009accfca348e2bfab05834c119feed9dd\", \"zucchini_unittests\": \"d3db0455926873c5516fdd54f258d610aa865ff0\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-8-jw2s\"], \"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\": [\"5685-1877293\"], \"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": "1612859194571551",
"status": "COMPLETED",
"status_changed_ts": "1612863207371821",
"tags": [
"build_address:luci.chromium.ci/win-asan/17251",
"builder:win-asan",
"buildset:commit/git/cc4c0a07d9388eb042c0674c3e06da1d5bb16e9f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc4c0a07d9388eb042c0674c3e06da1d5bb16e9f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021218348689684352",
"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/8855788282121701152/+/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:519eb7bd455d1910",
"user_agent:luci-scheduler"
],
"updated_ts": "1612863207443236",
"url": "https://ci.chromium.org/b/8855788282121701152",
"utcnow_ts": "1612863208221220"
}
}
[09:33:28.231] Invocation finished in 1h7m7.861829976s with status SUCCEEDED