[04:53:54.664] New invocation is queued and will start shortly
[04:53:56.628] Starting the invocation (attempt 1)
[04:53:56.666] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:53:56.666] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:53:56.666] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019691565117328400",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a07d2a1f9755c11285c54283844798f480bcd3b4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019691565117328400",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/a07d2a1f9755c11285c54283844798f480bcd3b4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a07d2a1f9755c11285c54283844798f480bcd3b4",
"gitiles_ref:refs/heads/master"
]
}
[04:53:57.189] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614315236731201",
"id": "8854261502287321184",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a07d2a1f9755c11285c54283844798f480bcd3b4\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614315237082297",
"tags": [
"build_address:luci.chromium.ci/win-asan/17539",
"builder:win-asan",
"buildset:commit/git/a07d2a1f9755c11285c54283844798f480bcd3b4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a07d2a1f9755c11285c54283844798f480bcd3b4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019691565117328400",
"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/8854261502287321184/+/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": "1614315237082406",
"url": "https://ci.chromium.org/b/8854261502287321184",
"utcnow_ts": "1614315237183731"
}
}
[04:53:57.189] Task URL: https://ci.chromium.org/b/8854261502287321184
[04:53:57.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:2:0) after 1m0s
[04:53:59.258] Received PubSub notification, asking Buildbucket for the build status
[04:53:59.330] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:57.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:2:0)
[04:54:57.280] Timer tick, asking Buildbucket for the build status
[04:54:57.355] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:57.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:4:0) after 1m0s
[04:55:57.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:4:0)
[04:55:57.469] Timer tick, asking Buildbucket for the build status
[04:55:57.537] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:57.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:5:0) after 1m0s
[04:56:57.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:5:0)
[04:56:57.631] Timer tick, asking Buildbucket for the build status
[04:56:57.702] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:57.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:6:0) after 1m0s
[04:57:57.741] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:6:0)
[04:57:57.741] Timer tick, asking Buildbucket for the build status
[04:57:57.851] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:57.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:7:0) after 1m0s
[04:58:57.934] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:7:0)
[04:58:57.934] Timer tick, asking Buildbucket for the build status
[04:58:58.017] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:58.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:8:0) after 1m0s
[04:59:58.099] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:8:0)
[04:59:58.099] Timer tick, asking Buildbucket for the build status
[04:59:58.459] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:58.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:9:0) after 1m0s
[05:00:58.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:9:0)
[05:00:58.505] Timer tick, asking Buildbucket for the build status
[05:00:58.598] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:58.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:10:0) after 1m0s
[05:01:58.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:10:0)
[05:01:58.621] Timer tick, asking Buildbucket for the build status
[05:01:58.689] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:58.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:11:0) after 1m0s
[05:02:58.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:11:0)
[05:02:58.751] Timer tick, asking Buildbucket for the build status
[05:02:58.998] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:58.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:12:0) after 1m0s
[05:03:59.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:12:0)
[05:03:59.094] Timer tick, asking Buildbucket for the build status
[05:03:59.351] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:59.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:13:0) after 1m0s
[05:04:59.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:13:0)
[05:04:59.425] Timer tick, asking Buildbucket for the build status
[05:04:59.510] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:59.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:14:0) after 1m0s
[05:05:59.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:14:0)
[05:05:59.528] Timer tick, asking Buildbucket for the build status
[05:05:59.854] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:59.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:15:0) after 1m0s
[05:06:59.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:15:0)
[05:06:59.899] Timer tick, asking Buildbucket for the build status
[05:06:59.976] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:59.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:16:0) after 1m0s
[05:08:00.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:16:0)
[05:08:00.026] Timer tick, asking Buildbucket for the build status
[05:08:00.241] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:00.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:17:0) after 1m0s
[05:09:00.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:17:0)
[05:09:00.283] Timer tick, asking Buildbucket for the build status
[05:09:00.451] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:00.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:18:0) after 1m0s
[05:10:00.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:18:0)
[05:10:00.460] Timer tick, asking Buildbucket for the build status
[05:10:00.563] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:00.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:19:0) after 1m0s
[05:11:00.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:19:0)
[05:11:00.585] Timer tick, asking Buildbucket for the build status
[05:11:00.704] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:00.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:20:0) after 1m0s
[05:12:01.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:20:0)
[05:12:01.196] Timer tick, asking Buildbucket for the build status
[05:12:01.603] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:01.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:21:0) after 1m0s
[05:13:02.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:21:0)
[05:13:02.054] Timer tick, asking Buildbucket for the build status
[05:13:02.120] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:02.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:22:0) after 1m0s
[05:14:02.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:22:0)
[05:14:02.159] Timer tick, asking Buildbucket for the build status
[05:14:02.272] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:02.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:23:0) after 1m0s
[05:15:02.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:23:0)
[05:15:02.271] Timer tick, asking Buildbucket for the build status
[05:15:02.451] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:02.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:24:0) after 1m0s
[05:16:02.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:24:0)
[05:16:02.502] Timer tick, asking Buildbucket for the build status
[05:16:02.580] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:02.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:25:0) after 1m0s
[05:17:02.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:25:0)
[05:17:02.657] Timer tick, asking Buildbucket for the build status
[05:17:02.911] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:02.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:26:0) after 1m0s
[05:18:02.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:26:0)
[05:18:02.933] Timer tick, asking Buildbucket for the build status
[05:18:03.395] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:03.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:27:0) after 1m0s
[05:19:03.417] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:27:0)
[05:19:03.417] Timer tick, asking Buildbucket for the build status
[05:19:03.784] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:03.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:28:0) after 1m0s
[05:20:03.803] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:28:0)
[05:20:03.803] Timer tick, asking Buildbucket for the build status
[05:20:03.894] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:03.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:29:0) after 1m0s
[05:21:03.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:29:0)
[05:21:03.936] Timer tick, asking Buildbucket for the build status
[05:21:04.026] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:04.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:30:0) after 1m0s
[05:22:04.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:30:0)
[05:22:04.065] Timer tick, asking Buildbucket for the build status
[05:22:04.147] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:04.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:31:0) after 1m0s
[05:23:04.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:31:0)
[05:23:04.192] Timer tick, asking Buildbucket for the build status
[05:23:04.297] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:04.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:32:0) after 1m0s
[05:24:04.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:32:0)
[05:24:04.355] Timer tick, asking Buildbucket for the build status
[05:24:04.663] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:04.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:33:0) after 1m0s
[05:25:04.679] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:33:0)
[05:25:04.679] Timer tick, asking Buildbucket for the build status
[05:25:04.771] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:04.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:34:0) after 1m0s
[05:26:04.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:34:0)
[05:26:04.801] Timer tick, asking Buildbucket for the build status
[05:26:05.048] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:05.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:35:0) after 1m0s
[05:27:05.096] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:35:0)
[05:27:05.096] Timer tick, asking Buildbucket for the build status
[05:27:05.182] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:05.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:36:0) after 1m0s
[05:28:05.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:36:0)
[05:28:05.168] Timer tick, asking Buildbucket for the build status
[05:28:05.498] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:05.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:37:0) after 1m0s
[05:29:05.514] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:37:0)
[05:29:05.514] Timer tick, asking Buildbucket for the build status
[05:29:05.605] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:05.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:38:0) after 1m0s
[05:30:05.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:38:0)
[05:30:05.665] Timer tick, asking Buildbucket for the build status
[05:30:05.745] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:05.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:39:0) after 1m0s
[05:31:05.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:39:0)
[05:31:05.719] Timer tick, asking Buildbucket for the build status
[05:31:05.860] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:05.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:40:0) after 1m0s
[05:32:05.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:40:0)
[05:32:05.910] Timer tick, asking Buildbucket for the build status
[05:32:06.017] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:06.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:41:0) after 1m0s
[05:33:06.020] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:41:0)
[05:33:06.020] Timer tick, asking Buildbucket for the build status
[05:33:06.313] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:06.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:42:0) after 1m0s
[05:34:06.339] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:42:0)
[05:34:06.339] Timer tick, asking Buildbucket for the build status
[05:34:06.454] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:06.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:43:0) after 1m0s
[05:35:06.473] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:43:0)
[05:35:06.473] Timer tick, asking Buildbucket for the build status
[05:35:06.567] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:06.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:44:0) after 1m0s
[05:36:06.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:44:0)
[05:36:06.611] Timer tick, asking Buildbucket for the build status
[05:36:06.709] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:06.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:45:0) after 1m0s
[05:37:06.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:45:0)
[05:37:06.770] Timer tick, asking Buildbucket for the build status
[05:37:06.885] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:06.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:46:0) after 1m0s
[05:38:06.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:46:0)
[05:38:06.936] Timer tick, asking Buildbucket for the build status
[05:38:07.055] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:07.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:47:0) after 1m0s
[05:39:07.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:47:0)
[05:39:07.092] Timer tick, asking Buildbucket for the build status
[05:39:07.180] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:07.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:48:0) after 1m0s
[05:40:07.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:48:0)
[05:40:07.299] Timer tick, asking Buildbucket for the build status
[05:40:07.386] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:07.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:49:0) after 1m0s
[05:41:07.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:49:0)
[05:41:07.469] Timer tick, asking Buildbucket for the build status
[05:41:07.650] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:07.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:50:0) after 1m0s
[05:42:07.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:50:0)
[05:42:07.682] Timer tick, asking Buildbucket for the build status
[05:42:07.757] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:07.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:51:0) after 1m0s
[05:43:07.871] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:51:0)
[05:43:07.871] Timer tick, asking Buildbucket for the build status
[05:43:08.028] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:08.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:52:0) after 1m0s
[05:44:08.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:52:0)
[05:44:08.078] Timer tick, asking Buildbucket for the build status
[05:44:08.248] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:08.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:53:0) after 1m0s
[05:45:08.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:53:0)
[05:45:08.333] Timer tick, asking Buildbucket for the build status
[05:45:08.565] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:08.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:54:0) after 1m0s
[05:46:08.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:54:0)
[05:46:08.536] Timer tick, asking Buildbucket for the build status
[05:46:08.660] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:08.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:55:0) after 1m0s
[05:47:08.740] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:55:0)
[05:47:08.740] Timer tick, asking Buildbucket for the build status
[05:47:08.842] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:08.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:56:0) after 1m0s
[05:48:08.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:56:0)
[05:48:08.879] Timer tick, asking Buildbucket for the build status
[05:48:09.182] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:09.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:57:0) after 1m0s
[05:49:09.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:57:0)
[05:49:09.280] Timer tick, asking Buildbucket for the build status
[05:49:09.593] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:09.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:58:0) after 1m0s
[05:50:09.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:58:0)
[05:50:09.601] Timer tick, asking Buildbucket for the build status
[05:50:09.712] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:09.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:59:0) after 1m0s
[05:51:09.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:59:0)
[05:51:09.749] Timer tick, asking Buildbucket for the build status
[05:51:09.835] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:09.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:60:0) after 1m0s
[05:52:09.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:60:0)
[05:52:09.839] Timer tick, asking Buildbucket for the build status
[05:52:09.961] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:09.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:61:0) after 1m0s
[05:53:10.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:61:0)
[05:53:10.048] Timer tick, asking Buildbucket for the build status
[05:53:10.142] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:10.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:62:0) after 1m0s
[05:54:10.161] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:62:0)
[05:54:10.161] Timer tick, asking Buildbucket for the build status
[05:54:10.276] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:10.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:63:0) after 1m0s
[05:55:10.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:63:0)
[05:55:10.317] Timer tick, asking Buildbucket for the build status
[05:55:10.532] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:10.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:64:0) after 1m0s
[05:56:10.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:64:0)
[05:56:10.532] Timer tick, asking Buildbucket for the build status
[05:56:10.621] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:10.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:65:0) after 1m0s
[05:57:10.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:65:0)
[05:57:10.665] Timer tick, asking Buildbucket for the build status
[05:57:10.765] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:10.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:66:0) after 1m0s
[05:58:10.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:66:0)
[05:58:10.857] Timer tick, asking Buildbucket for the build status
[05:58:10.955] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:10.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:67:0) after 1m0s
[05:59:11.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:67:0)
[05:59:11.027] Timer tick, asking Buildbucket for the build status
[05:59:11.553] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:11.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:68:0) after 1m0s
[06:00:11.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:68:0)
[06:00:11.626] Timer tick, asking Buildbucket for the build status
[06:00:11.723] Build 8854261502287321184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:11.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019691565117328400:69:0) after 1m0s
[06:00:37.698] Received PubSub notification, asking Buildbucket for the build status
[06:00:37.823] Build 8854261502287321184: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:00:37.823] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614319236971535",
"created_by": "project:chromium",
"created_ts": "1614315236731201",
"id": "8854261502287321184",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a07d2a1f9755c11285c54283844798f480bcd3b4\"}}",
"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\": \"a07d2a1f9755c11285c54283844798f480bcd3b4\", \"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-3-xpf4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17539, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"77637f2dd222530c721018185a90e24a51e7a745\", \"got_dawn_revision\": \"545a6970cbe9e986dd25e01f7ecf2f9a1b17c756\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"a07d2a1f9755c11285c54283844798f480bcd3b4\", \"got_revision_cp\": \"refs/heads/master@{#858028}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"349abccec8f7ebd1259de11965d58790a76b0ba4\", \"got_v8_revision\": \"bd0108b4c88e0d6f2350cb79b5f363fbd02f3eb7\", \"got_v8_revision_cp\": \"refs/heads/9.0.257@{#1}\", \"got_webrtc_revision\": \"bb52bdf09516ca548c4aff50526eda561f239bc0\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33341}\", \"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\": \"a07d2a1f9755c11285c54283844798f480bcd3b4\", \"swarm_hashes_refs/heads/master(at){#858028}_without_patch\": {\"absl_hardening_tests\": \"81450d4a59cf9e11196375f7c6c8c515700de2b8\", \"accessibility_unittests\": \"595b6468bca724c10f480803bb0f8183f6dc4900\", \"angle_unittests\": \"c95fc065ac8df3967b1c14cf8c25f6b04e407a3f\", \"app_shell_unittests\": \"0c0f0c253032ac157dcf151b5d40cc99766ae369\", \"aura_unittests\": \"96cf51256064f60a5b2a2ceab3c56b6063470ddc\", \"base_unittests\": \"906740c15f10ed80bf925139d3e77ce4f87a7dd4\", \"base_util_unittests\": \"183ea2f869a5bacacb92e1d4efa996d21f62c1b5\", \"blink_common_unittests\": \"755f82dd6e473d6ff7aba1716a2492a30fd7f185\", \"blink_fuzzer_unittests\": \"e63940d882a673a2db9805c354abe7b70da7cc96\", \"blink_heap_unittests\": \"b1a30f7a60a6225c5c47fe1c022cceaa6d1dd21d\", \"blink_platform_unittests\": \"8c8674800a82e463d4ee7343901470978f7cf3b4\", \"blink_unittests\": \"7d2197bc932e52c341e370a07c2559c0f84928b7\", \"boringssl_crypto_tests\": \"674ab1de077ecbf293466d4cd9be4a9ce028af5c\", \"boringssl_ssl_tests\": \"9c2e930ff8b74248dbb8f2c686bde225d63fe1b2\", \"browser_switcher_bho_unittests\": \"e6bf1ef4ede47b01747b72e946ac79e27de81f8c\", \"browser_tests\": \"7078fe0bbff779a2066cc0d4960661ac5af9d0a8\", \"capture_unittests\": \"13300c2ec7c56a5319243315db1d792bdf8792ac\", \"cast_unittests\": \"a69575560085a7c9282c34fc839f4e8ffaa04999\", \"cc_unittests\": \"65b4d9ac9cc711fc2721c84ba24003ad5bbf69a0\", \"chrome_app_unittests\": \"fd1aaf17f027eeab1089a145f341fb3242650c28\", \"chrome_cleaner_unittests\": \"bfeefc8073f85be55dc7769dbfa12712b860c30c\", \"chrome_elf_unittests\": \"2936dc1a8a1402b87f4f0d732128020395408b0c\", \"chromedriver_unittests\": \"efa252503637c87636b439bcf49b808df1e4e328\", \"color_unittests\": \"869d07a493e069e298a79826d2ee0de3576236f4\", \"components_browsertests\": \"b9bea7a40aa686ca28451474887fe8fab9feca82\", \"components_unittests\": \"9ac7710c2ded90c3683425425c70c6589b023d36\", \"compositor_unittests\": \"065c577c95cf7d4fa59f420994339d033b6b7a68\", \"content_browsertests\": \"21bf4f9c1f40d380281b20e1f82c837002d22002\", \"content_unittests\": \"8b000bcbad9b3ba6da86093cf9c25b24d018a8e4\", \"courgette_unittests\": \"c1b9625b0b838aeee98d56a7a29d6730a773af97\", \"crashpad_tests\": \"a2c135a197ccc896cab4bffb7654587287aa5ef8\", \"cronet_tests\": \"ff59d422d179c1d2650d012b92bfcd278768dfdb\", \"cronet_unittests\": \"baf01e0117e11e09a50d4ea6a7632c68f190ddb6\", \"crypto_unittests\": \"810f93774b6de6e1c72b90ecf67e53fb0ab2379b\", \"delayloads_unittests\": \"a5dc51f59d6f62854a029ce77b630ec5ae6348e8\", \"device_unittests\": \"48077703c5f7a540c6c4db115e9a98fdbab375bb\", \"display_unittests\": \"c6095051ad569c82283a915b54925eeee067f823\", \"elevation_service_unittests\": \"1216a9861683abea94902a3bb8cd227bf9636ebb\", \"events_unittests\": \"675fefef2d54f5e4f5cc04dcdb1dea94c6612b0a\", \"extensions_browsertests\": \"92a0393c9ad2a49f167f168ef9dc498ae08ce894\", \"extensions_unittests\": \"f595746d14c1f739a302aed77dd31b1419e9ec73\", \"filesystem_service_unittests\": \"ef919b359d66c916899656ab74a633054401a4ed\", \"gcm_unit_tests\": \"b80f6619e2a6ce09264fa4725a836cc59187d97f\", \"gcp_unittests\": \"a82607ba584c740ab828ebc8bca2530245b38623\", \"gfx_unittests\": \"bba6db38859161eaee4a7662274a25d86660c4c5\", \"gin_unittests\": \"77a9064652b5990f17c87d035091a2eba8cc212d\", \"google_apis_unittests\": \"727532e273c27534a8f880a6280083126c9ccdf6\", \"gpu_unittests\": \"b3319f703a91fd352716466a7b2c52a1b1ea9bfa\", \"gwp_asan_unittests\": \"fba4bda01bdc4c369ac1aaae1a3b1814c13cf30e\", \"headless_browsertests\": \"32f704f557963316f5b8dfa14629fbfd28741899\", \"headless_unittests\": \"d909416e269d3140ef370f3a915902309c896f0e\", \"install_static_unittests\": \"f0102e26b06ea141fea09c403002a08276d314e4\", \"installer_util_unittests\": \"b23de9c32d6d5c8d244520426acf6554a3c63617\", \"interactive_ui_tests\": \"57b8a39e0156faa1a4bdcb0ec843d92d0fe5a105\", \"ipc_tests\": \"eb94de0b3b55b0584b8cbf623a1cfc1abf42beee\", \"jingle_unittests\": \"91293ff5dadc357a43d3f98b09342a5c1868a648\", \"latency_unittests\": \"7927f9427ac9fd97c9c157b611a51609b2ce59ca\", \"libjingle_xmpp_unittests\": \"fab0019103a07408bb35fb9b0bbb9fe7777a5293\", \"liburlpattern_unittests\": \"cd4b5716a0d5f5d2c2b19bf957292b58915f9a62\", \"media_blink_unittests\": \"995a6a0868d7e6734a19652ac2dc9d7b5e3c6b48\", \"media_unittests\": \"ff7e81686545a02b20d0ed5d7405a9a443045728\", \"message_center_unittests\": \"87209474a0705cdc560b9a4dad177e9076367529\", \"midi_unittests\": \"d3980e8000f8ffffdd153b72ee7ce77c3d5021dc\", \"mojo_core_unittests\": \"6ed81928f14e680ed1c6c422dafd1b632f0d3423\", \"mojo_unittests\": \"c47e15529918fa448a67a9933bf90fdfa98375fc\", \"nacl_loader_unittests\": \"5ec6dcff864e68057f2bcaa899ae92e5c19b3de9\", \"native_theme_unittests\": \"02ee7c579656cc44011c9c46e7cdfd1680f0da37\", \"net_unittests\": \"e9809cb9f47a8fcd519c277a6dbb5e1560c73110\", \"notification_helper_unittests\": \"7704c45b518947953ec720dbfba1e1a7c810e155\", \"pdf_unittests\": \"7af4b86ea9055e93fb800a98d59300d5d203eaae\", \"perfetto_unittests\": \"212f88040123494d46da09f0d65a3d4929569640\", \"ppapi_unittests\": \"1675d4294d85759ea9649b2aea5a874b50168199\", \"printing_unittests\": \"680ca7ef29adf4f6125a2406e01cb856a57a9278\", \"remoting_unittests\": \"68c2e287603e3c6f47f779a4b463563167d4dd9f\", \"sbox_integration_tests\": \"761e3f270e3bf207779a054b7e7938690a63bdc2\", \"sbox_unittests\": \"9072afd83d4383c1dd069c4ce494010bf63a438f\", \"sbox_validation_tests\": \"764495ee09df7ee67b33bec8f0180e5bb2aed46c\", \"service_manager_unittests\": \"d0d1a5d2e6f2697dbd1a15f20a2b8c870453c61b\", \"services_unittests\": \"7775378a496cc13ae8b4343742392bae3080c57c\", \"setup_unittests\": \"d54fe26f72605cc8b50a8ee9a7c7315af92cd2cf\", \"shell_dialogs_unittests\": \"ef4e08f6baba4b6f1b41854d13f614dd1345ef64\", \"skia_unittests\": \"aea5e05df1fd7493f56472f91c2f314ea0fe874d\", \"snapshot_unittests\": \"a1a8d0248a65014bf664440317262c8b4804116a\", \"sql_unittests\": \"157d0c83a4e242868da944f17767efe96ba9c262\", \"storage_unittests\": \"6028f64bd7a90d95b4affb5cbd7fefc5783f763b\", \"sync_integration_tests\": \"9c7a4ff6823086dc605c9562828f834ed90f88cc\", \"traffic_annotation_auditor_unittests\": \"5e7d772d18987c5a49b9aa385431156471be1585\", \"ui_base_unittests\": \"c1873782f898d27281142b51e77f57bf66609a73\", \"ui_touch_selection_unittests\": \"df0ce1f60a39747cf6476ab1061a7b197147b012\", \"unit_tests\": \"889bf51a904e4c3dfabb726ab872a619c66888ce\", \"updater_tests\": \"94878aca99426c0e68f72c8acf642123be37ef92\", \"url_unittests\": \"e19d33b327f1a7a657f7e5d58f2102d0a30a701f\", \"views_unittests\": \"482f51182a224877cf461d7919ccf489dfdc3acb\", \"viz_unittests\": \"56172302c61ea849f33414f07a64c01f3dfdb3a2\", \"vr_common_unittests\": \"e679d7b85f3a3c3ed1b2e64c1712c0527f29631e\", \"vr_pixeltests\": \"169af9de1949c3c83c309439ff5852ba3f3e5f70\", \"weblayer_browsertests\": \"58a0dd829be064cae317fd85503d846d5b766329\", \"weblayer_unittests\": \"9cbfda5e0fe4f4dfdef25cc57ee176581cc6a92e\", \"wm_unittests\": \"2d08768ba093cd65c3aaf0b85cd29591d4c78b07\", \"wtf_unittests\": \"c5d79936e2438177fe6efeaaea7fd73631d40347\", \"zlib_unittests\": \"1e2790495518a78946c0bf8e63ab5d56caea82bd\", \"zucchini_unittests\": \"9d9838d7ad18fa13984eaf20affc280c4ed32692\"}}, \"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-3-xpf4\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5712-339372b\"], \"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": "1614315238399658",
"status": "COMPLETED",
"status_changed_ts": "1614319237299618",
"tags": [
"build_address:luci.chromium.ci/win-asan/17539",
"builder:win-asan",
"buildset:commit/git/a07d2a1f9755c11285c54283844798f480bcd3b4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a07d2a1f9755c11285c54283844798f480bcd3b4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019691565117328400",
"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/8854261502287321184/+/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:51f5813839a63210",
"user_agent:luci-scheduler"
],
"updated_ts": "1614319237304556",
"url": "https://ci.chromium.org/b/8854261502287321184",
"utcnow_ts": "1614319237815090"
}
}
[06:00:37.823] Invocation finished in 1h6m43.173684685s with status SUCCEEDED