[18:21:35.208] New invocation is queued and will start shortly
[18:21:36.276] Starting the invocation (attempt 1)
[18:21:36.314] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:21:36.314] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:21:36.314] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030240595622949728",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4d7f92026a14102aab8055f3a8ffed91d83ef6ec\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9030240595622949728",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/4d7f92026a14102aab8055f3a8ffed91d83ef6ec",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4d7f92026a14102aab8055f3a8ffed91d83ef6ec",
"gitiles_ref:refs/heads/master"
]
}
[18:21:37.528] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604254896430976",
"id": "8864810533679488496",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4d7f92026a14102aab8055f3a8ffed91d83ef6ec\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604254897222378",
"tags": [
"build_address:luci.chromium.ci/win-asan/15398",
"builder:win-asan",
"buildset:commit/git/4d7f92026a14102aab8055f3a8ffed91d83ef6ec",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4d7f92026a14102aab8055f3a8ffed91d83ef6ec",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030240595622949728",
"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/8864810533679488496/+/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": "1604254897222736",
"url": "https://ci.chromium.org/b/8864810533679488496",
"utcnow_ts": "1604254897474631"
}
}
[18:21:37.528] Task URL: https://ci.chromium.org/b/8864810533679488496
[18:21:37.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:2:0) after 1m0s
[18:21:43.952] Received PubSub notification, asking Buildbucket for the build status
[18:21:44.028] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:37.610] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:2:0)
[18:22:37.610] Timer tick, asking Buildbucket for the build status
[18:22:37.706] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:37.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:4:0) after 1m0s
[18:23:37.803] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:4:0)
[18:23:37.803] Timer tick, asking Buildbucket for the build status
[18:23:37.903] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:37.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:5:0) after 1m0s
[18:24:37.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:5:0)
[18:24:37.911] Timer tick, asking Buildbucket for the build status
[18:24:38.339] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:38.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:6:0) after 1m0s
[18:25:38.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:6:0)
[18:25:38.404] Timer tick, asking Buildbucket for the build status
[18:25:38.703] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:38.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:7:0) after 1m0s
[18:26:38.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:7:0)
[18:26:38.780] Timer tick, asking Buildbucket for the build status
[18:26:38.880] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:38.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:8:0) after 1m0s
[18:27:38.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:8:0)
[18:27:38.952] Timer tick, asking Buildbucket for the build status
[18:27:39.104] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:39.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:9:0) after 1m0s
[18:28:39.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:9:0)
[18:28:39.168] Timer tick, asking Buildbucket for the build status
[18:28:39.232] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:39.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:10:0) after 1m0s
[18:29:39.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:10:0)
[18:29:39.236] Timer tick, asking Buildbucket for the build status
[18:29:39.325] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:39.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:11:0) after 1m0s
[18:30:39.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:11:0)
[18:30:39.475] Timer tick, asking Buildbucket for the build status
[18:30:39.572] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:39.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:12:0) after 1m0s
[18:31:39.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:12:0)
[18:31:39.601] Timer tick, asking Buildbucket for the build status
[18:31:39.807] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:39.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:13:0) after 1m0s
[18:32:39.840] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:13:0)
[18:32:39.840] Timer tick, asking Buildbucket for the build status
[18:32:40.283] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:40.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:14:0) after 1m0s
[18:33:40.307] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:14:0)
[18:33:40.307] Timer tick, asking Buildbucket for the build status
[18:33:40.553] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:40.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:15:0) after 1m0s
[18:34:40.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:15:0)
[18:34:40.727] Timer tick, asking Buildbucket for the build status
[18:34:41.288] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:41.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:16:0) after 1m0s
[18:35:41.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:16:0)
[18:35:41.313] Timer tick, asking Buildbucket for the build status
[18:35:41.444] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:41.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:17:0) after 1m0s
[18:36:41.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:17:0)
[18:36:41.494] Timer tick, asking Buildbucket for the build status
[18:36:42.078] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:42.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:18:0) after 1m0s
[18:37:42.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:18:0)
[18:37:42.144] Timer tick, asking Buildbucket for the build status
[18:37:42.601] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:42.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:19:0) after 1m0s
[18:38:42.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:19:0)
[18:38:42.626] Timer tick, asking Buildbucket for the build status
[18:38:42.739] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:42.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:20:0) after 1m0s
[18:39:42.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:20:0)
[18:39:42.769] Timer tick, asking Buildbucket for the build status
[18:39:42.891] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:42.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:21:0) after 1m0s
[18:40:42.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:21:0)
[18:40:42.914] Timer tick, asking Buildbucket for the build status
[18:40:43.025] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:43.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:22:0) after 1m0s
[18:41:43.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:22:0)
[18:41:43.048] Timer tick, asking Buildbucket for the build status
[18:41:43.288] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:43.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:23:0) after 1m0s
[18:42:43.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:23:0)
[18:42:43.314] Timer tick, asking Buildbucket for the build status
[18:42:43.685] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:43.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:24:0) after 1m0s
[18:43:43.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:24:0)
[18:43:43.716] Timer tick, asking Buildbucket for the build status
[18:43:43.901] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:43.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:25:0) after 1m0s
[18:44:43.920] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:25:0)
[18:44:43.920] Timer tick, asking Buildbucket for the build status
[18:44:44.273] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:44.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:26:0) after 1m0s
[18:45:44.416] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:26:0)
[18:45:44.416] Timer tick, asking Buildbucket for the build status
[18:45:44.540] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:44.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:27:0) after 1m0s
[18:46:44.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:27:0)
[18:46:44.622] Timer tick, asking Buildbucket for the build status
[18:46:44.869] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:44.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:28:0) after 1m0s
[18:47:44.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:28:0)
[18:47:44.990] Timer tick, asking Buildbucket for the build status
[18:47:45.210] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:45.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:29:0) after 1m0s
[18:48:45.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:29:0)
[18:48:45.227] Timer tick, asking Buildbucket for the build status
[18:48:45.565] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:45.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:30:0) after 1m0s
[18:49:45.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:30:0)
[18:49:45.595] Timer tick, asking Buildbucket for the build status
[18:49:45.825] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:45.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:31:0) after 1m0s
[18:50:45.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:31:0)
[18:50:45.939] Timer tick, asking Buildbucket for the build status
[18:50:46.362] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:46.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:32:0) after 1m0s
[18:51:46.441] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:32:0)
[18:51:46.441] Timer tick, asking Buildbucket for the build status
[18:51:46.548] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:46.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:33:0) after 1m0s
[18:52:46.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:33:0)
[18:52:46.554] Timer tick, asking Buildbucket for the build status
[18:52:46.691] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:46.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:34:0) after 1m0s
[18:53:46.713] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:34:0)
[18:53:46.713] Timer tick, asking Buildbucket for the build status
[18:53:46.857] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:46.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:35:0) after 1m0s
[18:54:46.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:35:0)
[18:54:46.879] Timer tick, asking Buildbucket for the build status
[18:54:47.364] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:47.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:36:0) after 1m0s
[18:55:47.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:36:0)
[18:55:47.477] Timer tick, asking Buildbucket for the build status
[18:55:47.576] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:47.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:37:0) after 1m0s
[18:56:47.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:37:0)
[18:56:47.651] Timer tick, asking Buildbucket for the build status
[18:56:47.763] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:47.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:38:0) after 1m0s
[18:57:47.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:38:0)
[18:57:47.820] Timer tick, asking Buildbucket for the build status
[18:57:48.676] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:48.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:39:0) after 1m0s
[18:58:48.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:39:0)
[18:58:48.785] Timer tick, asking Buildbucket for the build status
[18:58:48.861] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:48.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:40:0) after 1m0s
[18:59:49.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:40:0)
[18:59:49.018] Timer tick, asking Buildbucket for the build status
[18:59:49.130] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:49.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:41:0) after 1m0s
[19:00:49.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:41:0)
[19:00:49.209] Timer tick, asking Buildbucket for the build status
[19:00:49.789] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:49.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:42:0) after 1m0s
[19:01:49.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:42:0)
[19:01:49.915] Timer tick, asking Buildbucket for the build status
[19:01:50.026] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:50.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:43:0) after 1m0s
[19:02:50.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:43:0)
[19:02:50.313] Timer tick, asking Buildbucket for the build status
[19:02:50.415] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:50.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:44:0) after 1m0s
[19:03:50.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:44:0)
[19:03:50.490] Timer tick, asking Buildbucket for the build status
[19:03:50.778] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:50.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:45:0) after 1m0s
[19:04:50.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:45:0)
[19:04:50.813] Timer tick, asking Buildbucket for the build status
[19:04:51.093] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:51.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:46:0) after 1m0s
[19:05:51.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:46:0)
[19:05:51.133] Timer tick, asking Buildbucket for the build status
[19:05:51.278] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:51.278] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:47:0) after 1m0s
[19:06:51.275] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:47:0)
[19:06:51.275] Timer tick, asking Buildbucket for the build status
[19:06:51.423] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:51.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:48:0) after 1m0s
[19:07:51.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:48:0)
[19:07:51.467] Timer tick, asking Buildbucket for the build status
[19:07:51.552] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:51.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:49:0) after 1m0s
[19:08:51.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:49:0)
[19:08:51.604] Timer tick, asking Buildbucket for the build status
[19:08:51.887] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:51.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:50:0) after 1m0s
[19:09:51.908] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:50:0)
[19:09:51.908] Timer tick, asking Buildbucket for the build status
[19:09:52.019] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:52.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:51:0) after 1m0s
[19:10:52.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:51:0)
[19:10:52.058] Timer tick, asking Buildbucket for the build status
[19:10:52.211] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:52.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:52:0) after 1m0s
[19:11:52.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:52:0)
[19:11:52.239] Timer tick, asking Buildbucket for the build status
[19:11:52.349] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:52.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:53:0) after 1m0s
[19:12:52.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:53:0)
[19:12:52.427] Timer tick, asking Buildbucket for the build status
[19:12:52.524] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:52.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:54:0) after 1m0s
[19:13:52.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:54:0)
[19:13:52.592] Timer tick, asking Buildbucket for the build status
[19:13:52.694] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:52.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:55:0) after 1m0s
[19:14:52.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:55:0)
[19:14:52.719] Timer tick, asking Buildbucket for the build status
[19:14:53.000] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:53.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:56:0) after 1m0s
[19:15:53.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:56:0)
[19:15:53.008] Timer tick, asking Buildbucket for the build status
[19:15:53.169] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:53.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:57:0) after 1m0s
[19:16:53.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:57:0)
[19:16:53.257] Timer tick, asking Buildbucket for the build status
[19:16:53.469] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:53.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:58:0) after 1m0s
[19:17:53.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:58:0)
[19:17:53.482] Timer tick, asking Buildbucket for the build status
[19:17:53.730] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:53.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:59:0) after 1m0s
[19:18:53.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:59:0)
[19:18:53.752] Timer tick, asking Buildbucket for the build status
[19:18:53.824] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:53.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:60:0) after 1m0s
[19:19:53.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:60:0)
[19:19:53.938] Timer tick, asking Buildbucket for the build status
[19:19:54.087] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:54.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:61:0) after 1m0s
[19:20:54.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:61:0)
[19:20:54.132] Timer tick, asking Buildbucket for the build status
[19:20:54.301] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:54.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:62:0) after 1m0s
[19:21:54.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:62:0)
[19:21:54.384] Timer tick, asking Buildbucket for the build status
[19:21:54.689] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:54.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:63:0) after 1m0s
[19:22:54.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:63:0)
[19:22:54.717] Timer tick, asking Buildbucket for the build status
[19:22:54.800] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:54.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:64:0) after 1m0s
[19:23:54.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:64:0)
[19:23:54.820] Timer tick, asking Buildbucket for the build status
[19:23:54.975] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:54.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:65:0) after 1m0s
[19:24:55.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:65:0)
[19:24:55.036] Timer tick, asking Buildbucket for the build status
[19:24:55.116] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:55.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:66:0) after 1m0s
[19:25:55.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:66:0)
[19:25:55.193] Timer tick, asking Buildbucket for the build status
[19:25:55.426] Build 8864810533679488496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:55.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030240595622949728:67:0) after 1m0s
[19:26:44.230] Received PubSub notification, asking Buildbucket for the build status
[19:26:44.295] Build 8864810533679488496: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:26:44.296] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604258803089827",
"created_by": "project:chromium",
"created_ts": "1604254896430976",
"id": "8864810533679488496",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4d7f92026a14102aab8055f3a8ffed91d83ef6ec\"}}",
"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\": \"4d7f92026a14102aab8055f3a8ffed91d83ef6ec\", \"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-5-387x\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15398, \"clang_revision\": \"llvmorg-12-init-5627-gf086e85e-2\", \"got_angle_revision\": \"6c618ce31c0099c71ba0279153cbf6d04a2429d4\", \"got_dawn_revision\": \"0a374dce665328391ab3b68fd9e667b018dd9dcd\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"4d7f92026a14102aab8055f3a8ffed91d83ef6ec\", \"got_revision_cp\": \"refs/heads/master@{#823024}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"e0de282e678b72dac25b81f8d25305231aec01f8\", \"got_v8_revision\": \"69c856dc816ff1dd65fcc536095b44399a7434b9\", \"got_v8_revision_cp\": \"refs/heads/8.8.214@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4d7f92026a14102aab8055f3a8ffed91d83ef6ec\", \"swarm_hashes_refs/heads/master(at){#823024}_without_patch\": {\"absl_hardening_tests\": \"d5a86944f2a750fb455a9a2699620e68bdd97190\", \"accessibility_unittests\": \"3dd78f066e1083cbe626f394177f628fdb1c9df4\", \"angle_unittests\": \"bfeca859b95f4e61f5861de5d4d77a887aee5913\", \"app_shell_unittests\": \"05c6c10997fce65d447ffbd0135cebb0c7b30d8c\", \"aura_unittests\": \"a3680e160b244cfa7330e2db66ce6d8c74602e42\", \"base_unittests\": \"dff8dc06b5c39f36d1f7122678558135b60c9fdf\", \"base_util_unittests\": \"5ebd7ae8e2bf642902d1e8348fb5b7c271f5c80e\", \"blink_common_unittests\": \"63ac0e99e52bf48c3c9322bfa0208b705bcde983\", \"blink_fuzzer_unittests\": \"ccd6c04d35b771d4fdb4172c0a4ad8d55f08e004\", \"blink_heap_unittests\": \"45ba7d8e5c4d5b7f71d363fe9a4062b885880dce\", \"blink_platform_unittests\": \"1dda9a9f44fecc1ca4edc3038cd762e734c7940d\", \"blink_unittests\": \"cda88455303514ed4f4924dc5cd1e18dbe2e0eee\", \"boringssl_crypto_tests\": \"88cbfece12d9cb6fc9b8ac4db1e012d8775d31a2\", \"boringssl_ssl_tests\": \"bd1f7c2582a548a6144caa5ea57061db5d3159d5\", \"browser_switcher_bho_unittests\": \"1ed50446122794a2fa7b2c38f63f8972cffd5b8c\", \"browser_tests\": \"eaca131904f16f54df8463917e81adf713afec95\", \"capture_unittests\": \"440270c28c83637631fd9be67695be700e5f51fa\", \"cast_unittests\": \"5ce89d1d1022cedc2e48123c7679f76e6c3c82ee\", \"cc_unittests\": \"c4e97470db37493098b056cfcf2a154a7928b3f9\", \"chrome_app_unittests\": \"1ceafe27772149ebafde44490e7986289bc74159\", \"chrome_cleaner_unittests\": \"1e0dedf30aeeeb53cb847f1cc601614763dacbae\", \"chrome_elf_unittests\": \"135bc0f3e82834ad007b816d5f7d8675b91157d4\", \"chromedriver_unittests\": \"ac5ceb411c4f30ef1684b7a57d9b0449cc3f4cc4\", \"components_browsertests\": \"e9eefe7001a6952e7be694a7c1070f1e7fd4e42a\", \"components_unittests\": \"12cb65e638e6bbb3b52acf1bdc42df15cfe3023a\", \"compositor_unittests\": \"de6f1af0973225f4690671d6c8404da936be760a\", \"content_browsertests\": \"204f3aacd28f0ba2cca3d4c85f9e9a11dda45179\", \"content_unittests\": \"0784ea6ff43fbb23ce28eadcd68eb58e4e470471\", \"courgette_unittests\": \"7d13ee8c04571136cc677c437e6d5ca9b3b07f6f\", \"crashpad_tests\": \"7d50ff645dde37d0f12477bec3b0ebc2ff9c6556\", \"cronet_tests\": \"a183f9d7ded73f7a8bbc7cad14b41e6ff315b9ef\", \"cronet_unittests\": \"411a97a8517d964f06e73ed4324b120669f909d3\", \"crypto_unittests\": \"6719b0c5b489d31b48daea1b5cdbee159cda404f\", \"delayloads_unittests\": \"73cb0b3709988ca9a5f167d6d4890795e92e3b88\", \"device_unittests\": \"ec0824aa4d3c488ec9eeb9f66ddce9b6781c0e40\", \"display_unittests\": \"48e36a521c81286942c8f880fef43a49e6ba4777\", \"elevation_service_unittests\": \"c099583fab3baf52964a7e271a681be736c1ab4f\", \"events_unittests\": \"4dd4df336aa687d211ffd05870684e685b04a419\", \"extensions_browsertests\": \"ccefab3ed5b6d7923eb922ed7d24df85e6e791ad\", \"extensions_unittests\": \"1312b4b885830c4fa608d26d715088748d165081\", \"filesystem_service_unittests\": \"9c374d29a56855ec6ae163f0029e24a70d410591\", \"gcm_unit_tests\": \"c00762cd44e886fce30c449d32b8b2f37718a339\", \"gcp_unittests\": \"3fe30ae84b10e3663dc90a78b31cc14bb69b8526\", \"gfx_unittests\": \"d2d4df179d9a365f6eb0e3472b195eafcd6c19eb\", \"gin_unittests\": \"423efac58365bece43917c4d0cfdb4ab20899643\", \"google_apis_unittests\": \"774d50de00f1a2be2c513ebacea21ba3260ad53f\", \"gpu_unittests\": \"25699442c984b610d05d76e97d1ae865ef234ab7\", \"gwp_asan_unittests\": \"38a832f8d8d57c9f0f126591410bd172beca474d\", \"headless_browsertests\": \"b64f0159dc17422ba0002fcfb9849fffe6caeef2\", \"headless_unittests\": \"297c80bf192aa4ff38c6c9688669853e6ced847c\", \"install_static_unittests\": \"de9a576147d9ad241be3643d31a22b1cedac0913\", \"installer_util_unittests\": \"e44c2a701ee930a084e2dbe61779f0edf5aaa60d\", \"interactive_ui_tests\": \"df355f59f289eaf6f9c5dda21399f45160d16af8\", \"ipc_tests\": \"89c1e1a2defac9d10303eb6380faaf4317215cec\", \"jingle_unittests\": \"1b75afbc7f281355562f5a6323060ba650b6b74b\", \"latency_unittests\": \"3606d3a957c7d7c3a6615288d727cf0146aba7f5\", \"libjingle_xmpp_unittests\": \"1b08636bc77c9f7b7932280b93f38f72c7560990\", \"liburlpattern_unittests\": \"9be2c250e7759e35a7fb76719aee63f52d108561\", \"media_blink_unittests\": \"8262da806aceb92f62a195a7638508a09bd05076\", \"media_unittests\": \"21ba7a86b9c842c58d1675fc0bf14aa616a57fac\", \"message_center_unittests\": \"04ee12fcf2b378a0f8c929816bc601d5c77623b6\", \"midi_unittests\": \"19354b633ba0b200533e147034938a8d14948e36\", \"mojo_core_unittests\": \"e48c911080a6f5f560be8c1afea807a97a9275b0\", \"mojo_unittests\": \"d9ff4cec989faa0fb7f42c946850049a79cb9423\", \"nacl_loader_unittests\": \"67decb4e35d99e50ac8ba3385f357f9abca5f934\", \"native_theme_unittests\": \"5733d8be35da31eb4d371c5520cb706e8e0bb722\", \"net_unittests\": \"f25a0ab68735471605bc807c379e10ade0cf2b3b\", \"notification_helper_unittests\": \"7f3c3eaae912549535846d102984d1ba953f17de\", \"pdf_unittests\": \"f38c36b57e12698fdc3d7536f889e3981dc09627\", \"perfetto_unittests\": \"c95d5f0a9c3aa8a18b77d8ad5835e9a6b6f25c35\", \"ppapi_unittests\": \"2dea99cc411ff824e558c44efb8cab86410ba4bd\", \"printing_unittests\": \"7b69dddce20405e06a4f460229b996064f0f8513\", \"remoting_unittests\": \"a7623e812b028a6b3dfc25738d468f39a0a1e2fb\", \"sbox_integration_tests\": \"dbceea529608fd7babb570c35b15b1cbfb279d3c\", \"sbox_unittests\": \"b80b87758729672cffa9968eeb2c811496263cea\", \"sbox_validation_tests\": \"6769eb943f975f2910eab84bf63d18e826b46922\", \"service_manager_unittests\": \"ca742b4aa128b4f28bfa12a5c3fd0b9646900466\", \"services_unittests\": \"f9d7068f1d59a7a0562ba70bb5b2a9cf429021c0\", \"setup_unittests\": \"9207f9e46b53eb0ebf306b37f7eb0e70365687e7\", \"shell_dialogs_unittests\": \"e0a0d70a04c504a574bcd78ca79f5bff9a06e649\", \"skia_unittests\": \"d9e0b36aeb1a6d5f1866db2fd0fa7a74e78343b0\", \"snapshot_unittests\": \"affb4b774a7b50eeb9e97c4ac7c1754375244f92\", \"sql_unittests\": \"37c52cd19638482955dd59cf4266d5d6cdb033e9\", \"storage_unittests\": \"c0a15ff43ae1cf40132f4a5b88a8f695f8b7ad60\", \"sync_integration_tests\": \"d2c55db4ae138699fcfd03ed25131602aeeb724b\", \"traffic_annotation_auditor_unittests\": \"e883d2bab0d1d91bbc5b9d0e45555c915e32a196\", \"ui_base_unittests\": \"569e4e96c77d7905d6b65551fe86ef367bdcc2e1\", \"ui_touch_selection_unittests\": \"81555d0b90948084eb17f538ee612c787b18dd9e\", \"unit_tests\": \"a9246b1f2943fa9ab8c5a0f1a4881fb0901e847d\", \"updater_tests\": \"1f8d7ccc16e130491bae1718898fafcc044be6b2\", \"url_unittests\": \"bc76fda48236d5e774c3f008f91b448da7e31413\", \"views_unittests\": \"d9359ae1af08d14a364674d9d3022fd33a06f1e3\", \"viz_unittests\": \"8b83324c88298a0eb4acd7fe4513a0117f0a05b0\", \"vr_common_unittests\": \"2282c8cb17258931817c82ed87b3f1d961cac1a9\", \"vr_pixeltests\": \"7624ba20233adc5bcedb19e1ac3e4400d2d1f6f7\", \"weblayer_browsertests\": \"93f307eb9d9d13bba30cf15d9547d8a0c14684a7\", \"weblayer_unittests\": \"2f184efcfe2f145dd2170c883604ad4bb7f22ad6\", \"wm_unittests\": \"72cf97d994ed3d02acf5ff9185a0046d8ff31bf6\", \"wtf_unittests\": \"44e644aac53015039327f9918f758a08e3bec110\", \"zlib_unittests\": \"68dcde7ea2dc3f534e393188e9561c32ecd644c8\", \"zucchini_unittests\": \"944d862ac9b593887a7d63a508f6d5ea9e14c8e6\"}}, \"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-5-387x\"], \"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": "1604254900832046",
"status": "COMPLETED",
"status_changed_ts": "1604258803433659",
"tags": [
"build_address:luci.chromium.ci/win-asan/15398",
"builder:win-asan",
"buildset:commit/git/4d7f92026a14102aab8055f3a8ffed91d83ef6ec",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4d7f92026a14102aab8055f3a8ffed91d83ef6ec",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030240595622949728",
"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/8864810533679488496/+/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:4f9ddca043440510",
"user_agent:luci-scheduler"
],
"updated_ts": "1604258803440348",
"url": "https://ci.chromium.org/b/8864810533679488496",
"utcnow_ts": "1604258804287451"
}
}
[19:26:44.296] Invocation finished in 1h5m9.102816777s with status SUCCEEDED