[09:27:31.169] New invocation is queued and will start shortly
[09:27:32.283] Starting the invocation (attempt 1)
[09:27:32.358] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:27:32.358] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:27:32.358] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029821211405384608",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"fa00fce5197f8efd56c736353feb7cd54dd7417d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029821211405384608",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/fa00fce5197f8efd56c736353feb7cd54dd7417d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fa00fce5197f8efd56c736353feb7cd54dd7417d",
"gitiles_ref:refs/heads/master"
]
}
[09:27:33.073] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604654852461392",
"id": "8864391149383738224",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fa00fce5197f8efd56c736353feb7cd54dd7417d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604654852931987",
"tags": [
"build_address:luci.chromium.ci/win-asan/15493",
"builder:win-asan",
"buildset:commit/git/fa00fce5197f8efd56c736353feb7cd54dd7417d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fa00fce5197f8efd56c736353feb7cd54dd7417d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029821211405384608",
"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/8864391149383738224/+/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": "1604654852932138",
"url": "https://ci.chromium.org/b/8864391149383738224",
"utcnow_ts": "1604654853058776"
}
}
[09:27:33.073] Task URL: https://ci.chromium.org/b/8864391149383738224
[09:27:33.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:2:0) after 1m0s
[09:27:40.731] Received PubSub notification, asking Buildbucket for the build status
[09:27:40.835] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:34.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:2:0)
[09:28:34.379] Timer tick, asking Buildbucket for the build status
[09:28:34.471] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:34.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:4:0) after 1m0s
[09:29:34.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:4:0)
[09:29:34.663] Timer tick, asking Buildbucket for the build status
[09:29:34.935] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:34.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:5:0) after 1m0s
[09:30:35.047] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:5:0)
[09:30:35.047] Timer tick, asking Buildbucket for the build status
[09:30:35.223] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:35.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:6:0) after 1m0s
[09:31:35.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:6:0)
[09:31:35.262] Timer tick, asking Buildbucket for the build status
[09:31:35.658] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:35.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:7:0) after 1m0s
[09:32:35.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:7:0)
[09:32:35.674] Timer tick, asking Buildbucket for the build status
[09:32:35.790] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:35.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:8:0) after 1m0s
[09:33:35.871] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:8:0)
[09:33:35.871] Timer tick, asking Buildbucket for the build status
[09:33:37.247] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:37.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:9:0) after 1m0s
[09:34:37.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:9:0)
[09:34:37.277] Timer tick, asking Buildbucket for the build status
[09:34:37.479] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:37.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:10:0) after 1m0s
[09:35:37.563] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:10:0)
[09:35:37.563] Timer tick, asking Buildbucket for the build status
[09:35:37.647] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:37.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:11:0) after 1m0s
[09:36:37.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:11:0)
[09:36:37.708] Timer tick, asking Buildbucket for the build status
[09:36:37.901] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:37.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:12:0) after 1m0s
[09:37:37.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:12:0)
[09:37:37.977] Timer tick, asking Buildbucket for the build status
[09:37:38.111] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:38.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:13:0) after 1m0s
[09:38:38.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:13:0)
[09:38:38.244] Timer tick, asking Buildbucket for the build status
[09:38:38.320] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:38.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:14:0) after 1m0s
[09:39:38.342] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:14:0)
[09:39:38.342] Timer tick, asking Buildbucket for the build status
[09:39:38.619] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:38.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:15:0) after 1m0s
[09:40:38.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:15:0)
[09:40:38.675] Timer tick, asking Buildbucket for the build status
[09:40:38.784] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:38.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:16:0) after 1m0s
[09:41:38.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:16:0)
[09:41:38.831] Timer tick, asking Buildbucket for the build status
[09:41:38.942] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:38.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:17:0) after 1m0s
[09:42:38.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:17:0)
[09:42:38.933] Timer tick, asking Buildbucket for the build status
[09:42:39.307] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:39.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:18:0) after 1m0s
[09:43:39.349] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:18:0)
[09:43:39.349] Timer tick, asking Buildbucket for the build status
[09:43:39.411] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:39.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:19:0) after 1m0s
[09:44:39.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:19:0)
[09:44:39.479] Timer tick, asking Buildbucket for the build status
[09:44:39.832] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:39.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:20:0) after 1m0s
[09:45:39.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:20:0)
[09:45:39.921] Timer tick, asking Buildbucket for the build status
[09:45:40.062] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:40.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:21:0) after 1m0s
[09:46:40.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:21:0)
[09:46:40.084] Timer tick, asking Buildbucket for the build status
[09:46:40.195] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:40.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:22:0) after 1m0s
[09:47:40.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:22:0)
[09:47:40.220] Timer tick, asking Buildbucket for the build status
[09:47:40.452] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:40.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:23:0) after 1m0s
[09:48:40.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:23:0)
[09:48:40.462] Timer tick, asking Buildbucket for the build status
[09:48:40.526] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:40.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:24:0) after 1m0s
[09:49:40.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:24:0)
[09:49:40.566] Timer tick, asking Buildbucket for the build status
[09:49:40.715] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:40.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:25:0) after 1m0s
[09:50:40.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:25:0)
[09:50:40.753] Timer tick, asking Buildbucket for the build status
[09:50:41.058] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:41.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:26:0) after 1m0s
[09:51:41.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:26:0)
[09:51:41.090] Timer tick, asking Buildbucket for the build status
[09:51:41.920] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:41.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:27:0) after 1m0s
[09:52:41.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:27:0)
[09:52:41.953] Timer tick, asking Buildbucket for the build status
[09:52:42.120] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:42.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:28:0) after 1m0s
[09:53:42.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:28:0)
[09:53:42.138] Timer tick, asking Buildbucket for the build status
[09:53:42.656] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:42.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:29:0) after 1m0s
[09:54:42.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:29:0)
[09:54:42.662] Timer tick, asking Buildbucket for the build status
[09:54:43.259] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:43.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:30:0) after 1m0s
[09:55:43.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:30:0)
[09:55:43.363] Timer tick, asking Buildbucket for the build status
[09:55:43.500] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:43.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:31:0) after 1m0s
[09:56:43.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:31:0)
[09:56:43.878] Timer tick, asking Buildbucket for the build status
[09:56:44.536] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:44.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:32:0) after 1m0s
[09:57:44.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:32:0)
[09:57:44.655] Timer tick, asking Buildbucket for the build status
[09:57:44.769] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:44.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:33:0) after 1m0s
[09:58:44.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:33:0)
[09:58:44.859] Timer tick, asking Buildbucket for the build status
[09:58:45.182] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:45.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:34:0) after 1m0s
[09:59:45.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:34:0)
[09:59:45.200] Timer tick, asking Buildbucket for the build status
[09:59:45.780] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:45.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:35:0) after 1m0s
[10:00:45.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:35:0)
[10:00:45.960] Timer tick, asking Buildbucket for the build status
[10:00:46.412] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:46.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:36:0) after 1m0s
[10:01:46.807] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:36:0)
[10:01:46.807] Timer tick, asking Buildbucket for the build status
[10:01:46.954] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:46.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:37:0) after 1m0s
[10:02:47.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:37:0)
[10:02:47.147] Timer tick, asking Buildbucket for the build status
[10:02:47.288] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:47.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:38:0) after 1m0s
[10:03:47.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:38:0)
[10:03:47.358] Timer tick, asking Buildbucket for the build status
[10:03:47.424] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:47.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:39:0) after 1m0s
[10:04:47.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:39:0)
[10:04:47.696] Timer tick, asking Buildbucket for the build status
[10:04:48.038] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:48.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:40:0) after 1m0s
[10:05:48.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:40:0)
[10:05:48.231] Timer tick, asking Buildbucket for the build status
[10:05:48.308] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:48.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:41:0) after 1m0s
[10:06:48.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:41:0)
[10:06:48.382] Timer tick, asking Buildbucket for the build status
[10:06:48.537] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:48.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:42:0) after 1m0s
[10:07:48.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:42:0)
[10:07:48.689] Timer tick, asking Buildbucket for the build status
[10:07:48.973] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:48.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:43:0) after 1m0s
[10:08:49.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:43:0)
[10:08:49.205] Timer tick, asking Buildbucket for the build status
[10:08:49.283] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:49.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:44:0) after 1m0s
[10:09:49.349] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:44:0)
[10:09:49.349] Timer tick, asking Buildbucket for the build status
[10:09:50.149] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:50.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:45:0) after 1m0s
[10:10:50.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:45:0)
[10:10:50.209] Timer tick, asking Buildbucket for the build status
[10:10:50.349] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:50.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:46:0) after 1m0s
[10:11:50.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:46:0)
[10:11:50.442] Timer tick, asking Buildbucket for the build status
[10:11:50.578] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:50.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:47:0) after 1m0s
[10:12:50.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:47:0)
[10:12:50.668] Timer tick, asking Buildbucket for the build status
[10:12:50.815] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:50.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:48:0) after 1m0s
[10:13:50.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:48:0)
[10:13:50.806] Timer tick, asking Buildbucket for the build status
[10:13:50.967] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:50.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:49:0) after 1m0s
[10:14:50.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:49:0)
[10:14:50.962] Timer tick, asking Buildbucket for the build status
[10:14:51.227] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:51.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:50:0) after 1m0s
[10:15:51.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:50:0)
[10:15:51.229] Timer tick, asking Buildbucket for the build status
[10:15:51.885] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:51.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:51:0) after 1m0s
[10:16:51.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:51:0)
[10:16:51.925] Timer tick, asking Buildbucket for the build status
[10:16:52.506] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:52.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:52:0) after 1m0s
[10:17:52.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:52:0)
[10:17:52.564] Timer tick, asking Buildbucket for the build status
[10:17:52.669] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:52.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:53:0) after 1m0s
[10:18:52.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:53:0)
[10:18:52.761] Timer tick, asking Buildbucket for the build status
[10:18:53.166] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:53.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:54:0) after 1m0s
[10:19:53.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:54:0)
[10:19:53.309] Timer tick, asking Buildbucket for the build status
[10:19:53.464] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:53.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:55:0) after 1m0s
[10:20:53.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:55:0)
[10:20:53.651] Timer tick, asking Buildbucket for the build status
[10:20:53.782] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:53.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:56:0) after 1m0s
[10:21:53.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:56:0)
[10:21:53.888] Timer tick, asking Buildbucket for the build status
[10:21:54.005] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:54.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:57:0) after 1m0s
[10:22:54.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:57:0)
[10:22:54.377] Timer tick, asking Buildbucket for the build status
[10:22:54.484] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:54.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:58:0) after 1m0s
[10:23:54.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:58:0)
[10:23:54.700] Timer tick, asking Buildbucket for the build status
[10:23:54.783] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:54.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:59:0) after 1m0s
[10:24:54.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:59:0)
[10:24:54.886] Timer tick, asking Buildbucket for the build status
[10:24:55.356] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:55.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:60:0) after 1m0s
[10:25:55.370] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:60:0)
[10:25:55.370] Timer tick, asking Buildbucket for the build status
[10:25:56.035] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:56.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:61:0) after 1m0s
[10:26:56.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:61:0)
[10:26:56.126] Timer tick, asking Buildbucket for the build status
[10:26:56.209] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:56.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:62:0) after 1m0s
[10:27:56.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:62:0)
[10:27:56.281] Timer tick, asking Buildbucket for the build status
[10:27:56.404] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:56.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:63:0) after 1m0s
[10:28:56.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:63:0)
[10:28:56.437] Timer tick, asking Buildbucket for the build status
[10:28:56.506] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:56.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:64:0) after 1m0s
[10:29:56.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:64:0)
[10:29:56.581] Timer tick, asking Buildbucket for the build status
[10:29:57.086] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:57.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:65:0) after 1m0s
[10:30:57.160] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:65:0)
[10:30:57.160] Timer tick, asking Buildbucket for the build status
[10:30:57.272] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:57.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:66:0) after 1m0s
[10:31:57.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:66:0)
[10:31:57.386] Timer tick, asking Buildbucket for the build status
[10:31:57.973] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:57.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:67:0) after 1m0s
[10:32:58.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:67:0)
[10:32:58.027] Timer tick, asking Buildbucket for the build status
[10:32:58.119] Build 8864391149383738224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:58.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029821211405384608:68:0) after 1m0s
[10:33:37.824] Received PubSub notification, asking Buildbucket for the build status
[10:33:37.909] Build 8864391149383738224: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:33:37.910] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604658816811422",
"created_by": "project:chromium",
"created_ts": "1604654852461392",
"id": "8864391149383738224",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fa00fce5197f8efd56c736353feb7cd54dd7417d\"}}",
"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\": \"fa00fce5197f8efd56c736353feb7cd54dd7417d\", \"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-h4bf\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15493, \"clang_revision\": \"llvmorg-12-init-10666-gc9f69ee7-1\", \"got_angle_revision\": \"3dfaeeb00271b1e230e8cc03415cdbe7dd34efb6\", \"got_dawn_revision\": \"2b6b0f45ff0597a847d0da25485dce9e2278ee80\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"fa00fce5197f8efd56c736353feb7cd54dd7417d\", \"got_revision_cp\": \"refs/heads/master@{#824787}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"612ded0654a027d64922448f136d125a9dc17956\", \"got_v8_revision\": \"24c7b2d7fd1733d0bea46fdcc20f1b3d032c702b\", \"got_v8_revision_cp\": \"refs/heads/8.8.243@{#1}\", \"got_webrtc_revision\": \"42cafa5696f516712bbe6709fedeedffac61544c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32540}\", \"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\": \"fa00fce5197f8efd56c736353feb7cd54dd7417d\", \"swarm_hashes_refs/heads/master(at){#824787}_without_patch\": {\"absl_hardening_tests\": \"021eb9f2050bd0e26fbeab5c71f548cfcdc30966\", \"accessibility_unittests\": \"b36b19bb2328da3ff4bd36234cf94731e0cdd675\", \"angle_unittests\": \"1e4efef50286adf900038bde10053712fd957312\", \"app_shell_unittests\": \"1f110ad13cc43c0368c44a94b0517c93da4fb5f8\", \"aura_unittests\": \"52a0e1b1343f120c02c01cd53c69d30719b55dbf\", \"base_unittests\": \"48f12a61da28f4327bb52e269883246224d407c7\", \"base_util_unittests\": \"afa061f5e6b028e08827b6f427b25a4c2c46c752\", \"blink_common_unittests\": \"65e1ad86a7dacbdeda68eec99b4c8a28807326f2\", \"blink_fuzzer_unittests\": \"c7deaccc6d00fe6a9a3df1fcfa544f31ace01c07\", \"blink_heap_unittests\": \"ea0c94f6fb5f5704934a1f4aa169bf2f33a33037\", \"blink_platform_unittests\": \"36d747ff16ef89833e24e2a63dafbe9f023e5a5c\", \"blink_unittests\": \"b9cec70c1c62864dcaad481e09f13954dbb7a102\", \"boringssl_crypto_tests\": \"5056205557a2380160de094ec920ef7714c91ff7\", \"boringssl_ssl_tests\": \"e90058c71db486e655aa9cea1b9cabb4d6b8b9f4\", \"browser_switcher_bho_unittests\": \"7fc9c32f3e9334fe952633c59c8f165db2b3e1c6\", \"browser_tests\": \"ed1f834bcac400f0bd07cf1511a446dcec07ec32\", \"capture_unittests\": \"22fb615dd9905a6b53f267d698790773f34c1047\", \"cast_unittests\": \"67534c1750df6743f9fb13a544cd2347ef1c9025\", \"cc_unittests\": \"a72f8dc6dabdae686dd01f42a8f50a188e58aaed\", \"chrome_app_unittests\": \"881d50ac6212255f75ceca8591711146cda5e88b\", \"chrome_cleaner_unittests\": \"481199c42c831bdb63ae393a312f3d414b17f1a3\", \"chrome_elf_unittests\": \"fbabba51b19fb7bda63d3341afd2465fade6e940\", \"chromedriver_unittests\": \"ee1744748205018c7b2b027f297c348b7f7ef0d7\", \"components_browsertests\": \"91d1399e83cb5622e0bbeb1b7a1f0df98d654c74\", \"components_unittests\": \"d57064848afa8b9d892fe18b2b8c0d5ce64eaf66\", \"compositor_unittests\": \"be3a68c4923e8015598251b0bb6f567b1706d73c\", \"content_browsertests\": \"07b48db5683fe11927e9ae5ae79fe4d1b71f5549\", \"content_unittests\": \"66b10fcf8095f92fd1a9ee7cf14ed3b32335b46c\", \"courgette_unittests\": \"5addf8b2259453e0d76235a7262ac4da7fe56d0d\", \"crashpad_tests\": \"464e5d180c0077589fd179be33407c3f219e7bfc\", \"cronet_tests\": \"f603cf8baa7f5c5a71a436079aa01cf23770b6ac\", \"cronet_unittests\": \"bed7060292328b2315a087c7a3701f501281aa34\", \"crypto_unittests\": \"cf08f9b215137e7358cfedca640f088751a89b1c\", \"delayloads_unittests\": \"f1930c1cbc99cacc0c87ef95e23ac9b48515e2f1\", \"device_unittests\": \"554e6ce4ac67536e0df381c1c8d3118c1fb9f3c5\", \"display_unittests\": \"8890c5ab21d97b5dc22de93a8a8f2d4c65e1a394\", \"elevation_service_unittests\": \"ad31a9ff51f68298167015f2ba6bfaa9164637e5\", \"events_unittests\": \"c3079ffc13a94edf3523617248786b7d7d19aa56\", \"extensions_browsertests\": \"5158370bb687c31c0b42bafa52dfeb8b17c974ca\", \"extensions_unittests\": \"85e4d63e81d423f3aaa07edebc76578566a6869e\", \"filesystem_service_unittests\": \"e1b9f99c7e7a2aea6c4c57d6343f5edd357665e0\", \"gcm_unit_tests\": \"4d1123221691df8d6b8063b00dc517076021752f\", \"gcp_unittests\": \"22f5f0a72e5ebc7949426f19b4f203efe99affb7\", \"gfx_unittests\": \"78ab4c36cb0dc0b0421f1d88b59f417411fe5895\", \"gin_unittests\": \"26026f14f72b2274c6603a5592b7223196124587\", \"google_apis_unittests\": \"eeefb58288c494a41e65390d13cff4ebc7544d3f\", \"gpu_unittests\": \"9ca0f6b3d5a01d9b5f81240abb4e82cd1bc7f32c\", \"gwp_asan_unittests\": \"ddddc663f0defbcf5a7516890f47b42dd7e370ed\", \"headless_browsertests\": \"56fca81a88566a2ccd2b79cf89fdc78fca7e6471\", \"headless_unittests\": \"5842bf958e5dd33b80d5e7b90226aae27615b2aa\", \"install_static_unittests\": \"90078e9c6ce81087916dda0b00eefa444638f667\", \"installer_util_unittests\": \"6596634dec8eb94f613c69fbe1a7542fcf15b800\", \"interactive_ui_tests\": \"0e407517e0683169bdac66285e23df0237f9427b\", \"ipc_tests\": \"f6cce61119f6a66c36a10f478f3d3c3edbe01c36\", \"jingle_unittests\": \"8baec5cbc041fb689f2880d083e92a06adbf0ef1\", \"latency_unittests\": \"3def60e1d53e2534b48617363b0cc745da9e8b8d\", \"libjingle_xmpp_unittests\": \"c5ee23768e8428dadfcb16b82ac0d963779ce634\", \"liburlpattern_unittests\": \"262de4a4b73a2b0fe45d2143bb3decc3347d3d38\", \"media_blink_unittests\": \"2da3adc2912b7660eb84b26a1040c48c0b0e5183\", \"media_unittests\": \"a4eeafafb0e2dc1285edd4c0741f72896089a5c9\", \"message_center_unittests\": \"009eb22ae87ec41df4cf8c4083b8927e752847c9\", \"midi_unittests\": \"2475d9830dbe9ee8013aebf7462b253666703abb\", \"mojo_core_unittests\": \"55f7376627bad252e08b74d974c65f60f5962f75\", \"mojo_unittests\": \"e874646f8a3511e27b65d534402c737407e6347c\", \"nacl_loader_unittests\": \"c21b8c6a4c968bcc58dd159f25dc85e1e9c143d1\", \"native_theme_unittests\": \"d7758389676c5af7fad90ef4fff861bb0662df79\", \"net_unittests\": \"5638a81d4466fdc7f1aa741bd795f7968547179e\", \"notification_helper_unittests\": \"fb472faba40f3791c9acb12b673a0c0691016b76\", \"pdf_unittests\": \"89d8b307c89c708107c13825302826976835fdd7\", \"perfetto_unittests\": \"d24d9954a969e4cc5d7d278474b03a0ba65e52f7\", \"ppapi_unittests\": \"af17a9a3d3cd2922079d43c12963009516795b4b\", \"printing_unittests\": \"a21fafe5af27a126ade019a656076481f2b0f71b\", \"remoting_unittests\": \"5bf550377b77d241ca258e85430e5eefbac0c96b\", \"sbox_integration_tests\": \"5f733291a5b6d0df8968c6e3ca6021f820277a45\", \"sbox_unittests\": \"cefdd0dc989ac5347e37cbddb407cf6c31584ebc\", \"sbox_validation_tests\": \"b74a233d52ca7a75ea17080e9d5c8c1abeee52af\", \"service_manager_unittests\": \"3e58fec5e0d18e3eebc884c4cfe9163bec1262ae\", \"services_unittests\": \"51e624920059361a1ce17875a100303a3fe9bb56\", \"setup_unittests\": \"d39aa68466f070a95b0bae077beeb54d72432241\", \"shell_dialogs_unittests\": \"e2d9f1e1a88e75dba9402bc4d50c90ca2f044a30\", \"skia_unittests\": \"0108145b8a552a258aa31534cd836c303c19ac89\", \"snapshot_unittests\": \"5892b0b21884b53a6ee72c6cd51e525cddec3d4d\", \"sql_unittests\": \"3a7d3ca2f2569e94c883bd0043ee21a798ad6834\", \"storage_unittests\": \"971d9d11f9860f0ee94ad709f4489c3617941171\", \"sync_integration_tests\": \"c68c824a49f901fd0bd82af704cb60049cc404f7\", \"traffic_annotation_auditor_unittests\": \"e6ccfd3b344f8ee42363d670ea4111585e286b03\", \"ui_base_unittests\": \"3f2004880de951ee579f13926f297f99155ae856\", \"ui_touch_selection_unittests\": \"4c93d3bf3d0e68434bab0e3246e14545c6bb6070\", \"unit_tests\": \"9ee98ba48958240d3777934579d01e66a655df73\", \"updater_tests\": \"ae9879de63770e76b69d69af5d95e0bf727026e1\", \"url_unittests\": \"4e075d6ef6100a1a82026b8ffd24ba06170250d3\", \"views_unittests\": \"26c17ec44545dbecf573077e5fa967d50a207ff4\", \"viz_unittests\": \"f05cf8508c27f97591ca70f9001cc039fc293e68\", \"vr_common_unittests\": \"156968a8c80084f85bbda98102bb0ec388f52c72\", \"vr_pixeltests\": \"26313407c8e7d862bf04912b1c50d39323372aaa\", \"weblayer_browsertests\": \"ee2cd8c26f3e1bd95edaf2b5ccd4c1004ddae9d3\", \"weblayer_unittests\": \"08641a5f72376f8abf4dcf7e9b9cf3e252ebeae2\", \"wm_unittests\": \"74a2d6e744e93e882d8a54ba10c88645bdff225c\", \"wtf_unittests\": \"e18d3251bfdadf17659bc6a7f6db8e3e9d28cd36\", \"zlib_unittests\": \"7d55904f60c2b5f678a8df1021c4f036a2f4e245\", \"zucchini_unittests\": \"3b108dbdbc5ba51129b4a90f88d7659f9646fb87\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_91b2ef8341586e76bd64fa3093e74ce43aef344c2e2134fb3b046b47c3d4bcdb_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-h4bf\"], \"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\": [\"5614-d862eaa\"], \"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": "1604654859959279",
"status": "COMPLETED",
"status_changed_ts": "1604658817464932",
"tags": [
"build_address:luci.chromium.ci/win-asan/15493",
"builder:win-asan",
"buildset:commit/git/fa00fce5197f8efd56c736353feb7cd54dd7417d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fa00fce5197f8efd56c736353feb7cd54dd7417d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029821211405384608",
"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/8864391149383738224/+/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:4fb5b37486086f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1604658817469200",
"url": "https://ci.chromium.org/b/8864391149383738224",
"utcnow_ts": "1604658817892339"
}
}
[10:33:37.911] Invocation finished in 1h6m6.76054409s with status SUCCEEDED