[19:35:42.932] New invocation is queued and will start shortly
[19:35:44.094] Starting the invocation (attempt 1)
[19:35:44.154] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:35:44.154] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:35:44.155] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023712950265715008",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a0f906860f838339837e3968f2d1a1f2b3d27435\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023712950265715008",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/a0f906860f838339837e3968f2d1a1f2b3d27435",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a0f906860f838339837e3968f2d1a1f2b3d27435",
"gitiles_ref:refs/heads/master"
]
}
[19:35:44.903] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610480144309095",
"id": "8858282888159370208",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a0f906860f838339837e3968f2d1a1f2b3d27435\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610480144740659",
"tags": [
"build_address:luci.chromium.ci/win-asan/16781",
"builder:win-asan",
"buildset:commit/git/a0f906860f838339837e3968f2d1a1f2b3d27435",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a0f906860f838339837e3968f2d1a1f2b3d27435",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023712950265715008",
"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/8858282888159370208/+/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": "1610480144740802",
"url": "https://ci.chromium.org/b/8858282888159370208",
"utcnow_ts": "1610480144888672"
}
}
[19:35:44.903] Task URL: https://ci.chromium.org/b/8858282888159370208
[19:35:44.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:2:0) after 1m0s
[19:35:53.386] Received PubSub notification, asking Buildbucket for the build status
[19:35:53.530] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:45.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:2:0)
[19:36:45.262] Timer tick, asking Buildbucket for the build status
[19:36:45.340] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:45.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:4:0) after 1m0s
[19:37:45.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:4:0)
[19:37:45.386] Timer tick, asking Buildbucket for the build status
[19:37:45.474] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:45.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:5:0) after 1m0s
[19:38:45.637] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:5:0)
[19:38:45.637] Timer tick, asking Buildbucket for the build status
[19:38:45.751] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:45.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:6:0) after 1m0s
[19:39:45.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:6:0)
[19:39:45.899] Timer tick, asking Buildbucket for the build status
[19:39:46.380] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:46.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:7:0) after 1m0s
[19:40:46.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:7:0)
[19:40:46.379] Timer tick, asking Buildbucket for the build status
[19:40:46.618] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:46.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:8:0) after 1m0s
[19:41:46.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:8:0)
[19:41:46.646] Timer tick, asking Buildbucket for the build status
[19:41:47.071] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:47.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:9:0) after 1m0s
[19:42:47.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:9:0)
[19:42:47.144] Timer tick, asking Buildbucket for the build status
[19:42:47.228] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:47.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:10:0) after 1m0s
[19:43:47.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:10:0)
[19:43:47.311] Timer tick, asking Buildbucket for the build status
[19:43:47.407] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:47.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:11:0) after 1m0s
[19:44:47.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:11:0)
[19:44:47.398] Timer tick, asking Buildbucket for the build status
[19:44:47.462] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:47.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:12:0) after 1m0s
[19:45:47.488] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:12:0)
[19:45:47.488] Timer tick, asking Buildbucket for the build status
[19:45:47.865] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:47.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:13:0) after 1m0s
[19:46:47.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:13:0)
[19:46:47.883] Timer tick, asking Buildbucket for the build status
[19:46:47.943] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:47.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:14:0) after 1m0s
[19:47:47.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:14:0)
[19:47:47.974] Timer tick, asking Buildbucket for the build status
[19:47:48.051] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:48.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:15:0) after 1m0s
[19:48:48.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:15:0)
[19:48:48.098] Timer tick, asking Buildbucket for the build status
[19:48:48.221] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:48.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:16:0) after 1m0s
[19:49:48.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:16:0)
[19:49:48.240] Timer tick, asking Buildbucket for the build status
[19:49:48.352] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:48.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:17:0) after 1m0s
[19:50:48.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:17:0)
[19:50:48.388] Timer tick, asking Buildbucket for the build status
[19:50:48.514] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:48.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:18:0) after 1m0s
[19:51:48.600] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:18:0)
[19:51:48.600] Timer tick, asking Buildbucket for the build status
[19:51:48.714] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:48.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:19:0) after 1m0s
[19:52:48.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:19:0)
[19:52:48.714] Timer tick, asking Buildbucket for the build status
[19:52:48.826] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:48.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:20:0) after 1m0s
[19:53:48.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:20:0)
[19:53:48.850] Timer tick, asking Buildbucket for the build status
[19:53:48.936] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:48.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:21:0) after 1m0s
[19:54:48.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:21:0)
[19:54:48.994] Timer tick, asking Buildbucket for the build status
[19:54:49.083] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:49.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:22:0) after 1m0s
[19:55:49.137] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:22:0)
[19:55:49.137] Timer tick, asking Buildbucket for the build status
[19:55:50.014] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:50.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:23:0) after 1m0s
[19:56:50.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:23:0)
[19:56:50.034] Timer tick, asking Buildbucket for the build status
[19:56:50.724] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:50.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:24:0) after 1m0s
[19:57:50.784] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:24:0)
[19:57:50.784] Timer tick, asking Buildbucket for the build status
[19:57:57.298] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:57.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:25:0) after 1m0s
[19:58:57.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:25:0)
[19:58:57.379] Timer tick, asking Buildbucket for the build status
[19:58:57.487] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:57.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:26:0) after 1m0s
[19:59:57.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:26:0)
[19:59:57.564] Timer tick, asking Buildbucket for the build status
[19:59:57.681] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:57.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:27:0) after 1m0s
[20:00:57.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:27:0)
[20:00:57.676] Timer tick, asking Buildbucket for the build status
[20:00:57.820] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:57.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:28:0) after 1m0s
[20:01:57.840] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:28:0)
[20:01:57.840] Timer tick, asking Buildbucket for the build status
[20:01:57.968] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:57.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:29:0) after 1m0s
[20:02:58.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:29:0)
[20:02:58.004] Timer tick, asking Buildbucket for the build status
[20:02:58.449] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:58.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:30:0) after 1m0s
[20:03:58.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:30:0)
[20:03:58.471] Timer tick, asking Buildbucket for the build status
[20:03:59.013] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:59.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:31:0) after 1m0s
[20:04:59.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:31:0)
[20:04:59.066] Timer tick, asking Buildbucket for the build status
[20:04:59.218] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:59.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:32:0) after 1m0s
[20:05:59.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:32:0)
[20:05:59.372] Timer tick, asking Buildbucket for the build status
[20:05:59.579] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:59.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:33:0) after 1m0s
[20:06:59.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:33:0)
[20:06:59.647] Timer tick, asking Buildbucket for the build status
[20:06:59.780] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:59.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:34:0) after 1m0s
[20:07:59.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:34:0)
[20:07:59.865] Timer tick, asking Buildbucket for the build status
[20:08:00.195] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:00.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:35:0) after 1m0s
[20:09:00.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:35:0)
[20:09:00.362] Timer tick, asking Buildbucket for the build status
[20:09:00.555] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:00.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:36:0) after 1m0s
[20:10:00.690] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:36:0)
[20:10:00.691] Timer tick, asking Buildbucket for the build status
[20:10:00.850] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:00.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:37:0) after 1m0s
[20:11:00.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:37:0)
[20:11:00.919] Timer tick, asking Buildbucket for the build status
[20:11:01.105] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:01.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:38:0) after 1m0s
[20:12:01.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:38:0)
[20:12:01.369] Timer tick, asking Buildbucket for the build status
[20:12:01.932] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:01.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:39:0) after 1m0s
[20:13:01.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:39:0)
[20:13:01.972] Timer tick, asking Buildbucket for the build status
[20:13:02.181] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:02.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:40:0) after 1m0s
[20:14:02.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:40:0)
[20:14:02.243] Timer tick, asking Buildbucket for the build status
[20:14:02.514] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:02.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:41:0) after 1m0s
[20:15:02.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:41:0)
[20:15:02.578] Timer tick, asking Buildbucket for the build status
[20:15:02.694] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:02.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:42:0) after 1m0s
[20:16:02.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:42:0)
[20:16:02.738] Timer tick, asking Buildbucket for the build status
[20:16:03.414] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:03.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:43:0) after 1m0s
[20:17:03.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:43:0)
[20:17:03.425] Timer tick, asking Buildbucket for the build status
[20:17:03.509] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:03.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:44:0) after 1m0s
[20:18:03.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:44:0)
[20:18:03.506] Timer tick, asking Buildbucket for the build status
[20:18:03.898] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:03.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:45:0) after 1m0s
[20:19:03.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:45:0)
[20:19:03.913] Timer tick, asking Buildbucket for the build status
[20:19:03.995] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:03.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:46:0) after 1m0s
[20:20:04.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:46:0)
[20:20:04.055] Timer tick, asking Buildbucket for the build status
[20:20:04.207] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:04.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:47:0) after 1m0s
[20:21:04.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:47:0)
[20:21:04.227] Timer tick, asking Buildbucket for the build status
[20:21:04.530] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:04.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:48:0) after 1m0s
[20:22:04.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:48:0)
[20:22:04.557] Timer tick, asking Buildbucket for the build status
[20:22:04.657] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:04.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:49:0) after 1m0s
[20:23:04.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:49:0)
[20:23:04.724] Timer tick, asking Buildbucket for the build status
[20:23:04.790] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:04.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:50:0) after 1m0s
[20:24:05.421] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:50:0)
[20:24:05.421] Timer tick, asking Buildbucket for the build status
[20:24:05.509] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:05.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:51:0) after 1m0s
[20:25:05.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:51:0)
[20:25:05.577] Timer tick, asking Buildbucket for the build status
[20:25:06.016] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:06.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:52:0) after 1m0s
[20:26:06.114] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:52:0)
[20:26:06.114] Timer tick, asking Buildbucket for the build status
[20:26:06.310] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:06.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:53:0) after 1m0s
[20:27:06.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:53:0)
[20:27:06.397] Timer tick, asking Buildbucket for the build status
[20:27:06.528] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:06.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:54:0) after 1m0s
[20:28:06.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:54:0)
[20:28:06.564] Timer tick, asking Buildbucket for the build status
[20:28:06.651] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:06.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:55:0) after 1m0s
[20:29:06.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:55:0)
[20:29:06.671] Timer tick, asking Buildbucket for the build status
[20:29:07.080] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:07.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:56:0) after 1m0s
[20:30:07.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:56:0)
[20:30:07.169] Timer tick, asking Buildbucket for the build status
[20:30:07.988] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:07.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:57:0) after 1m0s
[20:31:08.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:57:0)
[20:31:08.076] Timer tick, asking Buildbucket for the build status
[20:31:08.242] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:08.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:58:0) after 1m0s
[20:32:08.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:58:0)
[20:32:08.291] Timer tick, asking Buildbucket for the build status
[20:32:08.429] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:08.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:59:0) after 1m0s
[20:33:08.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:59:0)
[20:33:08.498] Timer tick, asking Buildbucket for the build status
[20:33:08.610] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:08.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:60:0) after 1m0s
[20:34:08.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:60:0)
[20:34:08.617] Timer tick, asking Buildbucket for the build status
[20:34:08.795] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:08.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:61:0) after 1m0s
[20:35:08.817] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:61:0)
[20:35:08.817] Timer tick, asking Buildbucket for the build status
[20:35:08.954] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:08.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:62:0) after 1m0s
[20:36:09.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:62:0)
[20:36:09.015] Timer tick, asking Buildbucket for the build status
[20:36:09.135] Build 8858282888159370208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:09.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023712950265715008:63:0) after 1m0s
[20:37:08.802] Received PubSub notification, asking Buildbucket for the build status
[20:37:08.872] Build 8858282888159370208: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:37:08.872] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610483826998504",
"created_by": "project:chromium",
"created_ts": "1610480144309095",
"id": "8858282888159370208",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a0f906860f838339837e3968f2d1a1f2b3d27435\"}}",
"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\": \"a0f906860f838339837e3968f2d1a1f2b3d27435\", \"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-8-wwgq\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16781, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"7f0e7d0d262aa0ee71c862846c72f737cbbfca04\", \"got_dawn_revision\": \"7fae6cbf1d370bce5af457ad07f97f290a99cb0d\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"a0f906860f838339837e3968f2d1a1f2b3d27435\", \"got_revision_cp\": \"refs/heads/master@{#842641}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"3549479dc4ccd92ef3b9e179080dcec1a430b01d\", \"got_v8_revision\": \"81b3d28ad43829f5d3c65e0725a266bb1a8e7340\", \"got_v8_revision_cp\": \"refs/heads/8.9.254@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"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\": \"a0f906860f838339837e3968f2d1a1f2b3d27435\", \"swarm_hashes_refs/heads/master(at){#842641}_without_patch\": {\"absl_hardening_tests\": \"e7dc6d09e6de6fc96235fa6efdf30c9e5f5587be\", \"accessibility_unittests\": \"86daf5c262ffc323f27af7b1cf0904732f286ba6\", \"angle_unittests\": \"2c5d621950286c622b5ef71061dc88d17903c51d\", \"app_shell_unittests\": \"032a0054590941c96a56c95a96fffcee98476706\", \"aura_unittests\": \"bc34be58809c3c97e909815f18d2a84dbd5442cb\", \"base_unittests\": \"a138cae56d1fd13bfe8f891711eada9860119b9b\", \"base_util_unittests\": \"ef9e5be594ce9d34029905a7efca203c741d9687\", \"blink_common_unittests\": \"9b207fe1214ff18b305d750e36bf3d3396c2a915\", \"blink_fuzzer_unittests\": \"73963359fc0576126f30bb390fc60a16d197698d\", \"blink_heap_unittests\": \"a501d3146ba7f10fc9e625b263717b799b7d4846\", \"blink_platform_unittests\": \"b48447cb0180859e9816e2cf046635fed4955b01\", \"blink_unittests\": \"fe86af259357fdd0ce4504560dc0c1428d6b2dab\", \"boringssl_crypto_tests\": \"6d9c52f963ecfac789e932a7b6c8e4444e163e0e\", \"boringssl_ssl_tests\": \"bce9fa9230492b9b9d2d56d56282e4331a28c749\", \"browser_switcher_bho_unittests\": \"e477cd79d5186ad86dec75ea6e1404dff577efdc\", \"browser_tests\": \"b64e53d4b20d26407b41322d0bb3a44477510d2f\", \"capture_unittests\": \"a60152c06327ccd254207b16134ea6fca9856e1e\", \"cast_unittests\": \"d22bd1f4c60d62a478ae040ae0cf6d8d01e303e0\", \"cc_unittests\": \"7879624e490d5f1dfdeee4f1b638761d296e8545\", \"chrome_app_unittests\": \"f85a957c5b61da23293d9be2f5c7fe88739fc377\", \"chrome_cleaner_unittests\": \"3d579071fcbbb696073bd02b10aa1b6329e238e8\", \"chrome_elf_unittests\": \"315f6d195cd8e18c281ff6cae8a71f68b66b295f\", \"chromedriver_unittests\": \"15b1dc6ade79056ba1a85d8bcc316325ee3989cc\", \"components_browsertests\": \"494548bf5fbf22c589f3385b14503fabb62ccc84\", \"components_unittests\": \"57545dcff1ca62fc837ddc760082441599a2e78c\", \"compositor_unittests\": \"a2d556aca88ab0befc491ae9e7b07d2bfceb56e2\", \"content_browsertests\": \"555957cab726f1af1d929d82db332487b32b2c32\", \"content_unittests\": \"77097bfb1f7cb8133798b164e44513b2dd5a0cd4\", \"courgette_unittests\": \"ab7aafdef4caea8421c906adc71b35d535c53942\", \"crashpad_tests\": \"4c7578f35aedadfad29fcb069bfb60c155ed469d\", \"cronet_tests\": \"a4cfc453cd6057960d0ebf339b0e469524061332\", \"cronet_unittests\": \"c51f165c3281f26c1755f61396424a3d8564d745\", \"crypto_unittests\": \"67c64e2898a58fe3643a778cc87ed9eacd25fd0f\", \"delayloads_unittests\": \"8988971d18cc7c9f6960e2ff9c7ba0f43ecf031f\", \"device_unittests\": \"8d769aa254534c9c153be1f992b209398a0999e0\", \"display_unittests\": \"bed05825e41e2fcce1b735695856e53637a7394b\", \"elevation_service_unittests\": \"fa1d85614340e1bca03976b1e33fa1ff5e73a6a5\", \"events_unittests\": \"5907b789f2ce6fee6ef06f51ac7f0cfb46e658f1\", \"extensions_browsertests\": \"24f19ec9164317b35c7a6175a69731fd8f3bb94f\", \"extensions_unittests\": \"9a7d28ab118112adbfd62b505cdebfa70a3e4926\", \"filesystem_service_unittests\": \"364b60f6d4d0556f217a2fbbd1291d5837dc7625\", \"gcm_unit_tests\": \"85ed50d4e02c399fcfa061f64f0b0d8f9614a594\", \"gcp_unittests\": \"79306fded598791cb0ad1d9f2ef2f3ff67611a27\", \"gfx_unittests\": \"687b469df2ae7ae8720436dea9678c23330d891d\", \"gin_unittests\": \"3814fc46fcbc1646aacf416d9f84332b3994facb\", \"google_apis_unittests\": \"7d31fd5705c57b11595655639d393188dc003837\", \"gpu_unittests\": \"fa3ddd8ad138f55e2ca511703259cfa11cb52d84\", \"gwp_asan_unittests\": \"a74b8143ee7ad0c18296bc84415174cddf759f3f\", \"headless_browsertests\": \"1beefec1ae26d1614b0f8e98854d757073d844f0\", \"headless_unittests\": \"c77a43fdcdb3de4666396d971546bf5e5d8d3834\", \"install_static_unittests\": \"03799079bd4acc3478f6534160701c21d02322fa\", \"installer_util_unittests\": \"ca5666895735b0093ef9acc34c82f4b9a99121dc\", \"interactive_ui_tests\": \"76f36054801c0f80469b9c8af794545c2ece67a4\", \"ipc_tests\": \"26ee272369d441de696a48fbd85db60a459b4222\", \"jingle_unittests\": \"5a6768ad18e385c5178211b511265863d4e85866\", \"latency_unittests\": \"8369b1548eb6f029a735907c29ceed4bb29cff8b\", \"libjingle_xmpp_unittests\": \"86ad4747fe9a7a37aa41b611789aaf4d41dac8b0\", \"liburlpattern_unittests\": \"1ec599810f8fa8ec4a46eeb0170e58d1d3965cf7\", \"media_blink_unittests\": \"09187ad9a20f448aa2c63081cb76529f3ac47d38\", \"media_unittests\": \"3b2f219bee7b2bd55ed70bba93ac7e48e397edee\", \"message_center_unittests\": \"8d90663ab1064a39c2baf99a37612bc9ac333d19\", \"midi_unittests\": \"afd60dd9411026064a81d8923d7ef1e3f935945e\", \"mojo_core_unittests\": \"61338fa461d2faf25546de3812cd0af7bde2b9ce\", \"mojo_unittests\": \"ad9028744ff82f241693d4d60338eca63b2ddf0a\", \"nacl_loader_unittests\": \"f6af629aba52c62b7425cdc74ecf9112423110d8\", \"native_theme_unittests\": \"49776fc41f4aefbb5faf2cf95edff9ad15f76e19\", \"net_unittests\": \"f2ea688680a710bc08c5d0862b9d5d1fc389b6eb\", \"notification_helper_unittests\": \"823b38f7ad1b9e43cf3a20c95d97203deee81537\", \"pdf_unittests\": \"9637cefd4f7ee9331a17d7dd5bc3f0a209ccf480\", \"perfetto_unittests\": \"218371774e51c6ca2eadb6297611cf810fff2d8c\", \"ppapi_unittests\": \"0a5a32e58323d3e92b025525bc9d56cb15e617dc\", \"printing_unittests\": \"32d6537d18f0de8dd3a58058ca0521b3278b292b\", \"remoting_unittests\": \"1b6f23ac14f95952305299cbb27e7f286cfa1ce2\", \"sbox_integration_tests\": \"8ebba915daf59736f94c7af35ea72c8da5844426\", \"sbox_unittests\": \"1aa9fee6ca0f19e2324b25d61ae39942897f2b72\", \"sbox_validation_tests\": \"b8e48ad62e49731bda95a1930ed2b1aa9b1200d2\", \"service_manager_unittests\": \"c4c42381e2f51bd3ce51232761637b5bdd96dd47\", \"services_unittests\": \"037cb6a40a4707524e94242d8715b3060553032a\", \"setup_unittests\": \"72470e97af72b4433d07c5c8a134dd7ddbe385b6\", \"shell_dialogs_unittests\": \"544e978c68a9d75e61a23b3b0b48944504d0cd13\", \"skia_unittests\": \"3ba6a50eaecebdb2e5da34c9e0dc992fdf9646cb\", \"snapshot_unittests\": \"946965399adab46715e0f1c735b2e5d8aaa5bbaa\", \"sql_unittests\": \"091dea02c65d69667c51711bfaad024a83bb9e5c\", \"storage_unittests\": \"2b9777ea20a680a474110fb6350df37f174a2e6e\", \"sync_integration_tests\": \"9c39470bb3e3a9aaf0858b51a0964753da82c4da\", \"traffic_annotation_auditor_unittests\": \"b06964e7346dd6fc3a7e34ea045676399c10e300\", \"ui_base_unittests\": \"fdae74ad952008b0d070ea1889bc13f72f09c4e3\", \"ui_touch_selection_unittests\": \"10fb5f8ca9cf9d3c476c85b6fe73bd24ca42778a\", \"unit_tests\": \"ac1be04db4544ffe37045e42302c19b8f22e0e2c\", \"updater_tests\": \"452ca2451893cdcc67d86dc1e2e9dbafdda7efc9\", \"url_unittests\": \"ad8645fa3db827d590c21b9b45be44fab6509739\", \"views_unittests\": \"31fd567c64f18e7d3fc068ee5741b419bb19d2e6\", \"viz_unittests\": \"e0cfa59d5b89852d39cf97112fa9db6779513451\", \"vr_common_unittests\": \"4b1b34c487e95d79bb25a6ef624a8ae582a69c9a\", \"vr_pixeltests\": \"d16ff12ad5518e2a14b5ac0891d7d754fcf90d12\", \"weblayer_browsertests\": \"b0034349364c5fb33c77a6383ffb68315c56f6d7\", \"weblayer_unittests\": \"dc25b5bc929c0a1a05290c595ebe7d520eb71339\", \"wm_unittests\": \"624ad243bcd54dafd39484055c2f9701a4640b2c\", \"wtf_unittests\": \"2628a5ce9fb3045f7681cf5ca6d7f3d7c9068a2e\", \"zlib_unittests\": \"e25e8f8e3619dcf46e4b806f7f68b284ce97ac4a\", \"zucchini_unittests\": \"ce3b3e2135d17c8c47cad9eb43182fa00e64ad00\"}}, \"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-8-wwgq\"], \"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\": [\"5661-c6f6250\"], \"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": "1610480152416918",
"status": "COMPLETED",
"status_changed_ts": "1610483827324840",
"tags": [
"build_address:luci.chromium.ci/win-asan/16781",
"builder:win-asan",
"buildset:commit/git/a0f906860f838339837e3968f2d1a1f2b3d27435",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a0f906860f838339837e3968f2d1a1f2b3d27435",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023712950265715008",
"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/8858282888159370208/+/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:5110ea5c48066410",
"user_agent:luci-scheduler"
],
"updated_ts": "1610483827335625",
"url": "https://ci.chromium.org/b/8858282888159370208",
"utcnow_ts": "1610483828861106"
}
}
[20:37:08.872] Invocation finished in 1h1m25.955205879s with status SUCCEEDED