[09:00:16.149] New invocation is queued and will start shortly
[09:00:17.213] Starting the invocation (attempt 1)
[09:00:17.246] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:00:17.246] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:00:17.247] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019676065607134704",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"369026166094d010af0a3ad7ed69b616603abcad\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019676065607134704",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/369026166094d010af0a3ad7ed69b616603abcad",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/369026166094d010af0a3ad7ed69b616603abcad",
"gitiles_ref:refs/heads/master"
]
}
[09:00:18.101] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614330017321402",
"id": "8854246003716058880",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"369026166094d010af0a3ad7ed69b616603abcad\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614330017884336",
"tags": [
"build_address:luci.chromium.ci/win-asan/17543",
"builder:win-asan",
"buildset:commit/git/369026166094d010af0a3ad7ed69b616603abcad",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/369026166094d010af0a3ad7ed69b616603abcad",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019676065607134704",
"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/8854246003716058880/+/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": "1614330017884751",
"url": "https://ci.chromium.org/b/8854246003716058880",
"utcnow_ts": "1614330018090215"
}
}
[09:00:18.101] Task URL: https://ci.chromium.org/b/8854246003716058880
[09:00:18.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:2:0) after 1m0s
[09:00:21.892] Received PubSub notification, asking Buildbucket for the build status
[09:00:22.239] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:18.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:2:0)
[09:01:18.133] Timer tick, asking Buildbucket for the build status
[09:01:18.213] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:18.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:4:0) after 1m0s
[09:02:18.230] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:4:0)
[09:02:18.230] Timer tick, asking Buildbucket for the build status
[09:02:18.307] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:18.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:5:0) after 1m0s
[09:03:18.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:5:0)
[09:03:18.372] Timer tick, asking Buildbucket for the build status
[09:03:18.442] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:18.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:6:0) after 1m0s
[09:04:18.415] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:6:0)
[09:04:18.415] Timer tick, asking Buildbucket for the build status
[09:04:18.496] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:18.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:7:0) after 1m0s
[09:05:18.510] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:7:0)
[09:05:18.510] Timer tick, asking Buildbucket for the build status
[09:05:18.682] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:18.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:8:0) after 1m0s
[09:06:18.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:8:0)
[09:06:18.894] Timer tick, asking Buildbucket for the build status
[09:06:19.001] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:19.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:9:0) after 1m0s
[09:07:19.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:9:0)
[09:07:19.025] Timer tick, asking Buildbucket for the build status
[09:07:19.098] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:19.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:10:0) after 1m0s
[09:08:19.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:10:0)
[09:08:19.126] Timer tick, asking Buildbucket for the build status
[09:08:19.188] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:19.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:11:0) after 1m0s
[09:09:19.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:11:0)
[09:09:19.276] Timer tick, asking Buildbucket for the build status
[09:09:19.354] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:19.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:12:0) after 1m0s
[09:10:19.443] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:12:0)
[09:10:19.443] Timer tick, asking Buildbucket for the build status
[09:10:19.528] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:19.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:13:0) after 1m0s
[09:11:19.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:13:0)
[09:11:19.582] Timer tick, asking Buildbucket for the build status
[09:11:19.745] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:19.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:14:0) after 1m0s
[09:12:19.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:14:0)
[09:12:19.772] Timer tick, asking Buildbucket for the build status
[09:12:19.948] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:19.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:15:0) after 1m0s
[09:13:20.040] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:15:0)
[09:13:20.040] Timer tick, asking Buildbucket for the build status
[09:13:20.140] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:20.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:16:0) after 1m0s
[09:14:20.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:16:0)
[09:14:20.208] Timer tick, asking Buildbucket for the build status
[09:14:20.292] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:20.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:17:0) after 1m0s
[09:15:20.306] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:17:0)
[09:15:20.306] Timer tick, asking Buildbucket for the build status
[09:15:20.425] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:20.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:18:0) after 1m0s
[09:16:20.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:18:0)
[09:16:20.469] Timer tick, asking Buildbucket for the build status
[09:16:20.550] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:20.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:19:0) after 1m0s
[09:17:20.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:19:0)
[09:17:20.531] Timer tick, asking Buildbucket for the build status
[09:17:20.605] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:20.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:20:0) after 1m0s
[09:18:20.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:20:0)
[09:18:20.656] Timer tick, asking Buildbucket for the build status
[09:18:20.847] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:20.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:21:0) after 1m0s
[09:19:20.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:21:0)
[09:19:20.898] Timer tick, asking Buildbucket for the build status
[09:19:20.980] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:20.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:22:0) after 1m0s
[09:20:21.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:22:0)
[09:20:21.018] Timer tick, asking Buildbucket for the build status
[09:20:21.198] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:21.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:23:0) after 1m0s
[09:21:21.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:23:0)
[09:21:21.282] Timer tick, asking Buildbucket for the build status
[09:21:21.361] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:21.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:24:0) after 1m0s
[09:22:21.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:24:0)
[09:22:21.410] Timer tick, asking Buildbucket for the build status
[09:22:21.510] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:21.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:25:0) after 1m0s
[09:23:21.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:25:0)
[09:23:21.528] Timer tick, asking Buildbucket for the build status
[09:23:21.746] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:21.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:26:0) after 1m0s
[09:24:21.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:26:0)
[09:24:21.841] Timer tick, asking Buildbucket for the build status
[09:24:21.927] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:21.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:27:0) after 1m0s
[09:25:22.017] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:27:0)
[09:25:22.017] Timer tick, asking Buildbucket for the build status
[09:25:22.132] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:22.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:28:0) after 1m0s
[09:26:22.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:28:0)
[09:26:22.139] Timer tick, asking Buildbucket for the build status
[09:26:22.309] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:22.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:29:0) after 1m0s
[09:27:22.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:29:0)
[09:27:22.467] Timer tick, asking Buildbucket for the build status
[09:27:22.556] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:22.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:30:0) after 1m0s
[09:28:22.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:30:0)
[09:28:22.595] Timer tick, asking Buildbucket for the build status
[09:28:22.689] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:22.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:31:0) after 1m0s
[09:29:22.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:31:0)
[09:29:22.769] Timer tick, asking Buildbucket for the build status
[09:29:22.856] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:22.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:32:0) after 1m0s
[09:30:22.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:32:0)
[09:30:22.874] Timer tick, asking Buildbucket for the build status
[09:30:23.230] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:23.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:33:0) after 1m0s
[09:31:23.344] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:33:0)
[09:31:23.344] Timer tick, asking Buildbucket for the build status
[09:31:23.457] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:23.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:34:0) after 1m0s
[09:32:23.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:34:0)
[09:32:23.501] Timer tick, asking Buildbucket for the build status
[09:32:23.583] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:23.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:35:0) after 1m0s
[09:33:23.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:35:0)
[09:33:23.607] Timer tick, asking Buildbucket for the build status
[09:33:24.129] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:24.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:36:0) after 1m0s
[09:34:24.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:36:0)
[09:34:24.145] Timer tick, asking Buildbucket for the build status
[09:34:24.221] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:24.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:37:0) after 1m0s
[09:35:24.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:37:0)
[09:35:24.240] Timer tick, asking Buildbucket for the build status
[09:35:24.355] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:24.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:38:0) after 1m0s
[09:36:24.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:38:0)
[09:36:24.377] Timer tick, asking Buildbucket for the build status
[09:36:24.466] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:24.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:39:0) after 1m0s
[09:37:24.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:39:0)
[09:37:24.554] Timer tick, asking Buildbucket for the build status
[09:37:24.734] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:24.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:40:0) after 1m0s
[09:38:24.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:40:0)
[09:38:24.733] Timer tick, asking Buildbucket for the build status
[09:38:24.813] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:24.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:41:0) after 1m0s
[09:39:24.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:41:0)
[09:39:24.837] Timer tick, asking Buildbucket for the build status
[09:39:24.916] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:24.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:42:0) after 1m0s
[09:40:25.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:42:0)
[09:40:25.063] Timer tick, asking Buildbucket for the build status
[09:40:25.199] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:25.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:43:0) after 1m0s
[09:41:25.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:43:0)
[09:41:25.258] Timer tick, asking Buildbucket for the build status
[09:41:25.814] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:25.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:44:0) after 1m0s
[09:42:25.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:44:0)
[09:42:25.839] Timer tick, asking Buildbucket for the build status
[09:42:26.048] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:26.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:45:0) after 1m0s
[09:43:26.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:45:0)
[09:43:26.068] Timer tick, asking Buildbucket for the build status
[09:43:26.148] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:26.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:46:0) after 1m0s
[09:44:26.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:46:0)
[09:44:26.172] Timer tick, asking Buildbucket for the build status
[09:44:27.021] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:27.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:47:0) after 1m0s
[09:45:27.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:47:0)
[09:45:27.098] Timer tick, asking Buildbucket for the build status
[09:45:27.215] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:27.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:48:0) after 1m0s
[09:46:27.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:48:0)
[09:46:27.238] Timer tick, asking Buildbucket for the build status
[09:46:27.600] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:27.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:49:0) after 1m0s
[09:47:27.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:49:0)
[09:47:27.709] Timer tick, asking Buildbucket for the build status
[09:47:27.806] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:27.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:50:0) after 1m0s
[09:48:27.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:50:0)
[09:48:27.955] Timer tick, asking Buildbucket for the build status
[09:48:28.037] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:28.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:51:0) after 1m0s
[09:49:28.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:51:0)
[09:49:28.034] Timer tick, asking Buildbucket for the build status
[09:49:28.112] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:28.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:52:0) after 1m0s
[09:50:28.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:52:0)
[09:50:28.226] Timer tick, asking Buildbucket for the build status
[09:50:28.323] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:28.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:53:0) after 1m0s
[09:51:28.373] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:53:0)
[09:51:28.373] Timer tick, asking Buildbucket for the build status
[09:51:28.641] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:28.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:54:0) after 1m0s
[09:52:28.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:54:0)
[09:52:28.722] Timer tick, asking Buildbucket for the build status
[09:52:28.802] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:28.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:55:0) after 1m0s
[09:53:28.817] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:55:0)
[09:53:28.817] Timer tick, asking Buildbucket for the build status
[09:53:28.960] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:28.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:56:0) after 1m0s
[09:54:29.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:56:0)
[09:54:29.025] Timer tick, asking Buildbucket for the build status
[09:54:29.133] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:29.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:57:0) after 1m0s
[09:55:29.153] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:57:0)
[09:55:29.153] Timer tick, asking Buildbucket for the build status
[09:55:29.247] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:29.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:58:0) after 1m0s
[09:56:29.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:58:0)
[09:56:29.340] Timer tick, asking Buildbucket for the build status
[09:56:29.477] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:29.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:59:0) after 1m0s
[09:57:29.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:59:0)
[09:57:29.487] Timer tick, asking Buildbucket for the build status
[09:57:29.774] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:29.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:60:0) after 1m0s
[09:58:29.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:60:0)
[09:58:29.846] Timer tick, asking Buildbucket for the build status
[09:58:30.461] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:30.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:61:0) after 1m0s
[09:59:30.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:61:0)
[09:59:30.438] Timer tick, asking Buildbucket for the build status
[09:59:31.056] Build 8854246003716058880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:31.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019676065607134704:62:0) after 1m0s
[10:00:13.085] Received PubSub notification, asking Buildbucket for the build status
[10:00:13.190] Build 8854246003716058880: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:00:13.190] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614333611935713",
"created_by": "project:chromium",
"created_ts": "1614330017321402",
"id": "8854246003716058880",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"369026166094d010af0a3ad7ed69b616603abcad\"}}",
"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\": \"369026166094d010af0a3ad7ed69b616603abcad\", \"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\": 17543, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"d24398b3e36175d08e74261a1a0a2c8a27a00dd6\", \"got_dawn_revision\": \"87649ff09dee357f0412834527e4174b4989358f\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"369026166094d010af0a3ad7ed69b616603abcad\", \"got_revision_cp\": \"refs/heads/master@{#858057}\", \"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\": \"369026166094d010af0a3ad7ed69b616603abcad\", \"swarm_hashes_refs/heads/master(at){#858057}_without_patch\": {\"absl_hardening_tests\": \"81450d4a59cf9e11196375f7c6c8c515700de2b8\", \"accessibility_unittests\": \"673d470167a7ff93865e35bd97e690f9868b01ce\", \"angle_unittests\": \"303dfb275a274b725b65ab9ba3244eb42aa508ca\", \"app_shell_unittests\": \"e16c8dd430a63f320746a1d8da0d93188701b9cf\", \"aura_unittests\": \"da4519e4ce4eeafc841382925a3f1c59ab795484\", \"base_unittests\": \"906740c15f10ed80bf925139d3e77ce4f87a7dd4\", \"base_util_unittests\": \"183ea2f869a5bacacb92e1d4efa996d21f62c1b5\", \"blink_common_unittests\": \"dfcda5ca050bc84a43c675b686df17a53391fa6e\", \"blink_fuzzer_unittests\": \"92d6c83137a018d95e0b03064b09e27a76501506\", \"blink_heap_unittests\": \"b5d7194de614e45a5c9a48019a3f2bca3dad6bbf\", \"blink_platform_unittests\": \"79e0bf4c4f340ba0048381a4db7e49efe2dcce9e\", \"blink_unittests\": \"8d33740f3417e7d0cd0b74d2d083079b45c69d5e\", \"boringssl_crypto_tests\": \"674ab1de077ecbf293466d4cd9be4a9ce028af5c\", \"boringssl_ssl_tests\": \"9c2e930ff8b74248dbb8f2c686bde225d63fe1b2\", \"browser_switcher_bho_unittests\": \"e6bf1ef4ede47b01747b72e946ac79e27de81f8c\", \"browser_tests\": \"321621c4ff1b3e796ad68b9daaf07a19cc2a4965\", \"capture_unittests\": \"d9d73cb16a1a24738213913da7447cdd0ab40490\", \"cast_unittests\": \"3dc274891a81da44ee55107060a3677da88c67f1\", \"cc_unittests\": \"9178f2d7fa9e5cd2180de850282ae4696fb22861\", \"chrome_app_unittests\": \"00e14730e65cc25ade079dd58b1d8f20f21182b5\", \"chrome_cleaner_unittests\": \"192bf6440db48299eca9bbd00194251661d4a6c5\", \"chrome_elf_unittests\": \"61133cdea4a2523079639c2f347f7c3f5afd3345\", \"chromedriver_unittests\": \"cc39b72e354df040585848febaf2d67150223df1\", \"color_unittests\": \"245e4cdae4a28caa80791d94660b7ef44a8b2d90\", \"components_browsertests\": \"015dd57a9a55f9b84bb0c03996c28154f4aa7d96\", \"components_unittests\": \"498ca009f5a3545a0a7c6036edfda1a411c5b846\", \"compositor_unittests\": \"6ac73bbbac277b41d249ee058e44c52ef3bd0538\", \"content_browsertests\": \"a4b7420c02cc366b622f319abdf8f539d2a7f699\", \"content_unittests\": \"62694834a3d20c5d68311ca943ccfe86638990d0\", \"courgette_unittests\": \"c1b9625b0b838aeee98d56a7a29d6730a773af97\", \"crashpad_tests\": \"a2c135a197ccc896cab4bffb7654587287aa5ef8\", \"cronet_tests\": \"ff59d422d179c1d2650d012b92bfcd278768dfdb\", \"cronet_unittests\": \"baf01e0117e11e09a50d4ea6a7632c68f190ddb6\", \"crypto_unittests\": \"810f93774b6de6e1c72b90ecf67e53fb0ab2379b\", \"delayloads_unittests\": \"7c57f4f77d6e211e98cc6b8ca2ae5f1527d57f5b\", \"device_unittests\": \"096d381438ec895579c01cc5e9da9aebc827d499\", \"display_unittests\": \"fd5a51f998be0f2b7e435c60fdc624a13cd3fa81\", \"elevation_service_unittests\": \"dc961bc288550bbdddf3ef2c1dddf71fd7431e78\", \"events_unittests\": \"6f987ebb1fcd1f6200eeade1cdbdb575c4d517e1\", \"extensions_browsertests\": \"b9aa3c0a09aa41a71f307eca22eeb62439497cdf\", \"extensions_unittests\": \"9d17c65f188c0c85017d657d370f49401fe6f6f6\", \"filesystem_service_unittests\": \"ef919b359d66c916899656ab74a633054401a4ed\", \"gcm_unit_tests\": \"260757e09e943ee007b486a82f74c43ca3a60318\", \"gcp_unittests\": \"ae1ef54c74a66d42102853fe0531f8a343ab0f46\", \"gfx_unittests\": \"fc2a933e187d28960dfe3d4eb764b084384cc331\", \"gin_unittests\": \"77a9064652b5990f17c87d035091a2eba8cc212d\", \"google_apis_unittests\": \"e5e248ee0e337d524d75c5ad7c77b890f82024c2\", \"gpu_unittests\": \"631261e370a1d98e54aa0c2ce640861090fe7113\", \"gwp_asan_unittests\": \"fba4bda01bdc4c369ac1aaae1a3b1814c13cf30e\", \"headless_browsertests\": \"ec5cf68f590c7c3dcda6a1ea7fca57b0a56ddd95\", \"headless_unittests\": \"4eff6836f5f0105b79bdab17e6905e54cadafd03\", \"install_static_unittests\": \"f0102e26b06ea141fea09c403002a08276d314e4\", \"installer_util_unittests\": \"6b43df393b1060a30f4555563af23b7f8c42e01f\", \"interactive_ui_tests\": \"b9b843cb2e5d5aa3fd2a2698a3eca15e8d906305\", \"ipc_tests\": \"eb94de0b3b55b0584b8cbf623a1cfc1abf42beee\", \"jingle_unittests\": \"326a980bd1dfe279ffecd8954e6722160aa097d8\", \"latency_unittests\": \"b5fb73f4991241d71d0cb525844eba9183ca4610\", \"libjingle_xmpp_unittests\": \"fab0019103a07408bb35fb9b0bbb9fe7777a5293\", \"liburlpattern_unittests\": \"cd4b5716a0d5f5d2c2b19bf957292b58915f9a62\", \"media_blink_unittests\": \"a733021a9a24f08d417c02382a0e0e558d6b9402\", \"media_unittests\": \"876103319ef04241a17f73c0036808f1e34d7bdf\", \"message_center_unittests\": \"3e161cba03df056d4e7429e6dd43898ebecb32d9\", \"midi_unittests\": \"d3980e8000f8ffffdd153b72ee7ce77c3d5021dc\", \"mojo_core_unittests\": \"6ed81928f14e680ed1c6c422dafd1b632f0d3423\", \"mojo_unittests\": \"06583810305c7711557afb0af8cf5bc02676754a\", \"nacl_loader_unittests\": \"246cd9b120579fafe291078e9a37eaec286d778d\", \"native_theme_unittests\": \"23acd2da7a412aadc92ad9e5d426fcdfbe78e39c\", \"net_unittests\": \"e9809cb9f47a8fcd519c277a6dbb5e1560c73110\", \"notification_helper_unittests\": \"e8218638b3b14f5be676fa467c3c69fc2e917ef4\", \"pdf_unittests\": \"4e8c166d88295f6b1a713d41abd67606e9fff3b0\", \"perfetto_unittests\": \"212f88040123494d46da09f0d65a3d4929569640\", \"ppapi_unittests\": \"61d8f2423af5553dc8df986ae88f553e11413d3e\", \"printing_unittests\": \"d5ff975ac8deb318b8f65cfa77b1350827bc33ab\", \"remoting_unittests\": \"c6e37f7a0ef6fa80f4e81630d756f181ee24b8e8\", \"sbox_integration_tests\": \"761e3f270e3bf207779a054b7e7938690a63bdc2\", \"sbox_unittests\": \"9072afd83d4383c1dd069c4ce494010bf63a438f\", \"sbox_validation_tests\": \"764495ee09df7ee67b33bec8f0180e5bb2aed46c\", \"service_manager_unittests\": \"4ea426bb68bb2b062f98727c66ad1ef3a32963bc\", \"services_unittests\": \"ba1b32a6d964e44380ea874f1d8175919de51a5c\", \"setup_unittests\": \"24806e4445d388c39b5ef53d00595c3e53fb7a9d\", \"shell_dialogs_unittests\": \"f153a19e79179ecd8786aeae264b7651e5ff47af\", \"skia_unittests\": \"61cb592e305a80feb3bdc79cade87b22b8e6d2e8\", \"snapshot_unittests\": \"36a952a1eda33ca879000d9c8492c494e08be1d9\", \"sql_unittests\": \"157d0c83a4e242868da944f17767efe96ba9c262\", \"storage_unittests\": \"ff83fc93abb10614e23cf69f6c15827cd32f83c2\", \"sync_integration_tests\": \"e6d43b5d90ba5fa3bbf5df06441e8bb0e26d4901\", \"traffic_annotation_auditor_unittests\": \"5e7d772d18987c5a49b9aa385431156471be1585\", \"ui_base_unittests\": \"609060103571748dc7c075f1e1f7554013d57381\", \"ui_touch_selection_unittests\": \"eb939d19b310c2fcf24cbbd940680f2344f7979b\", \"unit_tests\": \"94b2921fbf3ff9a85857f3cccd3ddc727495a724\", \"updater_tests\": \"f8f42afd1f1b7da17f6036f2b58260187b9edfa8\", \"url_unittests\": \"e19d33b327f1a7a657f7e5d58f2102d0a30a701f\", \"views_unittests\": \"192d1c70b2bbef34ab4535428188d53d7723d094\", \"viz_unittests\": \"b9aa0b553bca77ffee6dc7cd4373a2424df9ae2c\", \"vr_common_unittests\": \"00bc60fb91b4bd27035b0118024dac9e4a3ae45e\", \"vr_pixeltests\": \"69cc7fb0ec71d88c3cc3df473192c4945ac2e4c8\", \"weblayer_browsertests\": \"2b6984c57e705a5c17960910783f8478735432c7\", \"weblayer_unittests\": \"0d88da6b4debfc1260b12e4dad33c2ef7853384e\", \"wm_unittests\": \"091a252c65bc77c621a35a3a962f7022662826b4\", \"wtf_unittests\": \"9389469b59904f440be35f114f2c034206fce8ca\", \"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": "1614330020782977",
"status": "COMPLETED",
"status_changed_ts": "1614333612435389",
"tags": [
"build_address:luci.chromium.ci/win-asan/17543",
"builder:win-asan",
"buildset:commit/git/369026166094d010af0a3ad7ed69b616603abcad",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/369026166094d010af0a3ad7ed69b616603abcad",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019676065607134704",
"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/8854246003716058880/+/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:51f662c1701d1310",
"user_agent:luci-scheduler"
],
"updated_ts": "1614333612443100",
"url": "https://ci.chromium.org/b/8854246003716058880",
"utcnow_ts": "1614333613182444"
}
}
[10:00:13.190] Invocation finished in 59m57.05561664s with status SUCCEEDED