[14:24:37.896] New invocation is queued and will start shortly
[14:24:38.957] Starting the invocation (attempt 1)
[14:24:38.989] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:24:38.989] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:24:38.989] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020471031184209312",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9fbca7dce3719bc82c29b33bdce866a47390165d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020471031184209312",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/9fbca7dce3719bc82c29b33bdce866a47390165d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9fbca7dce3719bc82c29b33bdce866a47390165d",
"gitiles_ref:refs/heads/master"
]
}
[14:24:39.995] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613571879080339",
"id": "8855040969280076736",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9fbca7dce3719bc82c29b33bdce866a47390165d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613571879788656",
"tags": [
"build_address:luci.chromium.ci/win-asan/17393",
"builder:win-asan",
"buildset:commit/git/9fbca7dce3719bc82c29b33bdce866a47390165d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9fbca7dce3719bc82c29b33bdce866a47390165d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020471031184209312",
"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/8855040969280076736/+/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": "1613571879788769",
"url": "https://ci.chromium.org/b/8855040969280076736",
"utcnow_ts": "1613571879954431"
}
}
[14:24:39.995] Task URL: https://ci.chromium.org/b/8855040969280076736
[14:24:39.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:2:0) after 1m0s
[14:24:44.212] Received PubSub notification, asking Buildbucket for the build status
[14:24:44.290] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:40.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:2:0)
[14:25:40.031] Timer tick, asking Buildbucket for the build status
[14:25:40.116] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:40.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:4:0) after 1m0s
[14:26:40.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:4:0)
[14:26:40.132] Timer tick, asking Buildbucket for the build status
[14:26:40.268] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:40.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:5:0) after 1m0s
[14:27:40.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:5:0)
[14:27:40.285] Timer tick, asking Buildbucket for the build status
[14:27:40.385] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:40.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:6:0) after 1m0s
[14:28:40.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:6:0)
[14:28:40.402] Timer tick, asking Buildbucket for the build status
[14:28:40.500] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:40.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:7:0) after 1m0s
[14:29:40.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:7:0)
[14:29:40.512] Timer tick, asking Buildbucket for the build status
[14:29:40.608] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:40.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:8:0) after 1m0s
[14:30:40.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:8:0)
[14:30:40.705] Timer tick, asking Buildbucket for the build status
[14:30:40.884] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:40.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:9:0) after 1m0s
[14:31:40.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:9:0)
[14:31:40.979] Timer tick, asking Buildbucket for the build status
[14:31:41.110] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:41.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:10:0) after 1m0s
[14:32:41.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:10:0)
[14:32:41.427] Timer tick, asking Buildbucket for the build status
[14:32:41.561] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:41.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:11:0) after 1m0s
[14:33:41.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:11:0)
[14:33:41.625] Timer tick, asking Buildbucket for the build status
[14:33:41.782] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:41.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:12:0) after 1m0s
[14:34:41.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:12:0)
[14:34:41.854] Timer tick, asking Buildbucket for the build status
[14:34:42.402] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:42.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:13:0) after 1m0s
[14:35:42.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:13:0)
[14:35:42.478] Timer tick, asking Buildbucket for the build status
[14:35:42.575] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:42.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:14:0) after 1m0s
[14:36:42.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:14:0)
[14:36:42.704] Timer tick, asking Buildbucket for the build status
[14:36:43.098] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:43.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:15:0) after 1m0s
[14:37:43.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:15:0)
[14:37:43.374] Timer tick, asking Buildbucket for the build status
[14:37:43.504] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:43.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:16:0) after 1m0s
[14:38:43.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:16:0)
[14:38:43.606] Timer tick, asking Buildbucket for the build status
[14:38:43.719] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:43.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:17:0) after 1m0s
[14:39:43.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:17:0)
[14:39:43.746] Timer tick, asking Buildbucket for the build status
[14:39:43.915] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:43.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:18:0) after 1m0s
[14:40:43.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:18:0)
[14:40:43.963] Timer tick, asking Buildbucket for the build status
[14:40:48.077] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:48.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:19:0) after 1m0s
[14:41:48.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:19:0)
[14:41:48.145] Timer tick, asking Buildbucket for the build status
[14:41:48.270] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:48.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:20:0) after 1m0s
[14:42:48.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:20:0)
[14:42:48.424] Timer tick, asking Buildbucket for the build status
[14:42:48.556] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:48.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:21:0) after 1m0s
[14:43:48.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:21:0)
[14:43:48.625] Timer tick, asking Buildbucket for the build status
[14:43:48.717] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:48.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:22:0) after 1m0s
[14:44:48.744] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:22:0)
[14:44:48.744] Timer tick, asking Buildbucket for the build status
[14:44:49.000] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:49.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:23:0) after 1m0s
[14:45:49.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:23:0)
[14:45:49.105] Timer tick, asking Buildbucket for the build status
[14:45:49.205] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:49.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:24:0) after 1m0s
[14:46:49.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:24:0)
[14:46:49.308] Timer tick, asking Buildbucket for the build status
[14:46:49.469] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:49.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:25:0) after 1m0s
[14:47:49.543] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:25:0)
[14:47:49.543] Timer tick, asking Buildbucket for the build status
[14:47:49.776] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:49.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:26:0) after 1m0s
[14:48:49.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:26:0)
[14:48:49.845] Timer tick, asking Buildbucket for the build status
[14:48:50.296] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:50.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:27:0) after 1m0s
[14:49:50.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:27:0)
[14:49:50.316] Timer tick, asking Buildbucket for the build status
[14:49:50.415] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:50.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:28:0) after 1m0s
[14:50:50.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:28:0)
[14:50:50.486] Timer tick, asking Buildbucket for the build status
[14:50:50.810] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:50.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:29:0) after 1m0s
[14:51:50.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:29:0)
[14:51:50.829] Timer tick, asking Buildbucket for the build status
[14:51:50.920] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:50.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:30:0) after 1m0s
[14:52:50.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:30:0)
[14:52:50.986] Timer tick, asking Buildbucket for the build status
[14:52:51.099] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:51.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:31:0) after 1m0s
[14:53:51.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:31:0)
[14:53:51.193] Timer tick, asking Buildbucket for the build status
[14:53:51.996] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:51.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:32:0) after 1m0s
[14:54:52.024] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:32:0)
[14:54:52.024] Timer tick, asking Buildbucket for the build status
[14:54:52.256] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:52.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:33:0) after 1m0s
[14:55:52.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:33:0)
[14:55:52.319] Timer tick, asking Buildbucket for the build status
[14:55:52.553] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:52.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:34:0) after 1m0s
[14:56:52.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:34:0)
[14:56:52.611] Timer tick, asking Buildbucket for the build status
[14:56:52.867] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:52.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:35:0) after 1m0s
[14:57:52.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:35:0)
[14:57:52.918] Timer tick, asking Buildbucket for the build status
[14:57:53.003] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:53.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:36:0) after 1m0s
[14:58:53.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:36:0)
[14:58:53.032] Timer tick, asking Buildbucket for the build status
[14:58:53.148] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:53.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:37:0) after 1m0s
[14:59:53.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:37:0)
[14:59:53.169] Timer tick, asking Buildbucket for the build status
[14:59:53.286] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:53.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:38:0) after 1m0s
[15:00:53.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:38:0)
[15:00:53.303] Timer tick, asking Buildbucket for the build status
[15:00:53.764] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:53.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:39:0) after 1m0s
[15:01:53.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:39:0)
[15:01:53.816] Timer tick, asking Buildbucket for the build status
[15:01:53.927] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:53.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:40:0) after 1m0s
[15:02:53.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:40:0)
[15:02:53.997] Timer tick, asking Buildbucket for the build status
[15:02:54.093] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:54.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:41:0) after 1m0s
[15:03:54.153] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:41:0)
[15:03:54.153] Timer tick, asking Buildbucket for the build status
[15:03:54.270] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:54.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:42:0) after 1m0s
[15:04:54.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:42:0)
[15:04:54.281] Timer tick, asking Buildbucket for the build status
[15:04:54.369] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:54.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:43:0) after 1m0s
[15:05:54.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:43:0)
[15:05:54.684] Timer tick, asking Buildbucket for the build status
[15:05:55.002] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:55.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:44:0) after 1m0s
[15:06:55.184] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:44:0)
[15:06:55.184] Timer tick, asking Buildbucket for the build status
[15:06:55.270] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:55.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:45:0) after 1m0s
[15:07:55.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:45:0)
[15:07:55.274] Timer tick, asking Buildbucket for the build status
[15:07:55.407] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:55.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:46:0) after 1m0s
[15:08:55.537] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:46:0)
[15:08:55.537] Timer tick, asking Buildbucket for the build status
[15:08:55.633] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:55.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:47:0) after 1m0s
[15:09:55.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:47:0)
[15:09:55.762] Timer tick, asking Buildbucket for the build status
[15:09:56.119] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:56.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:48:0) after 1m0s
[15:10:56.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:48:0)
[15:10:56.227] Timer tick, asking Buildbucket for the build status
[15:10:56.452] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:56.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:49:0) after 1m0s
[15:11:56.580] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:49:0)
[15:11:56.580] Timer tick, asking Buildbucket for the build status
[15:11:56.698] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:56.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:50:0) after 1m0s
[15:12:56.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:50:0)
[15:12:56.756] Timer tick, asking Buildbucket for the build status
[15:12:56.870] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:56.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:51:0) after 1m0s
[15:13:57.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:51:0)
[15:13:57.874] Timer tick, asking Buildbucket for the build status
[15:13:58.002] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:58.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:52:0) after 1m0s
[15:14:58.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:52:0)
[15:14:58.188] Timer tick, asking Buildbucket for the build status
[15:14:58.496] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:58.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:53:0) after 1m0s
[15:15:58.574] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:53:0)
[15:15:58.574] Timer tick, asking Buildbucket for the build status
[15:15:58.731] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:58.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:54:0) after 1m0s
[15:16:59.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:54:0)
[15:16:59.112] Timer tick, asking Buildbucket for the build status
[15:16:59.561] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:59.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:55:0) after 1m0s
[15:17:59.800] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:55:0)
[15:17:59.800] Timer tick, asking Buildbucket for the build status
[15:18:00.257] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:00.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:56:0) after 1m0s
[15:19:00.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:56:0)
[15:19:00.354] Timer tick, asking Buildbucket for the build status
[15:19:00.711] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:00.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:57:0) after 1m0s
[15:20:00.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:57:0)
[15:20:00.747] Timer tick, asking Buildbucket for the build status
[15:20:00.995] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:00.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:58:0) after 1m0s
[15:21:01.344] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:58:0)
[15:21:01.344] Timer tick, asking Buildbucket for the build status
[15:21:01.470] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:01.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:59:0) after 1m0s
[15:22:01.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:59:0)
[15:22:01.648] Timer tick, asking Buildbucket for the build status
[15:22:01.851] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:01.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:60:0) after 1m0s
[15:23:03.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:60:0)
[15:23:03.583] Timer tick, asking Buildbucket for the build status
[15:23:03.763] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:03.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:61:0) after 1m0s
[15:24:03.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:61:0)
[15:24:03.971] Timer tick, asking Buildbucket for the build status
[15:24:04.202] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:04.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:62:0) after 1m0s
[15:25:04.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:62:0)
[15:25:04.587] Timer tick, asking Buildbucket for the build status
[15:25:04.705] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:04.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:63:0) after 1m0s
[15:26:05.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:63:0)
[15:26:05.025] Timer tick, asking Buildbucket for the build status
[15:26:05.220] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:05.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:64:0) after 1m0s
[15:27:05.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:64:0)
[15:27:05.363] Timer tick, asking Buildbucket for the build status
[15:27:05.491] Build 8855040969280076736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:05.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020471031184209312:65:0) after 1m0s
[15:27:11.023] Received PubSub notification, asking Buildbucket for the build status
[15:27:11.300] Build 8855040969280076736: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:27:11.301] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613575629707513",
"created_by": "project:chromium",
"created_ts": "1613571879080339",
"id": "8855040969280076736",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9fbca7dce3719bc82c29b33bdce866a47390165d\"}}",
"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\": \"9fbca7dce3719bc82c29b33bdce866a47390165d\", \"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-0-6l07\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17393, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"3c28b2a0b4813f94730b2e8ca29d490bfb5d4c41\", \"got_dawn_revision\": \"a5ba2827f5810b7b1cd6ee046e8df6e109bb02b6\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"9fbca7dce3719bc82c29b33bdce866a47390165d\", \"got_revision_cp\": \"refs/heads/master@{#854759}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"bb04803b8daf08e186b327d18e14c78378f44e37\", \"got_v8_revision\": \"b908f020b8cf2c535cdc492d5a959b8fdf068c2b\", \"got_v8_revision_cp\": \"refs/heads/9.0.198@{#1}\", \"got_webrtc_revision\": \"60c0b442ee55ef1a34dcea0046bb9df29aab6992\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33285}\", \"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\": \"9fbca7dce3719bc82c29b33bdce866a47390165d\", \"swarm_hashes_refs/heads/master(at){#854759}_without_patch\": {\"absl_hardening_tests\": \"fe9ef3e17841aad3b481db582f28ed93ba5a0864\", \"accessibility_unittests\": \"7c72bf7e56aa03e9d19dc7bd983f8c143bef2053\", \"angle_unittests\": \"7b2fcd8d34153572f753edb7e9e131b7f9a96efe\", \"app_shell_unittests\": \"2272715d12e29453c520c65089f1a281d8a3792c\", \"aura_unittests\": \"483e58f14b03a156a0f941b0fa1b9bf8f1629557\", \"base_unittests\": \"7050ed91bb6dbeb8daf68ffe91cf2be690b9c021\", \"base_util_unittests\": \"7f5a5825e56f20c211a1bff9629acd83f5a46931\", \"blink_common_unittests\": \"5ed8749462bddd72a3057558622c6bddc4ec3f1a\", \"blink_fuzzer_unittests\": \"d1b3e5d1cf65146d2fc1921f6db2c62c1342eeac\", \"blink_heap_unittests\": \"b61904cab6d8d9e6e1204c0395be1fa1f85c4c7f\", \"blink_platform_unittests\": \"9647c81c6101c5887570e961426028dd73947c48\", \"blink_unittests\": \"832055623fddcbb09edf2c5c22e5f68bdd6f5d02\", \"boringssl_crypto_tests\": \"c7a407ed5f23ed9871379f2af284e43d35801d53\", \"boringssl_ssl_tests\": \"223af59be7d667ce7b0163ec23b7815b311da7b8\", \"browser_switcher_bho_unittests\": \"5ae2375d8eb6758749d8d24b6349d244fdaea22c\", \"browser_tests\": \"d030161e03975b9d653d7ed29415fd274aaecf05\", \"capture_unittests\": \"4f126a7615ab3dfe1fa41f63b1ce0508272bbbc5\", \"cast_unittests\": \"c8528ea1b93218c9bc422e3fd33a3d3bc1740456\", \"cc_unittests\": \"7515f8349a59599a688058490e2cb74422692be3\", \"chrome_app_unittests\": \"e1735d140c17cb2c4a1e44e34a3a949ffbe80907\", \"chrome_cleaner_unittests\": \"d80aad96b7354e45272bfa02af4159fb52d39260\", \"chrome_elf_unittests\": \"db399c5d78a9417fe27a5027c82ff4e57f2afedc\", \"chromedriver_unittests\": \"7bca92907ef01fd1065c985fa5a80d171d1fdd47\", \"components_browsertests\": \"4ea732ce681892dd02eb7bfb4e431a5f5473c00f\", \"components_unittests\": \"94469cde50fd7cad3eab548c2fe5bff9e5121eaf\", \"compositor_unittests\": \"3c7a4b7ad7b7c40780aa302086297aa4c2239d5f\", \"content_browsertests\": \"52c4a42fac3991ed6effd8c86ded5cf24c3de5a5\", \"content_unittests\": \"2659d93d8e645ee344b288944718bedbc5dc2fcf\", \"courgette_unittests\": \"03f42732ff1556d3b842f42ea23e02a237084fb5\", \"crashpad_tests\": \"a22554ec6dd2e0c450ca48e5c539916a35a9fa27\", \"cronet_tests\": \"bb8c7d43be41c67485c5f6af8bb3a4148d1e56c1\", \"cronet_unittests\": \"d0432bb6890e2316e9266cb285301a576157a2d2\", \"crypto_unittests\": \"1199b89d0550902bf138030ae812b33c0f82e13b\", \"delayloads_unittests\": \"6d141a414483d3ff32cd44fbda81d32d174df60c\", \"device_unittests\": \"b20131dcbfee82e943ea165edcdbfd823f3eae1a\", \"display_unittests\": \"884638857fd772c70ac1ad10cf339a9c550b9157\", \"elevation_service_unittests\": \"6ae6d6b0324fcec8866ab9c5bfd03954b5a86b84\", \"events_unittests\": \"bebe5982fc46e3d4eacfeb746c2507336326e3d2\", \"extensions_browsertests\": \"74b4de3e1b92673685982bf2c6a0dbc4fa7ad874\", \"extensions_unittests\": \"4aef919ca54054ec49754074315d47eccffbb1c7\", \"filesystem_service_unittests\": \"26b7505c91ac508110283769e63bf6da9b5b14cd\", \"gcm_unit_tests\": \"d245cdaa032067279ccabc995ca9c1dce8375474\", \"gcp_unittests\": \"d67ee3a104465f87dce2184ec620dc513b94fcea\", \"gfx_unittests\": \"71056033140d98f31f8719ee04a284c1c81c4e8e\", \"gin_unittests\": \"fca3727910b0a9ad56171e800da82f074819a479\", \"google_apis_unittests\": \"29e535353dc7d3e890758048a66777098f01b127\", \"gpu_unittests\": \"185c784d62683cd7d1ca875a5d31c15d7c656010\", \"gwp_asan_unittests\": \"6a0919c636a60575e4f41f8eb962a46c547c0197\", \"headless_browsertests\": \"4a79b766241d2ab0480fac140d8bf191aa3057ce\", \"headless_unittests\": \"bd7f2b80b2d25344f52f389da3e643668a3871a7\", \"install_static_unittests\": \"71fff2857b80403247ce821788d6dde9525ca8d2\", \"installer_util_unittests\": \"0ab0086424d4299a642958fa835486715bf10b3c\", \"interactive_ui_tests\": \"3c12be2cd619755262032ec8c7cf9f2491b39f90\", \"ipc_tests\": \"c58c5a0e499eff0e28b9d3f5d451af0236b3d268\", \"jingle_unittests\": \"73659b65c0109e618fcc6c8cdbe42bc19d12b608\", \"latency_unittests\": \"b20e83160b36187ddb18c17a722fa2e16d785ba5\", \"libjingle_xmpp_unittests\": \"79d927279854085770c8dc14c7392c208dfeae56\", \"liburlpattern_unittests\": \"46f825e523ee1cded72e6bae1b1edc8d16dc49eb\", \"media_blink_unittests\": \"96af14863e364c9b36a7561095c92b5ca913e4fb\", \"media_unittests\": \"908e4da7465e94e38d619ba5eafad27fc929c64a\", \"message_center_unittests\": \"de5ec5cf5d86b94088954c0246c0fd40ec11959a\", \"midi_unittests\": \"bcc9583a7f3a712fe2c306e3bca3017274b88bfe\", \"mojo_core_unittests\": \"db1bdfcf32b7a99cbbd1ea16d901174a8ec27393\", \"mojo_unittests\": \"2ee8dc6d5f9ee685037382b3e3b914bf3b5b4751\", \"nacl_loader_unittests\": \"bd5466fbc2bdb6b942742075cf1d67db7f6dcc15\", \"native_theme_unittests\": \"b614c26d82c8da98e685f7570765cc8f0c25e01c\", \"net_unittests\": \"82474eba136cfc2ad2098d375054a0b662788ab4\", \"notification_helper_unittests\": \"21763b95c2fc79b258cd6a5f33a1f01b2a069300\", \"pdf_unittests\": \"27bc6e4518ffd31dc59aacf71a11bc5021819fda\", \"perfetto_unittests\": \"9dcac98b5666b049bd2098d62e5dce86fd4ee2e3\", \"ppapi_unittests\": \"a54a4bde14e61178980da3b4b3ce01a1ee905923\", \"printing_unittests\": \"a0225ea76b022277b4b69846c4ea8f018fdc4417\", \"remoting_unittests\": \"cdc6b2cd09da11ae237051d2e6d52346b5365b78\", \"sbox_integration_tests\": \"1731fb78d9e4bef292064b5ce4c40c6c3cc067a6\", \"sbox_unittests\": \"3a65a85f300cbcf6c838bb2f346f8bf581b43606\", \"sbox_validation_tests\": \"9fa43bea7580563460944868c759679e8a1b20f5\", \"service_manager_unittests\": \"31a49ac3ddd84a2530c236794b3868bae7f87f6c\", \"services_unittests\": \"e72397d8ceb88fbac2e2b0c34838e8d7e946763b\", \"setup_unittests\": \"5e5a70844bd62ed56bdc201a5afcd83d3fd18016\", \"shell_dialogs_unittests\": \"580b3348c91ad4bfc0ec6d8b16809c522d985d4c\", \"skia_unittests\": \"0335ec29330a4104e34bc2c68f892d3c9b06cdcc\", \"snapshot_unittests\": \"fc46537b79dd120936410dc824f408489f58da76\", \"sql_unittests\": \"4fab75230e0c5461f1e544e480b98e3ca40be059\", \"storage_unittests\": \"e61fb4deeaf85956970bdc7e808059166c0b6ea5\", \"sync_integration_tests\": \"dc10a0a71feeafe91f0f2cf87641026f93599735\", \"traffic_annotation_auditor_unittests\": \"eb1e72e6d2b242cce5bd0077434a4fa641373923\", \"ui_base_unittests\": \"61380f4c6a1e10b86f392fb09eba11b7245d32b7\", \"ui_touch_selection_unittests\": \"6c992da5bdfe9d6c26b27659f2bf96f26ccac98c\", \"unit_tests\": \"62c25f04b8004ba8184a79f1bdcfb6a654e64040\", \"updater_tests\": \"8f8fb65053ec3926108aec6214a1ccba0f869154\", \"url_unittests\": \"5155f6a9411fe4808ecd927a8be3ff2e9b54372a\", \"views_unittests\": \"d52fc6b051b52203b9af7efc3e6219c033a09797\", \"viz_unittests\": \"f45f1f398511a914db51e42fca1ce23dc591887e\", \"vr_common_unittests\": \"f59c022a9b52842ed24845c1ddaf86e2192f5a43\", \"vr_pixeltests\": \"3630f2868ca54373fe9496701358deb7de217d4d\", \"weblayer_browsertests\": \"3a60aa72b818329c899ad7ba592e74a82c41d5c8\", \"weblayer_unittests\": \"796ae4bf4ca769be2c952780d333cb4607d05802\", \"wm_unittests\": \"e9e6db9fa29719645231a0687dc6ddbd9d0ac8c2\", \"wtf_unittests\": \"d674cced199b010f6bf8f8085c1d47624fee4ec5\", \"zlib_unittests\": \"3f112b6deef78f5129d8b77b31f0b10093869875\", \"zucchini_unittests\": \"90a43ed6e724e13365704dd28382b2f0b3c4d6f8\"}}, \"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-0-6l07\"], \"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\": [\"5700-b36f18b\"], \"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": "1613571883454264",
"status": "COMPLETED",
"status_changed_ts": "1613575630185118",
"tags": [
"build_address:luci.chromium.ci/win-asan/17393",
"builder:win-asan",
"buildset:commit/git/9fbca7dce3719bc82c29b33bdce866a47390165d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9fbca7dce3719bc82c29b33bdce866a47390165d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020471031184209312",
"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/8855040969280076736/+/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:51c9327e31c01210",
"user_agent:luci-scheduler"
],
"updated_ts": "1613575630257409",
"url": "https://ci.chromium.org/b/8855040969280076736",
"utcnow_ts": "1613575631290098"
}
}
[15:27:11.302] Invocation finished in 1h2m33.421961569s with status SUCCEEDED