[17:42:10.549] New invocation is queued and will start shortly
[17:42:11.653] Starting the invocation (attempt 1)
[17:42:11.689] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:42:11.689] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:42:11.690] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026890987391985568",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"52d1200f6d4dd29ff845c460dd894142fab5350e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026890987391985568",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/52d1200f6d4dd29ff845c460dd894142fab5350e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/52d1200f6d4dd29ff845c460dd894142fab5350e",
"gitiles_ref:refs/heads/master"
]
}
[17:42:12.475] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607449331810157",
"id": "8861460925406172192",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"52d1200f6d4dd29ff845c460dd894142fab5350e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607449332307492",
"tags": [
"build_address:luci.chromium.ci/win-asan/16103",
"builder:win-asan",
"buildset:commit/git/52d1200f6d4dd29ff845c460dd894142fab5350e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/52d1200f6d4dd29ff845c460dd894142fab5350e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026890987391985568",
"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/8861460925406172192/+/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": "1607449332307641",
"url": "https://ci.chromium.org/b/8861460925406172192",
"utcnow_ts": "1607449332462182"
}
}
[17:42:12.475] Task URL: https://ci.chromium.org/b/8861460925406172192
[17:42:12.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:2:0) after 1m0s
[17:42:16.360] Received PubSub notification, asking Buildbucket for the build status
[17:42:16.427] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:12.920] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:2:0)
[17:43:12.920] Timer tick, asking Buildbucket for the build status
[17:43:13.043] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:13.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:4:0) after 1m0s
[17:44:13.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:4:0)
[17:44:13.086] Timer tick, asking Buildbucket for the build status
[17:44:13.239] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:13.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:5:0) after 1m0s
[17:45:13.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:5:0)
[17:45:13.419] Timer tick, asking Buildbucket for the build status
[17:45:13.537] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:13.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:6:0) after 1m0s
[17:46:13.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:6:0)
[17:46:13.761] Timer tick, asking Buildbucket for the build status
[17:46:14.123] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:14.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:7:0) after 1m0s
[17:47:14.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:7:0)
[17:47:14.190] Timer tick, asking Buildbucket for the build status
[17:47:14.276] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:14.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:8:0) after 1m0s
[17:48:14.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:8:0)
[17:48:14.280] Timer tick, asking Buildbucket for the build status
[17:48:14.353] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:14.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:9:0) after 1m0s
[17:49:14.470] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:9:0)
[17:49:14.470] Timer tick, asking Buildbucket for the build status
[17:49:14.916] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:14.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:10:0) after 1m0s
[17:50:14.982] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:10:0)
[17:50:14.982] Timer tick, asking Buildbucket for the build status
[17:50:15.191] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:15.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:11:0) after 1m0s
[17:51:15.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:11:0)
[17:51:15.237] Timer tick, asking Buildbucket for the build status
[17:51:15.292] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:15.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:12:0) after 1m0s
[17:52:15.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:12:0)
[17:52:15.364] Timer tick, asking Buildbucket for the build status
[17:52:15.556] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:15.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:13:0) after 1m0s
[17:53:15.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:13:0)
[17:53:15.565] Timer tick, asking Buildbucket for the build status
[17:53:15.732] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:15.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:14:0) after 1m0s
[17:54:15.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:14:0)
[17:54:15.782] Timer tick, asking Buildbucket for the build status
[17:54:15.839] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:15.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:15:0) after 1m0s
[17:55:15.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:15:0)
[17:55:15.903] Timer tick, asking Buildbucket for the build status
[17:55:16.268] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:16.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:16:0) after 1m0s
[17:56:16.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:16:0)
[17:56:16.381] Timer tick, asking Buildbucket for the build status
[17:56:16.505] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:16.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:17:0) after 1m0s
[17:57:16.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:17:0)
[17:57:16.532] Timer tick, asking Buildbucket for the build status
[17:57:16.649] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:16.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:18:0) after 1m0s
[17:58:16.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:18:0)
[17:58:16.728] Timer tick, asking Buildbucket for the build status
[17:58:16.814] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:16.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:19:0) after 1m0s
[17:59:16.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:19:0)
[17:59:16.827] Timer tick, asking Buildbucket for the build status
[17:59:16.940] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:16.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:20:0) after 1m0s
[18:00:16.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:20:0)
[18:00:16.947] Timer tick, asking Buildbucket for the build status
[18:00:17.080] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:17.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:21:0) after 1m0s
[18:01:17.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:21:0)
[18:01:17.126] Timer tick, asking Buildbucket for the build status
[18:01:17.204] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:17.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:22:0) after 1m0s
[18:02:17.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:22:0)
[18:02:17.245] Timer tick, asking Buildbucket for the build status
[18:02:17.362] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:17.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:23:0) after 1m0s
[18:03:17.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:23:0)
[18:03:17.389] Timer tick, asking Buildbucket for the build status
[18:03:17.508] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:17.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:24:0) after 1m0s
[18:04:18.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:24:0)
[18:04:18.458] Timer tick, asking Buildbucket for the build status
[18:04:18.571] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:18.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:25:0) after 1m0s
[18:05:18.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:25:0)
[18:05:18.795] Timer tick, asking Buildbucket for the build status
[18:05:18.928] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:18.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:26:0) after 1m0s
[18:06:19.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:26:0)
[18:06:19.169] Timer tick, asking Buildbucket for the build status
[18:06:19.302] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:19.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:27:0) after 1m0s
[18:07:19.367] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:27:0)
[18:07:19.367] Timer tick, asking Buildbucket for the build status
[18:07:19.538] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:19.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:28:0) after 1m0s
[18:08:19.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:28:0)
[18:08:19.555] Timer tick, asking Buildbucket for the build status
[18:08:19.677] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:19.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:29:0) after 1m0s
[18:09:19.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:29:0)
[18:09:19.788] Timer tick, asking Buildbucket for the build status
[18:09:19.926] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:19.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:30:0) after 1m0s
[18:10:20.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:30:0)
[18:10:20.042] Timer tick, asking Buildbucket for the build status
[18:10:20.782] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:20.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:31:0) after 1m0s
[18:11:20.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:31:0)
[18:11:20.837] Timer tick, asking Buildbucket for the build status
[18:11:21.022] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:21.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:32:0) after 1m0s
[18:12:21.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:32:0)
[18:12:21.101] Timer tick, asking Buildbucket for the build status
[18:12:21.441] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:21.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:33:0) after 1m0s
[18:13:21.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:33:0)
[18:13:21.551] Timer tick, asking Buildbucket for the build status
[18:13:21.708] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:21.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:34:0) after 1m0s
[18:14:21.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:34:0)
[18:14:21.724] Timer tick, asking Buildbucket for the build status
[18:14:21.855] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:21.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:35:0) after 1m0s
[18:15:21.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:35:0)
[18:15:21.889] Timer tick, asking Buildbucket for the build status
[18:15:22.043] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:22.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:36:0) after 1m0s
[18:16:22.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:36:0)
[18:16:22.157] Timer tick, asking Buildbucket for the build status
[18:16:22.810] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:22.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:37:0) after 1m0s
[18:17:22.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:37:0)
[18:17:22.942] Timer tick, asking Buildbucket for the build status
[18:17:23.078] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:23.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:38:0) after 1m0s
[18:18:23.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:38:0)
[18:18:23.146] Timer tick, asking Buildbucket for the build status
[18:18:23.507] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:23.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:39:0) after 1m0s
[18:19:23.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:39:0)
[18:19:23.673] Timer tick, asking Buildbucket for the build status
[18:19:23.869] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:23.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:40:0) after 1m0s
[18:20:23.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:40:0)
[18:20:23.962] Timer tick, asking Buildbucket for the build status
[18:20:24.094] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:24.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:41:0) after 1m0s
[18:21:24.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:41:0)
[18:21:24.186] Timer tick, asking Buildbucket for the build status
[18:21:24.492] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:24.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:42:0) after 1m0s
[18:22:24.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:42:0)
[18:22:24.633] Timer tick, asking Buildbucket for the build status
[18:22:25.133] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:25.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:43:0) after 1m0s
[18:23:25.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:43:0)
[18:23:25.219] Timer tick, asking Buildbucket for the build status
[18:23:25.307] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:25.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:44:0) after 1m0s
[18:24:25.376] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:44:0)
[18:24:25.376] Timer tick, asking Buildbucket for the build status
[18:24:25.547] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:25.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:45:0) after 1m0s
[18:25:25.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:45:0)
[18:25:25.693] Timer tick, asking Buildbucket for the build status
[18:25:25.806] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:25.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:46:0) after 1m0s
[18:26:25.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:46:0)
[18:26:25.895] Timer tick, asking Buildbucket for the build status
[18:26:25.974] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:25.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:47:0) after 1m0s
[18:27:26.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:47:0)
[18:27:26.156] Timer tick, asking Buildbucket for the build status
[18:27:26.308] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:26.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:48:0) after 1m0s
[18:28:26.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:48:0)
[18:28:26.544] Timer tick, asking Buildbucket for the build status
[18:28:26.658] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:26.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:49:0) after 1m0s
[18:29:26.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:49:0)
[18:29:26.805] Timer tick, asking Buildbucket for the build status
[18:29:26.883] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:26.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:50:0) after 1m0s
[18:30:26.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:50:0)
[18:30:26.883] Timer tick, asking Buildbucket for the build status
[18:30:28.124] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:28.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:51:0) after 1m0s
[18:31:28.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:51:0)
[18:31:28.267] Timer tick, asking Buildbucket for the build status
[18:31:28.406] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:28.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:52:0) after 1m0s
[18:32:28.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:52:0)
[18:32:28.509] Timer tick, asking Buildbucket for the build status
[18:32:28.669] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:28.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:53:0) after 1m0s
[18:33:28.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:53:0)
[18:33:28.839] Timer tick, asking Buildbucket for the build status
[18:33:29.283] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:29.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:54:0) after 1m0s
[18:34:29.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:54:0)
[18:34:29.438] Timer tick, asking Buildbucket for the build status
[18:34:29.506] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:29.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:55:0) after 1m0s
[18:35:29.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:55:0)
[18:35:29.527] Timer tick, asking Buildbucket for the build status
[18:35:29.646] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:29.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:56:0) after 1m0s
[18:36:29.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:56:0)
[18:36:29.646] Timer tick, asking Buildbucket for the build status
[18:36:29.855] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:29.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:57:0) after 1m0s
[18:37:29.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:57:0)
[18:37:29.900] Timer tick, asking Buildbucket for the build status
[18:37:30.508] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:30.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:58:0) after 1m0s
[18:38:30.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:58:0)
[18:38:30.647] Timer tick, asking Buildbucket for the build status
[18:38:30.782] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:30.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:59:0) after 1m0s
[18:39:30.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:59:0)
[18:39:30.932] Timer tick, asking Buildbucket for the build status
[18:39:31.009] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:31.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:60:0) after 1m0s
[18:40:31.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:60:0)
[18:40:31.095] Timer tick, asking Buildbucket for the build status
[18:40:31.178] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:31.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:61:0) after 1m0s
[18:41:31.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:61:0)
[18:41:31.338] Timer tick, asking Buildbucket for the build status
[18:41:31.433] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:31.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:62:0) after 1m0s
[18:42:31.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:62:0)
[18:42:31.531] Timer tick, asking Buildbucket for the build status
[18:42:31.640] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:31.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:63:0) after 1m0s
[18:43:31.702] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:63:0)
[18:43:31.702] Timer tick, asking Buildbucket for the build status
[18:43:31.783] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:31.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:64:0) after 1m0s
[18:44:31.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:64:0)
[18:44:31.823] Timer tick, asking Buildbucket for the build status
[18:44:31.932] Build 8861460925406172192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:31.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026890987391985568:65:0) after 1m0s
[18:45:23.815] Received PubSub notification, asking Buildbucket for the build status
[18:45:23.892] Build 8861460925406172192: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:45:23.892] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607453120659363",
"created_by": "project:chromium",
"created_ts": "1607449331810157",
"id": "8861460925406172192",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"52d1200f6d4dd29ff845c460dd894142fab5350e\"}}",
"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}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$build/goma.jobs\", \"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"52d1200f6d4dd29ff845c460dd894142fab5350e\", \"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-z6mr\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16103, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"4f26a81e8710a41a7be6761322115d4c39b1fd24\", \"got_dawn_revision\": \"61355d416d9c568bcfbed6518516fbe5ef219e42\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"52d1200f6d4dd29ff845c460dd894142fab5350e\", \"got_revision_cp\": \"refs/heads/master@{#834746}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"84b9bb6621c7eae8da034d31aceab780d8b1abdc\", \"got_v8_revision\": \"4670e2c2930e89462938e85ba131caeb7c0d0419\", \"got_v8_revision_cp\": \"refs/heads/8.9.121@{#1}\", \"got_webrtc_revision\": \"bb1a28de3cf17476142e9de8ee12fe6c567d06c1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32789}\", \"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\": \"52d1200f6d4dd29ff845c460dd894142fab5350e\", \"swarm_hashes_refs/heads/master(at){#834746}_without_patch\": {\"absl_hardening_tests\": \"2f48598522621c87886edf03118514e4833559d2\", \"accessibility_unittests\": \"1bc5ecadb5a35973cd8874c6c0c37e69b5e22e3d\", \"angle_unittests\": \"56b690e42c42beb27b76d0754a7f00baa6fd5f3a\", \"app_shell_unittests\": \"e750eaba346d720ffbdadaf140ed30552f872b3c\", \"aura_unittests\": \"c7b09060eccad4aa35d60e8bfe06e1c79ae307a6\", \"base_unittests\": \"6d3181e5019e66f16834e4928974cd45f7dde91c\", \"base_util_unittests\": \"589452762fb420728f1a0dc850842c8ea8d45bdb\", \"blink_common_unittests\": \"9e10f811b65736ad4b5e8ac543670cb90772faaf\", \"blink_fuzzer_unittests\": \"fd052fce4abc681d7914a31af1a4809e179fe338\", \"blink_heap_unittests\": \"e1bc48eedd5bf1098a4a59787ee40848c25e414c\", \"blink_platform_unittests\": \"dcad5d4601b1d3ef4e4195ff6b91ec3db25edaa0\", \"blink_unittests\": \"38c073c4da8acd8d2f3aee97633f989485b83515\", \"boringssl_crypto_tests\": \"93c1021797e45dea907be7f47ed420353fcf0278\", \"boringssl_ssl_tests\": \"dc08da3eca46433ab1978383a04323505c2f71a1\", \"browser_switcher_bho_unittests\": \"a75b90f2c894b66e990c63c7ca627e03f9de3017\", \"browser_tests\": \"8a3bdf141a05d36378b00e136ee09237f7dea8c3\", \"capture_unittests\": \"c5e30192065c2c0c61ad983f379232fc7160b229\", \"cast_unittests\": \"50946a583be45fc286268edba3d1d3b82c5c7700\", \"cc_unittests\": \"803af1d65f9904752694ab8e69df11abef590b55\", \"chrome_app_unittests\": \"dc077b68a58a7cacc25334aa8cdbd062228f854e\", \"chrome_cleaner_unittests\": \"c7c7f1620c1e111fbeef31e9252f485cb407c2cb\", \"chrome_elf_unittests\": \"94b8f103067123cf9952c21e9880e996eabf6c5a\", \"chromedriver_unittests\": \"c39ffa7e6164cc7f77a9efa9d7079e27a783f29b\", \"components_browsertests\": \"9711c3d3072bfa4525f9312f18ac3bf7b1c702ee\", \"components_unittests\": \"a216bc6c0c6f756c6a5ef29158192d36fd8ecc74\", \"compositor_unittests\": \"592562932323d09997d8522fda5ea36dda31eaf2\", \"content_browsertests\": \"fb6516b1f78087fe9363b3c2640c96e0ec226613\", \"content_unittests\": \"9517991fc6728b61b0ce6d830d0268d9d3591fb1\", \"courgette_unittests\": \"8f418bbd71cdbf2baf42c46962004f68dc715c37\", \"crashpad_tests\": \"568e1be747b46173c575cfc7a1260eee60873604\", \"cronet_tests\": \"7a8f161e6566689d7227f0220fff7ca92ca18c62\", \"cronet_unittests\": \"43126e7220b82a1ab0e184f04696b1f246dd326e\", \"crypto_unittests\": \"e71ce40856d68627c7ddfc6b8b1f759ea43965f0\", \"delayloads_unittests\": \"d486d7f83160e24f3c79e5dd0eb519ac9a504e45\", \"device_unittests\": \"52ea98e1a10d3615aff0b42141962ece4fc6b929\", \"display_unittests\": \"13e0116745c5057eddb4fca3aca35150a59f826c\", \"elevation_service_unittests\": \"5ac97694620c00ccf979d8f3e3c729de25d7bce0\", \"events_unittests\": \"a832c968bb2a5b149a3f28d8fe13a2243fbcf36e\", \"extensions_browsertests\": \"d36fa1055ff520b066e3a636f33d89e70f3a9a58\", \"extensions_unittests\": \"cc18b6032394c769c52a392a87e7e368713d6a6d\", \"filesystem_service_unittests\": \"7fd836c823dd7c3edc06cb98997cb9b591f3511f\", \"gcm_unit_tests\": \"ca0223bbd7a6ad130d8e397c7a23251424ae4154\", \"gcp_unittests\": \"1d25a54efa5534c9a9eb0b9d325949063a90e543\", \"gfx_unittests\": \"0fd790515f77fa7296af643fbb0d20af4ae3d392\", \"gin_unittests\": \"d98d2b3537a57325b393b92fe40622f905e26176\", \"google_apis_unittests\": \"822277c8459fad294d1c60fc468c271bbd407b49\", \"gpu_unittests\": \"ecf881d909953061564f2d496a1caab476aff01c\", \"gwp_asan_unittests\": \"227915b6f90ee81dcbf226dbcc48ed0e77b39873\", \"headless_browsertests\": \"8e0fd7602a6e5f2830a058ac5333ae17c396df21\", \"headless_unittests\": \"493313548beef16b50f3f184f02041369c0f405c\", \"install_static_unittests\": \"8bb8d0aae532c6d5dde1e4213a05016774f54db6\", \"installer_util_unittests\": \"2c8d454abc747af302f501a8c196fc08785ac1b2\", \"interactive_ui_tests\": \"4edba7c29a1e4c0c8c69a23bd03c47899264c07d\", \"ipc_tests\": \"e5ebe5f7419aeb9dbacfb748a368fd6f4a38cd43\", \"jingle_unittests\": \"43245ddf22acda412566251fd878b7fa92c94047\", \"latency_unittests\": \"48fa919869c725a298f546af83819686a80b2e27\", \"libjingle_xmpp_unittests\": \"b6d5c4fac8e406364dfc867be6ca29ab8a30e7c5\", \"liburlpattern_unittests\": \"fbf454f9656925a9226679522f056b020b5e7a53\", \"media_blink_unittests\": \"fc9975220fc4ef8c6fb8db7c67d842e0972a764e\", \"media_unittests\": \"8fee4efc1418e2f2b9cd7cc2c88b85aacfed339a\", \"message_center_unittests\": \"e74c920234de76b7bbbf0ed1af95360415764d6b\", \"midi_unittests\": \"09194330081ba93fde78e53317b9dc4da276defb\", \"mojo_core_unittests\": \"b295b3e25393b6bec1df1931d997b19a19c6d1e6\", \"mojo_unittests\": \"bb60d052e9bfa037c83071ff0a06b724329e821c\", \"nacl_loader_unittests\": \"d83b5aa3ff06e73e93bc28e1fdb5dc4e99049881\", \"native_theme_unittests\": \"165cafc0ee5195b58a87ed7ed5ec07029ffa8f18\", \"net_unittests\": \"80213f5e9dee5b05d256a09b8d8f485b458315de\", \"notification_helper_unittests\": \"62906df2271b74e12efdca197d0d27a7cd2e373e\", \"pdf_unittests\": \"34e4f465c5f631a9709c7a9db4fec911546b64d1\", \"perfetto_unittests\": \"7941ae818e497d76045506ac9a84d62406a5b7b3\", \"ppapi_unittests\": \"4c49506b6703aab8c1b53aebac600c27999403f5\", \"printing_unittests\": \"6eb4d58cec390d9efff6bc3c3089a48efe8e7051\", \"remoting_unittests\": \"3deb4b50caf5b9357cd7737d509d18b130414116\", \"sbox_integration_tests\": \"a9dcd9a93055c4386a50fffc87820e3ef1b008f0\", \"sbox_unittests\": \"59e6429be4189b82253dde5139efbfb1daca8cf1\", \"sbox_validation_tests\": \"81255c0458bab267d8335512835a16e9eccdebd2\", \"service_manager_unittests\": \"1d04f0611be9e60658d6345632b63a3091b2c10e\", \"services_unittests\": \"2bf724b9d60d2521565508a3f4d00207350ec68c\", \"setup_unittests\": \"9f08f8aa11290bd430fc80e6fd03112cbc9f4d81\", \"shell_dialogs_unittests\": \"4ccb19e0a12806444aeb4ddad8a9a96abf4b23d6\", \"skia_unittests\": \"569367c0989f4000bbe21b67167d91e5d4a04a03\", \"snapshot_unittests\": \"26fe587d5edea38f5b155279d6a84a3518ea1fc1\", \"sql_unittests\": \"70a62189b6cddbfebb5466d28a649707c65d9a2b\", \"storage_unittests\": \"91394abe6c4a61aa38e15ce8734e43f03eb612ea\", \"sync_integration_tests\": \"3c4d881564c4e8e71fcd6497a8cad66dd9b1c035\", \"traffic_annotation_auditor_unittests\": \"4f44dd6d6a32a9a2f276223857614bce475bfda5\", \"ui_base_unittests\": \"8279f1eea2a7817b9142e0f15d1344b84cd907db\", \"ui_touch_selection_unittests\": \"5b6658f434114462de79a9a627f8ccfe768859f7\", \"unit_tests\": \"815e80c1fa03e01c38b5ac4450f28f20bc1d9882\", \"updater_tests\": \"76090de07755bee584547536ac9447d0223a29b2\", \"url_unittests\": \"b612bb8cfefe3f0290287dd5f85c340bb6fd5bf2\", \"views_unittests\": \"9d7a56d8407c7f56075400b663909275d3907368\", \"viz_unittests\": \"69c3382d584e0d245a13fe0e614c574e3473ec96\", \"vr_common_unittests\": \"da2ca6282b8c3855e676dc5264c0a182b517f7e3\", \"vr_pixeltests\": \"c9889bb5bea23031f42d0ee202ab761ff0d0c071\", \"weblayer_browsertests\": \"14d3851b1318a95007a251c74349bc511a81cc38\", \"weblayer_unittests\": \"efb1639096f8afb83abbbc5de7c4c543af074c0d\", \"wm_unittests\": \"c60a0649eba96d84c68f747b46a784d38a645dbf\", \"wtf_unittests\": \"8e9cc370b9ce52cf252a035d8915701580ea5f31\", \"zlib_unittests\": \"2b1a6aa0dd79a978368cfbb9c045d29615bb49c9\", \"zucchini_unittests\": \"637e98e80f9e718a18d0435875e94b20a681d5e8\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"builder_f77c1c08af9d0a4522ad60c9181aa925a8ae87fd7ec8443f284fb40f3b6bc27b_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-z6mr\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"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\": [\"5640-d0eb544\"], \"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": "1607449335744362",
"status": "COMPLETED",
"status_changed_ts": "1607453121012542",
"tags": [
"build_address:luci.chromium.ci/win-asan/16103",
"builder:win-asan",
"buildset:commit/git/52d1200f6d4dd29ff845c460dd894142fab5350e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/52d1200f6d4dd29ff845c460dd894142fab5350e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026890987391985568",
"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/8861460925406172192/+/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:505c43d3edcf9a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1607453121030161",
"url": "https://ci.chromium.org/b/8861460925406172192",
"utcnow_ts": "1607453123882170"
}
}
[18:45:23.892] Invocation finished in 1h3m13.35868801s with status SUCCEEDED