[07:52:36.591] New invocation is queued and will start shortly
[07:52:37.773] Starting the invocation (attempt 1)
[07:52:37.810] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:52:37.810] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:52:37.810] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027924646305596224",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0274fffe5117c594d7290a3ce2c627786306bb77\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027924646305596224",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/0274fffe5117c594d7290a3ce2c627786306bb77",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0274fffe5117c594d7290a3ce2c627786306bb77",
"gitiles_ref:refs/heads/master"
]
}
[07:52:39.331] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606463557956648",
"id": "8862494584211339616",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0274fffe5117c594d7290a3ce2c627786306bb77\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606463559042510",
"tags": [
"build_address:luci.chromium.ci/win-asan/15880",
"builder:win-asan",
"buildset:commit/git/0274fffe5117c594d7290a3ce2c627786306bb77",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0274fffe5117c594d7290a3ce2c627786306bb77",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027924646305596224",
"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/8862494584211339616/+/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": "1606463559042914",
"url": "https://ci.chromium.org/b/8862494584211339616",
"utcnow_ts": "1606463559317653"
}
}
[07:52:39.331] Task URL: https://ci.chromium.org/b/8862494584211339616
[07:52:39.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:2:0) after 1m0s
[07:52:45.475] Received PubSub notification, asking Buildbucket for the build status
[07:52:45.558] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:39.349] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:2:0)
[07:53:39.349] Timer tick, asking Buildbucket for the build status
[07:53:39.467] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:39.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:4:0) after 1m0s
[07:54:39.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:4:0)
[07:54:39.497] Timer tick, asking Buildbucket for the build status
[07:54:39.619] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:39.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:5:0) after 1m0s
[07:55:39.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:5:0)
[07:55:39.651] Timer tick, asking Buildbucket for the build status
[07:55:39.850] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:39.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:6:0) after 1m0s
[07:56:39.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:6:0)
[07:56:39.923] Timer tick, asking Buildbucket for the build status
[07:56:40.072] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:40.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:7:0) after 1m0s
[07:57:40.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:7:0)
[07:57:40.088] Timer tick, asking Buildbucket for the build status
[07:57:40.582] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:40.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:8:0) after 1m0s
[07:58:40.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:8:0)
[07:58:40.680] Timer tick, asking Buildbucket for the build status
[07:58:40.752] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:40.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:9:0) after 1m0s
[07:59:40.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:9:0)
[07:59:40.802] Timer tick, asking Buildbucket for the build status
[07:59:40.874] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:40.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:10:0) after 1m0s
[08:00:41.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:10:0)
[08:00:41.115] Timer tick, asking Buildbucket for the build status
[08:00:41.467] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:41.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:11:0) after 1m0s
[08:01:41.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:11:0)
[08:01:41.660] Timer tick, asking Buildbucket for the build status
[08:01:41.791] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:41.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:12:0) after 1m0s
[08:02:41.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:12:0)
[08:02:41.809] Timer tick, asking Buildbucket for the build status
[08:02:41.909] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:41.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:13:0) after 1m0s
[08:03:41.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:13:0)
[08:03:41.894] Timer tick, asking Buildbucket for the build status
[08:03:42.115] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:42.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:14:0) after 1m0s
[08:04:42.167] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:14:0)
[08:04:42.167] Timer tick, asking Buildbucket for the build status
[08:04:42.283] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:42.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:15:0) after 1m0s
[08:05:42.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:15:0)
[08:05:42.288] Timer tick, asking Buildbucket for the build status
[08:05:42.624] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:42.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:16:0) after 1m0s
[08:06:42.652] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:16:0)
[08:06:42.652] Timer tick, asking Buildbucket for the build status
[08:06:42.751] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:42.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:17:0) after 1m0s
[08:07:42.840] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:17:0)
[08:07:42.840] Timer tick, asking Buildbucket for the build status
[08:07:43.124] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:43.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:18:0) after 1m0s
[08:08:43.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:18:0)
[08:08:43.214] Timer tick, asking Buildbucket for the build status
[08:08:43.521] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:43.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:19:0) after 1m0s
[08:09:43.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:19:0)
[08:09:43.592] Timer tick, asking Buildbucket for the build status
[08:09:43.680] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:43.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:20:0) after 1m0s
[08:10:43.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:20:0)
[08:10:43.696] Timer tick, asking Buildbucket for the build status
[08:10:43.849] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:43.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:21:0) after 1m0s
[08:11:43.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:21:0)
[08:11:43.856] Timer tick, asking Buildbucket for the build status
[08:11:43.922] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:43.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:22:0) after 1m0s
[08:12:43.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:22:0)
[08:12:43.944] Timer tick, asking Buildbucket for the build status
[08:12:44.117] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:44.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:23:0) after 1m0s
[08:13:44.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:23:0)
[08:13:44.130] Timer tick, asking Buildbucket for the build status
[08:13:44.429] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:44.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:24:0) after 1m0s
[08:14:44.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:24:0)
[08:14:44.456] Timer tick, asking Buildbucket for the build status
[08:14:44.590] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:44.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:25:0) after 1m0s
[08:15:44.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:25:0)
[08:15:44.615] Timer tick, asking Buildbucket for the build status
[08:15:44.734] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:44.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:26:0) after 1m0s
[08:16:44.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:26:0)
[08:16:44.777] Timer tick, asking Buildbucket for the build status
[08:16:45.029] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:45.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:27:0) after 1m0s
[08:17:45.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:27:0)
[08:17:45.097] Timer tick, asking Buildbucket for the build status
[08:17:46.123] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:46.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:28:0) after 1m0s
[08:18:46.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:28:0)
[08:18:46.145] Timer tick, asking Buildbucket for the build status
[08:18:46.854] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:46.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:29:0) after 1m0s
[08:19:46.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:29:0)
[08:19:46.873] Timer tick, asking Buildbucket for the build status
[08:19:47.287] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:47.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:30:0) after 1m0s
[08:20:47.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:30:0)
[08:20:47.355] Timer tick, asking Buildbucket for the build status
[08:20:47.612] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:47.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:31:0) after 1m0s
[08:21:47.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:31:0)
[08:21:47.765] Timer tick, asking Buildbucket for the build status
[08:21:47.918] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:47.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:32:0) after 1m0s
[08:22:48.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:32:0)
[08:22:48.006] Timer tick, asking Buildbucket for the build status
[08:22:48.125] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:48.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:33:0) after 1m0s
[08:23:48.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:33:0)
[08:23:48.183] Timer tick, asking Buildbucket for the build status
[08:23:48.379] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:48.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:34:0) after 1m0s
[08:24:48.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:34:0)
[08:24:48.422] Timer tick, asking Buildbucket for the build status
[08:24:48.541] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:48.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:35:0) after 1m0s
[08:25:48.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:35:0)
[08:25:48.541] Timer tick, asking Buildbucket for the build status
[08:25:48.751] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:48.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:36:0) after 1m0s
[08:26:48.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:36:0)
[08:26:48.804] Timer tick, asking Buildbucket for the build status
[08:26:48.920] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:48.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:37:0) after 1m0s
[08:27:48.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:37:0)
[08:27:48.939] Timer tick, asking Buildbucket for the build status
[08:27:49.127] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:49.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:38:0) after 1m0s
[08:28:49.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:38:0)
[08:28:49.147] Timer tick, asking Buildbucket for the build status
[08:28:49.580] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:49.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:39:0) after 1m0s
[08:29:49.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:39:0)
[08:29:49.602] Timer tick, asking Buildbucket for the build status
[08:29:49.975] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:49.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:40:0) after 1m0s
[08:30:50.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:40:0)
[08:30:50.032] Timer tick, asking Buildbucket for the build status
[08:30:50.481] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:50.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:41:0) after 1m0s
[08:31:50.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:41:0)
[08:31:50.507] Timer tick, asking Buildbucket for the build status
[08:31:50.704] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:50.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:42:0) after 1m0s
[08:32:50.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:42:0)
[08:32:50.760] Timer tick, asking Buildbucket for the build status
[08:32:50.909] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:50.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:43:0) after 1m0s
[08:33:50.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:43:0)
[08:33:50.952] Timer tick, asking Buildbucket for the build status
[08:33:51.093] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:51.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:44:0) after 1m0s
[08:34:51.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:44:0)
[08:34:51.157] Timer tick, asking Buildbucket for the build status
[08:34:51.351] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:51.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:45:0) after 1m0s
[08:35:51.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:45:0)
[08:35:51.400] Timer tick, asking Buildbucket for the build status
[08:35:51.658] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:51.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:46:0) after 1m0s
[08:36:51.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:46:0)
[08:36:51.689] Timer tick, asking Buildbucket for the build status
[08:36:52.433] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:52.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:47:0) after 1m0s
[08:37:52.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:47:0)
[08:37:52.457] Timer tick, asking Buildbucket for the build status
[08:37:54.371] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:54.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:48:0) after 1m0s
[08:38:54.394] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:48:0)
[08:38:54.394] Timer tick, asking Buildbucket for the build status
[08:38:54.568] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:54.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:49:0) after 1m0s
[08:39:54.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:49:0)
[08:39:54.635] Timer tick, asking Buildbucket for the build status
[08:39:54.723] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:54.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:50:0) after 1m0s
[08:40:54.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:50:0)
[08:40:54.760] Timer tick, asking Buildbucket for the build status
[08:40:55.319] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:55.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:51:0) after 1m0s
[08:41:55.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:51:0)
[08:41:55.358] Timer tick, asking Buildbucket for the build status
[08:41:56.011] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:56.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:52:0) after 1m0s
[08:42:56.040] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:52:0)
[08:42:56.040] Timer tick, asking Buildbucket for the build status
[08:42:56.188] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:56.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:53:0) after 1m0s
[08:43:56.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:53:0)
[08:43:56.243] Timer tick, asking Buildbucket for the build status
[08:43:56.871] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:56.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:54:0) after 1m0s
[08:44:56.996] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:54:0)
[08:44:56.996] Timer tick, asking Buildbucket for the build status
[08:44:57.114] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:57.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:55:0) after 1m0s
[08:45:57.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:55:0)
[08:45:57.210] Timer tick, asking Buildbucket for the build status
[08:45:57.288] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:57.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:56:0) after 1m0s
[08:46:57.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:56:0)
[08:46:57.319] Timer tick, asking Buildbucket for the build status
[08:46:57.522] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:57.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:57:0) after 1m0s
[08:47:57.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:57:0)
[08:47:57.504] Timer tick, asking Buildbucket for the build status
[08:47:57.688] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:57.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:58:0) after 1m0s
[08:48:57.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:58:0)
[08:48:57.703] Timer tick, asking Buildbucket for the build status
[08:48:57.789] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:57.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:59:0) after 1m0s
[08:49:57.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:59:0)
[08:49:57.814] Timer tick, asking Buildbucket for the build status
[08:49:57.898] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:57.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:60:0) after 1m0s
[08:50:58.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:60:0)
[08:50:58.045] Timer tick, asking Buildbucket for the build status
[08:50:58.863] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:58.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:61:0) after 1m0s
[08:51:58.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:61:0)
[08:51:58.884] Timer tick, asking Buildbucket for the build status
[08:51:58.957] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:58.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:62:0) after 1m0s
[08:52:58.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:62:0)
[08:52:58.983] Timer tick, asking Buildbucket for the build status
[08:52:59.123] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:59.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:63:0) after 1m0s
[08:53:59.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:63:0)
[08:53:59.150] Timer tick, asking Buildbucket for the build status
[08:53:59.309] Build 8862494584211339616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:59.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027924646305596224:64:0) after 1m0s
[08:54:03.929] Received PubSub notification, asking Buildbucket for the build status
[08:54:04.101] Build 8862494584211339616: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:54:04.101] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606467242255675",
"created_by": "project:chromium",
"created_ts": "1606463557956648",
"id": "8862494584211339616",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0274fffe5117c594d7290a3ce2c627786306bb77\"}}",
"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\": \"0274fffe5117c594d7290a3ce2c627786306bb77\", \"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-4-qhsy\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15880, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"fcb6b5a5c55ee35d232f35bcfa9c35aefc00f89e\", \"got_dawn_revision\": \"4814bdbdeae40186a61dfb7494390e64820daa4f\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"0274fffe5117c594d7290a3ce2c627786306bb77\", \"got_revision_cp\": \"refs/heads/master@{#831498}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"9dff6a3bb5950edd890e5141f2d3d2f3b4ef351e\", \"got_v8_revision\": \"961f93287f84e7e7a7f5ff8a43c7adf14ca7fc42\", \"got_v8_revision_cp\": \"refs/heads/8.9.63@{#1}\", \"got_webrtc_revision\": \"0d863f72a8c747c1b41f2798e5201e1abcdaec2b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32704}\", \"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\": \"0274fffe5117c594d7290a3ce2c627786306bb77\", \"swarm_hashes_refs/heads/master(at){#831498}_without_patch\": {\"absl_hardening_tests\": \"82aa67fbb5885bc90653b5daa2d0c7c71f6c2dba\", \"accessibility_unittests\": \"e1ae1f29376fe1273fda0d9dff7193ca2bb4198e\", \"angle_unittests\": \"9f0a260d5f9a3f52193277c7df9d258e334435bc\", \"app_shell_unittests\": \"5e37cc19541ade290faa7423f95d1c016efbfd35\", \"aura_unittests\": \"a12daebd0911b7f2842f195bb098c83ec3878f01\", \"base_unittests\": \"a320039b382f925cf086eca42b308ce2f44ca761\", \"base_util_unittests\": \"22dae98eec4f8488849be3196607efa28f5e429e\", \"blink_common_unittests\": \"8bc53c4441fcbe7a90d4e61b26541be33e1c56e3\", \"blink_fuzzer_unittests\": \"3de9141301b018a68e21e19ef8bf1d2f920ca9f4\", \"blink_heap_unittests\": \"c613aa85feb56d97327834b8999e1bf44a67c78f\", \"blink_platform_unittests\": \"50a36a77040a0e921465884f5a48362bf66080a3\", \"blink_unittests\": \"01a14a22a2de39e0baf54f1b6c600240fdc4357d\", \"boringssl_crypto_tests\": \"70879457f1fc7b8ba0f90aabe885b4877537f0c8\", \"boringssl_ssl_tests\": \"74d4cfac1c832b14ffe6dd5695a476b5a4421b09\", \"browser_switcher_bho_unittests\": \"af49d9ab209cf4b3b436608eb48c48acd746b828\", \"browser_tests\": \"d062f48bb59dc37b99b09e019e83d4d0aae5b7e3\", \"capture_unittests\": \"aa285dbf8493c5ff20ba714587444ee3a140d6b5\", \"cast_unittests\": \"2570b2c65733e87fbabfdacd73654ad0e229bd65\", \"cc_unittests\": \"0baf54d144d190c9406fb7fd254f761cca764a05\", \"chrome_app_unittests\": \"bebb1d7ec14276e1ba9bcd6f272338399f9eb9d2\", \"chrome_cleaner_unittests\": \"318de6e6c4e10103c9df92cd011e8344ab13fe48\", \"chrome_elf_unittests\": \"56a421e9edd48fe28a4d2b65001efd65517dbb52\", \"chromedriver_unittests\": \"8652b73af9952619e2c325785d11450e0ec7a5fa\", \"components_browsertests\": \"bc93c7d0611f2c33a1bd758590fe42864a3392f5\", \"components_unittests\": \"816d61dabd035fc276387d9ee27b2f5df534d8e9\", \"compositor_unittests\": \"b3dc1c431c277faff62202184ca5faecc8ad1934\", \"content_browsertests\": \"8b0c377975f5b3b0abcc9d04af0977f3b2966620\", \"content_unittests\": \"e6c27f5bed08ca65e61cb7d45df338eff375c76f\", \"courgette_unittests\": \"73e4c0e4357ffe6f9c0bda3b1aa8053c4a350d1e\", \"crashpad_tests\": \"730d05a025601d65d9be64c43a90dd56b9896ff9\", \"cronet_tests\": \"7fe1b131bec5e6eb993bd8f87a319cc8adab371e\", \"cronet_unittests\": \"9915e97f2921a29ace934f157fe2434c7db78daf\", \"crypto_unittests\": \"1542c0096b5a673dafa8cdbdde8605ab40d8c7b6\", \"delayloads_unittests\": \"08ff1149a69ef58e5ba4c96c8191a501d98e16fb\", \"device_unittests\": \"ef95b169d9dcc5303a2b80bc84913a44d7601f4e\", \"display_unittests\": \"6ac7ebe02a1ffc21a76b339eac445cb6854976b1\", \"elevation_service_unittests\": \"fcabec72b1de665f97f0f49808636cbd4da90c56\", \"events_unittests\": \"8613caff5e160367d9ace1cab36ed0f047910e99\", \"extensions_browsertests\": \"4a2b14985959bc96b8c22272e3fcfdaaa1d0ed21\", \"extensions_unittests\": \"2283b811af8a837406591e1420fd38075565ec4d\", \"filesystem_service_unittests\": \"2b9d6174380df17284c3b6097638632de7184cfe\", \"gcm_unit_tests\": \"3355efb20189e3972db3c4315037c2593139fef6\", \"gcp_unittests\": \"2d1269920be671575438c9a801b0640e67b4a39f\", \"gfx_unittests\": \"248117cdb673b4ff62c9995c7867465476430809\", \"gin_unittests\": \"ea193d6d53370394c007fe60f2119969cf1c6f8d\", \"google_apis_unittests\": \"4fe52cda3dd0e8a8afc2a58a410d515494a29c86\", \"gpu_unittests\": \"77a8186ea110e8d5b1915a6a36ecfe2c9fd3e915\", \"gwp_asan_unittests\": \"fb9e8672dd9392410d8f62def0ac7de9cbe49b04\", \"headless_browsertests\": \"3864fd1d063180a181fccd07137c46f71f32b761\", \"headless_unittests\": \"346276c261cd51be27336b6ca5c69a3facc81aca\", \"install_static_unittests\": \"c5eeb68b34873c43bc8bbf57ddd1b19a4bb4960e\", \"installer_util_unittests\": \"713684a72b7b506493b7737112c56f4411620c67\", \"interactive_ui_tests\": \"b42690a8798562230818151997ed30fc3724de1e\", \"ipc_tests\": \"d03d6ab83a72a59c5c25227c60f6ecf71128d17d\", \"jingle_unittests\": \"941cb9be449f51e4abadf90fa79ec197909f70bc\", \"latency_unittests\": \"0f2c11751c53ea8815a7f052cb9c2947e14a3437\", \"libjingle_xmpp_unittests\": \"c01e4e51af2611a8081f973e0ba2f1c42a936c71\", \"liburlpattern_unittests\": \"fd05ee5b31addaf5047a0e010bb9f6a5a3b1f3d0\", \"media_blink_unittests\": \"ea2685b7dced584f341d4a122e294ad32d2168d9\", \"media_unittests\": \"9198e097ce20847742686582c5a8f20d480e993e\", \"message_center_unittests\": \"633b78608eef73991d06b8279a48e1b3e54ec9c8\", \"midi_unittests\": \"426849819e62b467ba935f7b7b40f4fd5a570f51\", \"mojo_core_unittests\": \"5cd7d119197acac3bc5028d8c948ade749d80ab3\", \"mojo_unittests\": \"e5002eb39c5e2091a4bc92a3b07ab72b74bb7be1\", \"nacl_loader_unittests\": \"42119f37c3504d0e19b2d7164a9480a143210465\", \"native_theme_unittests\": \"541220a30ad4d47e0c4732dd719099c33e7ad075\", \"net_unittests\": \"5455e1094c834abe756d6aea029b9dd3d56df418\", \"notification_helper_unittests\": \"33a5e623e09ede0fc87b60a5963e3208044f065e\", \"pdf_unittests\": \"183354dc0163ce6e7b0bbe9ee7b440465a13e5fe\", \"perfetto_unittests\": \"f7bed5bba44cdf165d662b7e0342643e9cd3d2f5\", \"ppapi_unittests\": \"56c8dfc18bfa51315e3ebef078cbad7281552cfa\", \"printing_unittests\": \"8a5db93b9c09ec1426888c152de87093658ad878\", \"remoting_unittests\": \"0fd103ad660f9a545452e2d03d2e375367c2ae72\", \"sbox_integration_tests\": \"0823695b4ba828c8c3dcf040649143cd5ee72128\", \"sbox_unittests\": \"d25055c36e66b930aa4d72e0df5e1f84b37a68c9\", \"sbox_validation_tests\": \"a00ad8915b9b94bc6871d3347749ea8accaeb5b0\", \"service_manager_unittests\": \"7df1109817be4b9ea832cd95fb5dbb4e3d2cf7e6\", \"services_unittests\": \"848f89d2a622ef710bde510992d57c60a0bf040d\", \"setup_unittests\": \"267cba0abf82a733ea7ab4aecaed5a2acabb523d\", \"shell_dialogs_unittests\": \"14c350522bffbe27fda814a6f80007f339e35b17\", \"skia_unittests\": \"29f1156f0770ea92fad7a9492b23dca5b58b23bd\", \"snapshot_unittests\": \"f92425734499a9bfb316fb2bf673dd12fbcac7bf\", \"sql_unittests\": \"190498fc256713750ba125c38236710735059915\", \"storage_unittests\": \"55f17fc5a49b6315043b982faafd440059caf787\", \"sync_integration_tests\": \"2455c394ffd6dc04ebed054b19539e339b655eb7\", \"traffic_annotation_auditor_unittests\": \"a751bf21a7ac72ffb81ddb3f72ee3a8378f44cba\", \"ui_base_unittests\": \"f44ac35cfe0100d5af25775fe57bd55544a9e3d5\", \"ui_touch_selection_unittests\": \"a0b3ea91fa495ddcbd6bb21b2116af1098787189\", \"unit_tests\": \"42b81888f73bfa1d4f425061b1f6b59ab27261ae\", \"updater_tests\": \"30e3ee90f3f6388c85600d6f08beb86faf9e1640\", \"url_unittests\": \"eea1aba62a0a470fe61554f4564fabf9ee541bc3\", \"views_unittests\": \"d448cf6bb004d2e6ea0b0d8903fbfba39c04b640\", \"viz_unittests\": \"4347102ad0b9049ac6afe1cac989fd63544b639c\", \"vr_common_unittests\": \"256fc98d7e8cabc64cf4c727d88f32a7cd74a052\", \"vr_pixeltests\": \"55e6101e285680895d612bb9fcfaf771801d3dd5\", \"weblayer_browsertests\": \"47faee2a3cc5576dc94fc8c12de6dc4b73b59e89\", \"weblayer_unittests\": \"9abab3e59bf85aabfc66ec8912ce00fcadd1c41e\", \"wm_unittests\": \"aa5cac503a51fee18f48f62081c67e01c1950346\", \"wtf_unittests\": \"2dd747310a1d13014c454d6899d3279dfa84e078\", \"zlib_unittests\": \"827cd81e434ea291b3382deacf14a071d3870896\", \"zucchini_unittests\": \"5fc74bbf6409902855f80c97fb4a1f73fc22a570\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_64c7a62a02e0da5ef76200bb7658267e40edf0984fe7c0f995e5999242969052_v2\", \"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-4-qhsy\"], \"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-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1606463564758201",
"status": "COMPLETED",
"status_changed_ts": "1606467242925209",
"tags": [
"build_address:luci.chromium.ci/win-asan/15880",
"builder:win-asan",
"buildset:commit/git/0274fffe5117c594d7290a3ce2c627786306bb77",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0274fffe5117c594d7290a3ce2c627786306bb77",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027924646305596224",
"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/8862494584211339616/+/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:5021821fbfcaa110",
"user_agent:luci-scheduler"
],
"updated_ts": "1606467242931535",
"url": "https://ci.chromium.org/b/8862494584211339616",
"utcnow_ts": "1606467244045992"
}
}
[08:54:04.101] Invocation finished in 1h1m27.525722184s with status SUCCEEDED