[01:17:52.511] New invocation is queued and will start shortly
[01:17:53.598] Starting the invocation (attempt 1)
[01:17:53.634] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:17:53.634] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:17:53.634] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025684556707177104",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9ef5ad0fa3377fa37df268bce2f485cf7593b828\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9025684556707177104",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/9ef5ad0fa3377fa37df268bce2f485cf7593b828",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9ef5ad0fa3377fa37df268bce2f485cf7593b828",
"gitiles_ref:refs/heads/master"
]
}
[01:17:54.344] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608599873716817",
"id": "8860254494777111264",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9ef5ad0fa3377fa37df268bce2f485cf7593b828\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608599874161815",
"tags": [
"build_address:luci.chromium.ci/win-asan/16373",
"builder:win-asan",
"buildset:commit/git/9ef5ad0fa3377fa37df268bce2f485cf7593b828",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9ef5ad0fa3377fa37df268bce2f485cf7593b828",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025684556707177104",
"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/8860254494777111264/+/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": "1608599874161925",
"url": "https://ci.chromium.org/b/8860254494777111264",
"utcnow_ts": "1608599874334494"
}
}
[01:17:54.344] Task URL: https://ci.chromium.org/b/8860254494777111264
[01:17:54.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:2:0) after 1m0s
[01:18:15.003] Received PubSub notification, asking Buildbucket for the build status
[01:18:15.236] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:54.367] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:2:0)
[01:18:54.367] Timer tick, asking Buildbucket for the build status
[01:18:54.475] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:54.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:4:0) after 1m0s
[01:19:54.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:4:0)
[01:19:54.504] Timer tick, asking Buildbucket for the build status
[01:19:54.620] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:54.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:5:0) after 1m0s
[01:20:54.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:5:0)
[01:20:54.643] Timer tick, asking Buildbucket for the build status
[01:20:55.022] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:55.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:6:0) after 1m0s
[01:21:55.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:6:0)
[01:21:55.056] Timer tick, asking Buildbucket for the build status
[01:21:55.233] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:55.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:7:0) after 1m0s
[01:22:55.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:7:0)
[01:22:55.252] Timer tick, asking Buildbucket for the build status
[01:22:55.357] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:55.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:8:0) after 1m0s
[01:23:55.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:8:0)
[01:23:55.330] Timer tick, asking Buildbucket for the build status
[01:23:55.542] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:55.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:9:0) after 1m0s
[01:24:55.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:9:0)
[01:24:55.528] Timer tick, asking Buildbucket for the build status
[01:24:55.604] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:55.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:10:0) after 1m0s
[01:25:55.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:10:0)
[01:25:55.768] Timer tick, asking Buildbucket for the build status
[01:25:55.855] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:55.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:11:0) after 1m0s
[01:26:55.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:11:0)
[01:26:55.896] Timer tick, asking Buildbucket for the build status
[01:26:56.030] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:56.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:12:0) after 1m0s
[01:27:56.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:12:0)
[01:27:56.053] Timer tick, asking Buildbucket for the build status
[01:27:56.171] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:56.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:13:0) after 1m0s
[01:28:56.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:13:0)
[01:28:56.190] Timer tick, asking Buildbucket for the build status
[01:28:56.265] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:56.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:14:0) after 1m0s
[01:29:56.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:14:0)
[01:29:56.317] Timer tick, asking Buildbucket for the build status
[01:29:56.382] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:56.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:15:0) after 1m0s
[01:30:56.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:15:0)
[01:30:56.408] Timer tick, asking Buildbucket for the build status
[01:30:56.627] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:56.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:16:0) after 1m0s
[01:31:56.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:16:0)
[01:31:56.771] Timer tick, asking Buildbucket for the build status
[01:31:56.849] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:56.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:17:0) after 1m0s
[01:32:56.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:17:0)
[01:32:56.932] Timer tick, asking Buildbucket for the build status
[01:32:57.202] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:57.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:18:0) after 1m0s
[01:33:57.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:18:0)
[01:33:57.239] Timer tick, asking Buildbucket for the build status
[01:33:57.324] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:57.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:19:0) after 1m0s
[01:34:57.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:19:0)
[01:34:57.411] Timer tick, asking Buildbucket for the build status
[01:34:57.502] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:57.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:20:0) after 1m0s
[01:35:57.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:20:0)
[01:35:57.595] Timer tick, asking Buildbucket for the build status
[01:35:57.719] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:57.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:21:0) after 1m0s
[01:36:57.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:21:0)
[01:36:57.742] Timer tick, asking Buildbucket for the build status
[01:36:57.832] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:57.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:22:0) after 1m0s
[01:37:57.876] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:22:0)
[01:37:57.876] Timer tick, asking Buildbucket for the build status
[01:37:58.017] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:58.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:23:0) after 1m0s
[01:38:58.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:23:0)
[01:38:58.016] Timer tick, asking Buildbucket for the build status
[01:38:58.159] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:58.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:24:0) after 1m0s
[01:39:58.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:24:0)
[01:39:58.259] Timer tick, asking Buildbucket for the build status
[01:39:58.603] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:58.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:25:0) after 1m0s
[01:40:58.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:25:0)
[01:40:58.622] Timer tick, asking Buildbucket for the build status
[01:40:58.766] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:58.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:26:0) after 1m0s
[01:41:58.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:26:0)
[01:41:58.814] Timer tick, asking Buildbucket for the build status
[01:41:59.042] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:59.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:27:0) after 1m0s
[01:42:59.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:27:0)
[01:42:59.195] Timer tick, asking Buildbucket for the build status
[01:42:59.431] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:59.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:28:0) after 1m0s
[01:43:59.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:28:0)
[01:43:59.454] Timer tick, asking Buildbucket for the build status
[01:43:59.787] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:59.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:29:0) after 1m0s
[01:44:59.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:29:0)
[01:44:59.813] Timer tick, asking Buildbucket for the build status
[01:45:00.307] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:00.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:30:0) after 1m0s
[01:46:00.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:30:0)
[01:46:00.507] Timer tick, asking Buildbucket for the build status
[01:46:00.843] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:00.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:31:0) after 1m0s
[01:47:01.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:31:0)
[01:47:01.068] Timer tick, asking Buildbucket for the build status
[01:47:01.214] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:01.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:32:0) after 1m0s
[01:48:01.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:32:0)
[01:48:01.237] Timer tick, asking Buildbucket for the build status
[01:48:01.688] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:01.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:33:0) after 1m0s
[01:49:01.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:33:0)
[01:49:01.976] Timer tick, asking Buildbucket for the build status
[01:49:02.235] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:02.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:34:0) after 1m0s
[01:50:02.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:34:0)
[01:50:02.308] Timer tick, asking Buildbucket for the build status
[01:50:02.473] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:02.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:35:0) after 1m0s
[01:51:02.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:35:0)
[01:51:02.512] Timer tick, asking Buildbucket for the build status
[01:51:02.985] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:02.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:36:0) after 1m0s
[01:52:02.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:36:0)
[01:52:02.998] Timer tick, asking Buildbucket for the build status
[01:52:03.258] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:03.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:37:0) after 1m0s
[01:53:03.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:37:0)
[01:53:03.288] Timer tick, asking Buildbucket for the build status
[01:53:03.470] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:03.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:38:0) after 1m0s
[01:54:03.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:38:0)
[01:54:03.503] Timer tick, asking Buildbucket for the build status
[01:54:03.620] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:03.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:39:0) after 1m0s
[01:55:03.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:39:0)
[01:55:03.621] Timer tick, asking Buildbucket for the build status
[01:55:04.024] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:04.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:40:0) after 1m0s
[01:56:04.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:40:0)
[01:56:04.048] Timer tick, asking Buildbucket for the build status
[01:56:04.257] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:04.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:41:0) after 1m0s
[01:57:04.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:41:0)
[01:57:04.281] Timer tick, asking Buildbucket for the build status
[01:57:05.248] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:05.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:42:0) after 1m0s
[01:58:05.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:42:0)
[01:58:05.407] Timer tick, asking Buildbucket for the build status
[01:58:05.593] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:05.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:43:0) after 1m0s
[01:59:05.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:43:0)
[01:59:05.648] Timer tick, asking Buildbucket for the build status
[01:59:06.118] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:06.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:44:0) after 1m0s
[02:00:06.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:44:0)
[02:00:06.166] Timer tick, asking Buildbucket for the build status
[02:00:06.525] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:06.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:45:0) after 1m0s
[02:01:07.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:45:0)
[02:01:07.381] Timer tick, asking Buildbucket for the build status
[02:01:07.534] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:07.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:46:0) after 1m0s
[02:02:07.574] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:46:0)
[02:02:07.574] Timer tick, asking Buildbucket for the build status
[02:02:07.669] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:07.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:47:0) after 1m0s
[02:03:07.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:47:0)
[02:03:07.915] Timer tick, asking Buildbucket for the build status
[02:03:08.013] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:08.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:48:0) after 1m0s
[02:04:09.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:48:0)
[02:04:09.403] Timer tick, asking Buildbucket for the build status
[02:04:09.871] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:09.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:49:0) after 1m0s
[02:05:09.966] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:49:0)
[02:05:09.966] Timer tick, asking Buildbucket for the build status
[02:05:10.064] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:10.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:50:0) after 1m0s
[02:06:10.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:50:0)
[02:06:10.077] Timer tick, asking Buildbucket for the build status
[02:06:10.548] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:10.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:51:0) after 1m0s
[02:07:10.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:51:0)
[02:07:10.557] Timer tick, asking Buildbucket for the build status
[02:07:10.902] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:10.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:52:0) after 1m0s
[02:08:10.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:52:0)
[02:08:10.937] Timer tick, asking Buildbucket for the build status
[02:08:11.075] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:11.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:53:0) after 1m0s
[02:09:11.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:53:0)
[02:09:11.086] Timer tick, asking Buildbucket for the build status
[02:09:11.299] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:11.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:54:0) after 1m0s
[02:10:11.278] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:54:0)
[02:10:11.278] Timer tick, asking Buildbucket for the build status
[02:10:11.388] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:11.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:55:0) after 1m0s
[02:11:11.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:55:0)
[02:11:11.442] Timer tick, asking Buildbucket for the build status
[02:11:11.713] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:11.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:56:0) after 1m0s
[02:12:11.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:56:0)
[02:12:11.790] Timer tick, asking Buildbucket for the build status
[02:12:11.890] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:11.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:57:0) after 1m0s
[02:13:11.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:57:0)
[02:13:11.925] Timer tick, asking Buildbucket for the build status
[02:13:12.105] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:12.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:58:0) after 1m0s
[02:14:12.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:58:0)
[02:14:12.207] Timer tick, asking Buildbucket for the build status
[02:14:12.445] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:12.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:59:0) after 1m0s
[02:15:12.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:59:0)
[02:15:12.512] Timer tick, asking Buildbucket for the build status
[02:15:12.762] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:12.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:60:0) after 1m0s
[02:16:12.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:60:0)
[02:16:12.841] Timer tick, asking Buildbucket for the build status
[02:16:13.338] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:13.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:61:0) after 1m0s
[02:17:13.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:61:0)
[02:17:13.331] Timer tick, asking Buildbucket for the build status
[02:17:13.469] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:13.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:62:0) after 1m0s
[02:18:13.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:62:0)
[02:18:13.586] Timer tick, asking Buildbucket for the build status
[02:18:13.809] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:13.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:63:0) after 1m0s
[02:19:13.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:63:0)
[02:19:13.827] Timer tick, asking Buildbucket for the build status
[02:19:14.392] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:14.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:64:0) after 1m0s
[02:20:14.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:64:0)
[02:20:14.461] Timer tick, asking Buildbucket for the build status
[02:20:14.576] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:14.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:65:0) after 1m0s
[02:21:14.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:65:0)
[02:21:14.651] Timer tick, asking Buildbucket for the build status
[02:21:14.735] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:14.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:66:0) after 1m0s
[02:22:14.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:66:0)
[02:22:14.809] Timer tick, asking Buildbucket for the build status
[02:22:14.958] Build 8860254494777111264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:14.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025684556707177104:67:0) after 1m0s
[02:22:53.853] Received PubSub notification, asking Buildbucket for the build status
[02:22:53.951] Build 8860254494777111264: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:22:53.951] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608603771709639",
"created_by": "project:chromium",
"created_ts": "1608599873716817",
"id": "8860254494777111264",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9ef5ad0fa3377fa37df268bce2f485cf7593b828\"}}",
"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\": \"9ef5ad0fa3377fa37df268bce2f485cf7593b828\", \"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-6-gpyc\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16373, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"11c31e0a4a73d7d62e765dc7ec400560fbb16b5e\", \"got_dawn_revision\": \"ec56b90ceab0c16e3c9f5603cc26562ec7bc2430\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"9ef5ad0fa3377fa37df268bce2f485cf7593b828\", \"got_revision_cp\": \"refs/heads/master@{#838764}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"ab90f8251bdc6bd9dc6033afdd2374af4883794f\", \"got_v8_revision_cp\": \"refs/heads/8.9.181@{#1}\", \"got_webrtc_revision\": \"8649e49d10e6e6efb5a98920f0b19a77abe8f070\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32867}\", \"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\": \"9ef5ad0fa3377fa37df268bce2f485cf7593b828\", \"swarm_hashes_refs/heads/master(at){#838764}_without_patch\": {\"absl_hardening_tests\": \"39f20418589f7c30f3194c31d0f3075429c07b21\", \"accessibility_unittests\": \"ba1e9bfd763f6511983cab114d706858f1dcdf9f\", \"angle_unittests\": \"c0d4c7654b6c8d58395191e9072291593c00731b\", \"app_shell_unittests\": \"57c0958aea980dc4eb252827f3114e6455200be4\", \"aura_unittests\": \"4b33b4e4917080a647434f15814882bbd7fd1968\", \"base_unittests\": \"42e974b3f9fa94cde0fd491121bfac60232dc94d\", \"base_util_unittests\": \"980b1219da6d12b16ee8cd2163d13acd7bc447ea\", \"blink_common_unittests\": \"e70b4be5c0cbd631d5d31f0e019add9a6eb10419\", \"blink_fuzzer_unittests\": \"a4f75a535f47bb0fbe92377bc766cfefd68ef670\", \"blink_heap_unittests\": \"49a2e06eaac703498a2cc65b1c6a387ff7ce0e35\", \"blink_platform_unittests\": \"9ac240c9c3ace1ca2436b1c2e99f6f8a57a44afc\", \"blink_unittests\": \"902de0344f7f3d9158086313b240e533eb6ea0ff\", \"boringssl_crypto_tests\": \"16fc97f8f1fc57e1aa10895e2a0c4d6bc2743348\", \"boringssl_ssl_tests\": \"99f1af84bcd30319868457a4c4ec2efae6688671\", \"browser_switcher_bho_unittests\": \"03562d54f20fb0e3c8188c91a2ebf5c318f8a595\", \"browser_tests\": \"ab0748ecfe7aea9c75c5327f0f46073503b497b1\", \"capture_unittests\": \"e8cac45075f7e54ab4015172438dc47a38c98750\", \"cast_unittests\": \"4343411e974d83f8365b301766f655525ba0ad79\", \"cc_unittests\": \"06643b6583197728168d1a226fd48801f7ac01fa\", \"chrome_app_unittests\": \"ba59be4bd814d0e141aaef9f2849188c751a935c\", \"chrome_cleaner_unittests\": \"084abae9681d41c958adda4d289cab9cc713d866\", \"chrome_elf_unittests\": \"a081ef53042e130c9931b514d4e6bfc90aff7b78\", \"chromedriver_unittests\": \"ce5f5d5e97f091164cf5356f5dff7aa09dee316b\", \"components_browsertests\": \"d959f74adc6487041c07d65cb72d546e71c13885\", \"components_unittests\": \"43c502b545c23c66453a960ecec8477520a3ed5e\", \"compositor_unittests\": \"05d7a01422e01aec9cd3981802830e0f23d97913\", \"content_browsertests\": \"511a713b78f0c5bc6009006c5d0f0a6b0c1ba935\", \"content_unittests\": \"b23a6f66d50862dbefe8e9c34628dbec6f57a68a\", \"courgette_unittests\": \"3412500d7cd474065ae627bdfcf0d7ee32e524ec\", \"crashpad_tests\": \"24e92dc4a4b0e336c7d7cec2c5c604ba4577510d\", \"cronet_tests\": \"09351816eaf28cbc0cf47b2b727dcaac2b0aebda\", \"cronet_unittests\": \"ff9790095bee750984b2291ec7e0584c57273e2b\", \"crypto_unittests\": \"fafc1d6736085a6069a68a6683e072028261c2ad\", \"delayloads_unittests\": \"eb19e55507160dec025ecd88258fa370f8f184f0\", \"device_unittests\": \"0db1ace86d49a0f7d042b2de8d39cf9cc0299b45\", \"display_unittests\": \"57d1c0010d80e52c97b6a901d695b4d48fb08beb\", \"elevation_service_unittests\": \"15723f1435135db225634e95ac73dcf7bf419c75\", \"events_unittests\": \"90fd8c706375a0f767cce50b3a788696e083cacf\", \"extensions_browsertests\": \"d2e563e0bc2d328beb7588e40593404a07b011cb\", \"extensions_unittests\": \"90938457e85a3747f7c5edf7fe7686816fe979f6\", \"filesystem_service_unittests\": \"2c0883cb44fbaac918ab8067c5233cb64fce58d6\", \"gcm_unit_tests\": \"9cad3a7ac2959cfba663155613946368fe3171ac\", \"gcp_unittests\": \"3791d44e42e72df2510bf27464a8dea33928e1e1\", \"gfx_unittests\": \"852ca1a59e63d3d8827479655581896462250cf3\", \"gin_unittests\": \"002a75a703934aa205772a60b148dc20acf8c221\", \"google_apis_unittests\": \"fa094a6adab633b4ac395f8b4b6e58a0d522e7ad\", \"gpu_unittests\": \"4c9854e626d43f4067d67df31116994198a4d595\", \"gwp_asan_unittests\": \"befb8d1bc6a2e09b6e0d9cfe14d6bdaed9064c6a\", \"headless_browsertests\": \"57592720e7880f2da47f18bcf04e4970e20ce7b4\", \"headless_unittests\": \"0ac9e8326a6630de5cde98a546cd6e333cdce84a\", \"install_static_unittests\": \"c4522f4a7f1a58578dd0a4c42eb4b43a430c7bb3\", \"installer_util_unittests\": \"46c6d8aeef4e1a0c5f8793d5b2ef84f209c016b5\", \"interactive_ui_tests\": \"ca0bc6a6606f4ab46ccd7257c8ae44a74e2c9839\", \"ipc_tests\": \"86b7892a8f7bfae0802b2c5420ecc8905ac385b7\", \"jingle_unittests\": \"1ed0dff18fff35c04d226bec2ab003c22113b5a3\", \"latency_unittests\": \"3cc9bc46a723c54d1a26ad8602b21d0acaba4e00\", \"libjingle_xmpp_unittests\": \"745cc5d939d01cdc340f665ad5e8f1e8b9dcf67f\", \"liburlpattern_unittests\": \"b572973968ae8e29c94963b512d8dce2500a120e\", \"media_blink_unittests\": \"58f6c98775603afe561894879c8803d09222421d\", \"media_unittests\": \"381705292e1304495f0cc601a554e494f18032e5\", \"message_center_unittests\": \"2f92242bb31cfab5a827276b86419b3326861c16\", \"midi_unittests\": \"c187e938d903247f1468bc6f27e015a05a8b1d76\", \"mojo_core_unittests\": \"b9506ecd9af4262463bca9108f660032f8f6aaab\", \"mojo_unittests\": \"94749d02891e7facdc963ce0fa54621a522c8c45\", \"nacl_loader_unittests\": \"28b0991b823874256a9c0d1ac407559d5fc59af0\", \"native_theme_unittests\": \"f9e3f3c83cbcff03d292b7e2e386b69c12e0ea83\", \"net_unittests\": \"880fa0a2a176a96d039b883c53baa92b67d8c5a2\", \"notification_helper_unittests\": \"0ef9542f9cd4d2c1dea30c03a3b0dc0c38b0eaa0\", \"pdf_unittests\": \"eaadc136ec37db366a5cacd424cb44ec15f92235\", \"perfetto_unittests\": \"ff781e25c4e9a53177d8c2933f514507a44deee4\", \"ppapi_unittests\": \"463d5bad77849470497db58e0a8fe4b183f810e7\", \"printing_unittests\": \"d4981123e53fdd45b8722a15bafbcb061d0696f5\", \"remoting_unittests\": \"35023ec3a4a8cc3e717cfd4b75b0d81ac1393ab1\", \"sbox_integration_tests\": \"1102a703f28a17648290e4e61153acea42d34ed2\", \"sbox_unittests\": \"795f6dedb474e1c924819163a0cc9c914f367cdf\", \"sbox_validation_tests\": \"3d30d654d895acf4f2f18e5d76bb9016536799e1\", \"service_manager_unittests\": \"04ea66405fd5dee57b343318dde6475af1ee6ed4\", \"services_unittests\": \"3a82741b3e31c6cb7768941c20978ad9ce6ab9eb\", \"setup_unittests\": \"eb33b3e25d8c1b538f343459c8eb3e7e289ab66e\", \"shell_dialogs_unittests\": \"53d9baf5f0332e7c17dff79800f738aa7fd32a48\", \"skia_unittests\": \"f2c42be9b02974f01d2a314761720eb03897d520\", \"snapshot_unittests\": \"c93d60ad4fcf325e93706ed8014bf8184397c351\", \"sql_unittests\": \"d53535e7593c46e816252f46ad672c2ae6360ee9\", \"storage_unittests\": \"3ecc6b5f2d3d2268e46372516575d5673b26c8b5\", \"sync_integration_tests\": \"0bec2e3f41cbfbdb2533370ade85342b97cb49a6\", \"traffic_annotation_auditor_unittests\": \"4ee2d52231a6e52543fc9c0353a91a0821209ab0\", \"ui_base_unittests\": \"33b338d4bf728912538f3d1e3cf481d454d97b43\", \"ui_touch_selection_unittests\": \"0eedde6c7207f57e48591246d35a83a1d04f9814\", \"unit_tests\": \"a53416158432a7786c0448e2c2d49c36f6945e41\", \"updater_tests\": \"f5308bdaa2e67dbe242807fd10bec2f2aba1120c\", \"url_unittests\": \"fdc3a9d3fa164f1a5df450f25804a9f9febe3b6d\", \"views_unittests\": \"6935664e894929c998896e779cdb090a2ebb94d9\", \"viz_unittests\": \"29c34b1d1eb7551e3d1ea3369cd38346aec6a17a\", \"vr_common_unittests\": \"a1eb1641ec43004aabeff75722662ce4de7aab17\", \"vr_pixeltests\": \"ee0d2d602b23d4c03a1a3b54e8db20706c731434\", \"weblayer_browsertests\": \"117ddd4624c057d17fa629b988daa9a9fbc874b0\", \"weblayer_unittests\": \"6e3e59d10a693dcf3748fc4466b1639318566797\", \"wm_unittests\": \"f584c98f23a7bfca82f1ce5e3f6c7ab1af0823da\", \"wtf_unittests\": \"47cbab3399bd47f39ba6b034f6f4bcaf9977b2f9\", \"zlib_unittests\": \"6f2e532f45d8ca7b8f502379784df0afbb20b999\", \"zucchini_unittests\": \"ab18aafeafb6a4a5cdc2654c2d529922f274f300\"}}, \"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-6-gpyc\"], \"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\": [\"5657-0417a3f\"], \"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": "1608599894229979",
"status": "COMPLETED",
"status_changed_ts": "1608603772333776",
"tags": [
"build_address:luci.chromium.ci/win-asan/16373",
"builder:win-asan",
"buildset:commit/git/9ef5ad0fa3377fa37df268bce2f485cf7593b828",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9ef5ad0fa3377fa37df268bce2f485cf7593b828",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025684556707177104",
"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/8860254494777111264/+/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:50a0d7b55656ff10",
"user_agent:luci-scheduler"
],
"updated_ts": "1608603772338040",
"url": "https://ci.chromium.org/b/8860254494777111264",
"utcnow_ts": "1608603773942666"
}
}
[02:22:53.951] Invocation finished in 1h5m1.459318272s with status SUCCEEDED