[02:20:15.483] New invocation is queued and will start shortly
[02:20:16.804] Starting the invocation (attempt 1)
[02:20:16.849] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:20:16.850] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:20:16.850] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022690932022167920",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4faf87cedc54075679475b369ff902820bef3160\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022690932022167920",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/4faf87cedc54075679475b369ff902820bef3160",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4faf87cedc54075679475b369ff902820bef3160",
"gitiles_ref:refs/heads/master"
]
}
[02:20:17.642] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611454816964923",
"id": "8857260869805308112",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4faf87cedc54075679475b369ff902820bef3160\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611454817483752",
"tags": [
"build_address:luci.chromium.ci/win-asan/16978",
"builder:win-asan",
"buildset:commit/git/4faf87cedc54075679475b369ff902820bef3160",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4faf87cedc54075679475b369ff902820bef3160",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022690932022167920",
"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/8857260869805308112/+/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": "1611454817483908",
"url": "https://ci.chromium.org/b/8857260869805308112",
"utcnow_ts": "1611454817635110"
}
}
[02:20:17.642] Task URL: https://ci.chromium.org/b/8857260869805308112
[02:20:17.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:2:0) after 1m0s
[02:20:22.581] Received PubSub notification, asking Buildbucket for the build status
[02:20:22.812] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:17.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:2:0)
[02:21:17.629] Timer tick, asking Buildbucket for the build status
[02:21:17.707] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:17.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:4:0) after 1m0s
[02:22:17.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:4:0)
[02:22:17.759] Timer tick, asking Buildbucket for the build status
[02:22:17.891] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:17.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:5:0) after 1m0s
[02:23:18.000] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:5:0)
[02:23:18.000] Timer tick, asking Buildbucket for the build status
[02:23:18.205] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:18.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:6:0) after 1m0s
[02:24:18.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:6:0)
[02:24:18.261] Timer tick, asking Buildbucket for the build status
[02:24:18.469] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:18.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:7:0) after 1m0s
[02:25:18.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:7:0)
[02:25:18.512] Timer tick, asking Buildbucket for the build status
[02:25:18.572] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:18.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:8:0) after 1m0s
[02:26:18.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:8:0)
[02:26:18.683] Timer tick, asking Buildbucket for the build status
[02:26:18.929] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:18.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:9:0) after 1m0s
[02:27:19.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:9:0)
[02:27:19.086] Timer tick, asking Buildbucket for the build status
[02:27:19.184] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:19.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:10:0) after 1m0s
[02:28:19.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:10:0)
[02:28:19.371] Timer tick, asking Buildbucket for the build status
[02:28:19.474] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:19.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:11:0) after 1m0s
[02:29:19.580] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:11:0)
[02:29:19.580] Timer tick, asking Buildbucket for the build status
[02:29:19.933] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:19.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:12:0) after 1m0s
[02:30:20.000] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:12:0)
[02:30:20.000] Timer tick, asking Buildbucket for the build status
[02:30:20.123] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:20.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:13:0) after 1m0s
[02:31:20.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:13:0)
[02:31:20.189] Timer tick, asking Buildbucket for the build status
[02:31:20.585] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:20.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:14:0) after 1m0s
[02:32:20.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:14:0)
[02:32:20.732] Timer tick, asking Buildbucket for the build status
[02:32:20.806] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:20.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:15:0) after 1m0s
[02:33:20.834] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:15:0)
[02:33:20.834] Timer tick, asking Buildbucket for the build status
[02:33:21.243] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:21.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:16:0) after 1m0s
[02:34:21.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:16:0)
[02:34:21.235] Timer tick, asking Buildbucket for the build status
[02:34:21.304] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:21.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:17:0) after 1m0s
[02:35:21.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:17:0)
[02:35:21.320] Timer tick, asking Buildbucket for the build status
[02:35:21.498] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:21.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:18:0) after 1m0s
[02:36:21.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:18:0)
[02:36:21.884] Timer tick, asking Buildbucket for the build status
[02:36:22.034] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:22.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:19:0) after 1m0s
[02:37:22.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:19:0)
[02:37:22.259] Timer tick, asking Buildbucket for the build status
[02:37:22.577] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:22.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:20:0) after 1m0s
[02:38:22.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:20:0)
[02:38:22.635] Timer tick, asking Buildbucket for the build status
[02:38:23.017] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:23.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:21:0) after 1m0s
[02:39:23.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:21:0)
[02:39:23.251] Timer tick, asking Buildbucket for the build status
[02:39:23.518] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:23.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:22:0) after 1m0s
[02:40:23.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:22:0)
[02:40:23.752] Timer tick, asking Buildbucket for the build status
[02:40:23.882] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:23.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:23:0) after 1m0s
[02:41:23.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:23:0)
[02:41:23.964] Timer tick, asking Buildbucket for the build status
[02:41:24.079] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:24.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:24:0) after 1m0s
[02:42:24.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:24:0)
[02:42:24.256] Timer tick, asking Buildbucket for the build status
[02:42:24.371] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:24.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:25:0) after 1m0s
[02:43:24.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:25:0)
[02:43:24.491] Timer tick, asking Buildbucket for the build status
[02:43:24.620] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:24.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:26:0) after 1m0s
[02:44:24.659] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:26:0)
[02:44:24.659] Timer tick, asking Buildbucket for the build status
[02:44:25.240] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:25.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:27:0) after 1m0s
[02:45:25.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:27:0)
[02:45:25.331] Timer tick, asking Buildbucket for the build status
[02:45:25.426] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:25.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:28:0) after 1m0s
[02:46:25.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:28:0)
[02:46:25.524] Timer tick, asking Buildbucket for the build status
[02:46:25.712] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:25.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:29:0) after 1m0s
[02:47:25.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:29:0)
[02:47:25.724] Timer tick, asking Buildbucket for the build status
[02:47:26.439] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:26.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:30:0) after 1m0s
[02:48:26.600] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:30:0)
[02:48:26.600] Timer tick, asking Buildbucket for the build status
[02:48:27.212] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:27.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:31:0) after 1m0s
[02:49:27.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:31:0)
[02:49:27.413] Timer tick, asking Buildbucket for the build status
[02:49:27.739] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:27.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:32:0) after 1m0s
[02:50:27.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:32:0)
[02:50:27.855] Timer tick, asking Buildbucket for the build status
[02:50:27.961] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:27.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:33:0) after 1m0s
[02:51:28.029] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:33:0)
[02:51:28.029] Timer tick, asking Buildbucket for the build status
[02:51:28.095] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:28.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:34:0) after 1m0s
[02:52:28.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:34:0)
[02:52:28.126] Timer tick, asking Buildbucket for the build status
[02:52:28.437] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:28.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:35:0) after 1m0s
[02:53:28.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:35:0)
[02:53:28.435] Timer tick, asking Buildbucket for the build status
[02:53:29.230] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:29.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:36:0) after 1m0s
[02:54:29.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:36:0)
[02:54:29.289] Timer tick, asking Buildbucket for the build status
[02:54:29.397] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:29.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:37:0) after 1m0s
[02:55:29.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:37:0)
[02:55:29.433] Timer tick, asking Buildbucket for the build status
[02:55:29.589] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:29.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:38:0) after 1m0s
[02:56:29.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:38:0)
[02:56:29.607] Timer tick, asking Buildbucket for the build status
[02:56:29.704] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:29.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:39:0) after 1m0s
[02:57:29.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:39:0)
[02:57:29.785] Timer tick, asking Buildbucket for the build status
[02:57:29.906] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:29.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:40:0) after 1m0s
[02:58:29.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:40:0)
[02:58:29.917] Timer tick, asking Buildbucket for the build status
[02:58:30.122] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:30.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:41:0) after 1m0s
[02:59:30.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:41:0)
[02:59:30.299] Timer tick, asking Buildbucket for the build status
[02:59:30.374] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:30.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:42:0) after 1m0s
[03:00:30.530] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:42:0)
[03:00:30.530] Timer tick, asking Buildbucket for the build status
[03:00:30.645] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:30.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:43:0) after 1m0s
[03:01:30.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:43:0)
[03:01:30.822] Timer tick, asking Buildbucket for the build status
[03:01:30.915] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:30.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:44:0) after 1m0s
[03:02:31.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:44:0)
[03:02:31.133] Timer tick, asking Buildbucket for the build status
[03:02:31.708] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:31.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:45:0) after 1m0s
[03:03:31.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:45:0)
[03:03:31.844] Timer tick, asking Buildbucket for the build status
[03:03:32.446] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:32.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:46:0) after 1m0s
[03:04:32.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:46:0)
[03:04:32.486] Timer tick, asking Buildbucket for the build status
[03:04:32.550] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:32.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:47:0) after 1m0s
[03:05:32.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:47:0)
[03:05:32.568] Timer tick, asking Buildbucket for the build status
[03:05:32.661] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:32.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:48:0) after 1m0s
[03:06:32.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:48:0)
[03:06:32.721] Timer tick, asking Buildbucket for the build status
[03:06:32.865] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:32.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:49:0) after 1m0s
[03:07:32.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:49:0)
[03:07:32.923] Timer tick, asking Buildbucket for the build status
[03:07:33.054] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:33.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:50:0) after 1m0s
[03:08:33.081] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:50:0)
[03:08:33.081] Timer tick, asking Buildbucket for the build status
[03:08:33.565] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:33.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:51:0) after 1m0s
[03:09:33.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:51:0)
[03:09:33.585] Timer tick, asking Buildbucket for the build status
[03:09:33.703] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:33.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:52:0) after 1m0s
[03:10:33.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:52:0)
[03:10:33.724] Timer tick, asking Buildbucket for the build status
[03:10:33.901] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:33.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:53:0) after 1m0s
[03:11:33.961] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:53:0)
[03:11:33.961] Timer tick, asking Buildbucket for the build status
[03:11:34.028] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:34.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:54:0) after 1m0s
[03:12:34.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:54:0)
[03:12:34.183] Timer tick, asking Buildbucket for the build status
[03:12:34.455] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:34.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:55:0) after 1m0s
[03:13:34.525] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:55:0)
[03:13:34.525] Timer tick, asking Buildbucket for the build status
[03:13:34.757] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:34.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:56:0) after 1m0s
[03:14:34.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:56:0)
[03:14:34.873] Timer tick, asking Buildbucket for the build status
[03:14:35.226] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:35.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:57:0) after 1m0s
[03:15:35.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:57:0)
[03:15:35.321] Timer tick, asking Buildbucket for the build status
[03:15:35.460] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:35.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:58:0) after 1m0s
[03:16:35.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:58:0)
[03:16:35.487] Timer tick, asking Buildbucket for the build status
[03:16:35.561] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:35.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:59:0) after 1m0s
[03:17:35.579] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:59:0)
[03:17:35.579] Timer tick, asking Buildbucket for the build status
[03:17:36.226] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:36.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:60:0) after 1m0s
[03:18:36.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:60:0)
[03:18:36.313] Timer tick, asking Buildbucket for the build status
[03:18:36.383] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:36.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:61:0) after 1m0s
[03:19:36.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:61:0)
[03:19:36.434] Timer tick, asking Buildbucket for the build status
[03:19:36.539] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:36.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:62:0) after 1m0s
[03:20:36.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:62:0)
[03:20:36.526] Timer tick, asking Buildbucket for the build status
[03:20:36.695] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:36.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:63:0) after 1m0s
[03:21:36.690] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:63:0)
[03:21:36.690] Timer tick, asking Buildbucket for the build status
[03:21:36.820] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:36.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:64:0) after 1m0s
[03:22:36.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:64:0)
[03:22:36.872] Timer tick, asking Buildbucket for the build status
[03:22:36.942] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:36.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:65:0) after 1m0s
[03:23:36.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:65:0)
[03:23:36.964] Timer tick, asking Buildbucket for the build status
[03:23:37.072] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:37.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:66:0) after 1m0s
[03:24:37.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:66:0)
[03:24:37.104] Timer tick, asking Buildbucket for the build status
[03:24:37.212] Build 8857260869805308112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:37.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022690932022167920:67:0) after 1m0s
[03:24:54.133] Received PubSub notification, asking Buildbucket for the build status
[03:24:54.352] Build 8857260869805308112: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:24:54.352] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611458693294867",
"created_by": "project:chromium",
"created_ts": "1611454816964923",
"id": "8857260869805308112",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4faf87cedc54075679475b369ff902820bef3160\"}}",
"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\": \"4faf87cedc54075679475b369ff902820bef3160\", \"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-10-3ote\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16978, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"3c3b82abcf0a5c18b4d97ae49726045ce6d3b832\", \"got_dawn_revision\": \"f1f8394de04ea9532de763dfb2f968fc9610683b\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"4faf87cedc54075679475b369ff902820bef3160\", \"got_revision_cp\": \"refs/heads/master@{#846551}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"413f7cb17c77c95e4cc3ba141f3c0f049ac2ecb2\", \"got_v8_revision\": \"f2b2f28304b332e59226ee6c0688307ed9982884\", \"got_v8_revision_cp\": \"refs/heads/9.0.57@{#1}\", \"got_webrtc_revision\": \"90776cb0ef72d437983c8d29e5a7f2ac264c107d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33064}\", \"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\": \"4faf87cedc54075679475b369ff902820bef3160\", \"swarm_hashes_refs/heads/master(at){#846551}_without_patch\": {\"absl_hardening_tests\": \"1f2a710a685ac73b35047b1818ef70ed124bf33c\", \"accessibility_unittests\": \"160052452919f3128229f27877aefc35aac77d7f\", \"angle_unittests\": \"069c1c9c80c861d5f0030d422cdff94d07317eba\", \"app_shell_unittests\": \"df6a5d610008d1d2e6511e7dc6f6d751cf1fcc24\", \"aura_unittests\": \"5959f7d3734deafcfe9878a0b09870029505e092\", \"base_unittests\": \"6f58242170690808b8ceade3fd1b9cdcb87054d2\", \"base_util_unittests\": \"9bf07a1a7a634ed272988db3c91c890b2ec5e7a8\", \"blink_common_unittests\": \"2b15a6510075ea65ac836f60c8a2850afab96d00\", \"blink_fuzzer_unittests\": \"db14a27295a81150b7a7ae5c275ff97af6f36e9a\", \"blink_heap_unittests\": \"9e646323137c2400b8c2bc18332991a708c76b5d\", \"blink_platform_unittests\": \"7364567c5e94b853e291b39e6591f6477303e54b\", \"blink_unittests\": \"d6f3558739961547f066021a40c7eeb4ab0b522c\", \"boringssl_crypto_tests\": \"3d92088223316e0de4cd3a7dc50aaf695ac5258f\", \"boringssl_ssl_tests\": \"fce259e3006c34c2b95622e11032abf419efc721\", \"browser_switcher_bho_unittests\": \"5c3794f88ec637239bd1bc0ad82f29c5fb18f1ce\", \"browser_tests\": \"b93a50c2c372210b2bcf436084f663129bbc119f\", \"capture_unittests\": \"42bbaf3987257fb87a7b187a15323dbad38730a2\", \"cast_unittests\": \"07eaad4296f21f39782231d7b63df62e49ce3a68\", \"cc_unittests\": \"8c41ba5887174084341f3ffcb45b737f305197df\", \"chrome_app_unittests\": \"be405200e1cb36b16cd6b653112c831405529747\", \"chrome_cleaner_unittests\": \"63cdb41237e214cc54c352bc6956246fd353913d\", \"chrome_elf_unittests\": \"405b1896df3b501befd82a76554b6bd5e81a1201\", \"chromedriver_unittests\": \"e694212b060d15f19e74bd65aad97fa092900fc8\", \"components_browsertests\": \"8c143c3bf2b83efa65673cabd2402f57fea5ab67\", \"components_unittests\": \"df2034dbef2985efda7582d0d3db75262eaf3433\", \"compositor_unittests\": \"987352bffdfd7a3fc933da50c2f4b5c10a00b878\", \"content_browsertests\": \"e04e05ad11a568f7e944314a6da9765961bf84d2\", \"content_unittests\": \"55d521dfac68fa26c48c15e42a4aec9a29208331\", \"courgette_unittests\": \"a2d2e2239b7d22e3b0ba41f472e09f5b23561dde\", \"crashpad_tests\": \"dc7f13cd1fd56d2cd76af1088da017ceafc7ba51\", \"cronet_tests\": \"b27c4d32b472e4edb4dfece3095bdb0c992bbee7\", \"cronet_unittests\": \"33137cf522b4017b01c4ed644abd2e89de009bb9\", \"crypto_unittests\": \"0dc89e8571a84296c9811a6bd97927b6cf192e8f\", \"delayloads_unittests\": \"ca8d137a3c25bc9e7f12d5d6e736d2e43f398186\", \"device_unittests\": \"7da5e222e1ae40f5a568192e829b47203446e6d1\", \"display_unittests\": \"3e221b069f4d1bb9f04bb52c85b12e56187b215f\", \"elevation_service_unittests\": \"7bd6619ed8b6cbae9ffb05773ee62385c27c353f\", \"events_unittests\": \"51262948bf04205cdf16ab9747df0733f1187fef\", \"extensions_browsertests\": \"cab9bf878bb022458c593831b354fc7d0e4adb89\", \"extensions_unittests\": \"9031f816cf0c1cf8ab3c9352c15138836cbe00df\", \"filesystem_service_unittests\": \"6b0fee073fe49d30dace2a57b6533af3e76bb9cf\", \"gcm_unit_tests\": \"7ff82c02b7f2ac3e8d2bb9419716d1b87b30ae2c\", \"gcp_unittests\": \"91bfacbd6313730fb8483570a6ce770cc8f3b659\", \"gfx_unittests\": \"43e06fbd140a6b3b3fc3ec091f11d75653bf5cf1\", \"gin_unittests\": \"9e84284807c24a651eb12d0e2c44c13d500f712b\", \"google_apis_unittests\": \"fc738665b4fa9281b8f2e6505d52b455299209ef\", \"gpu_unittests\": \"1efd376538e5c9b4e053acf32244825cd52ef4f1\", \"gwp_asan_unittests\": \"30f8313b9ab2f78b9f4cdd226a780db923ced183\", \"headless_browsertests\": \"b76a0391578ea3a86dbc033f4c1a8725a5a06993\", \"headless_unittests\": \"d783238e4578c7535c75ea734ca7eb266c77846c\", \"install_static_unittests\": \"720301fa29c8b461869a249d0686e7abfe977338\", \"installer_util_unittests\": \"0cd86923d9b5473e2ed32c7fe988466afe4cc585\", \"interactive_ui_tests\": \"5815353db8742ce8bd181d7ea42b810f5214cb8e\", \"ipc_tests\": \"0b4c726b026ce1d874009c97a231abb4242d429f\", \"jingle_unittests\": \"53c973e0ab014315b7dd83ba10016701a46ddb23\", \"latency_unittests\": \"a2cca5e840ef976fa5386116d55f7cd40cdb7c8c\", \"libjingle_xmpp_unittests\": \"1367ca97893cee47c5d71299dd2687e252a9c502\", \"liburlpattern_unittests\": \"9d811e5b616542cbe0210fdff76c31068fdfe2a2\", \"media_blink_unittests\": \"8ca1b77e4bbb867a4a04522a184ffddd4207071a\", \"media_unittests\": \"7c49bb1e43b0f0a2816baac7323542d7fb89a5c4\", \"message_center_unittests\": \"744e674252396003bd22204f9480f7ce761d887a\", \"midi_unittests\": \"da598f12e90bdcb1fc6b92202861c4a8721a52f6\", \"mojo_core_unittests\": \"df8edf8260ced679732c2ee056270feaeaab2edf\", \"mojo_unittests\": \"53001eb65301f675ed981aec74f3f58aba42971c\", \"nacl_loader_unittests\": \"039443bdb2334c54b4c0840e6986aae27e300b80\", \"native_theme_unittests\": \"735ad8a1b5845341cd5dd8a16bcb1840afa134ec\", \"net_unittests\": \"328cdbfdc9aa9a9f766562745b5415921536e2fb\", \"notification_helper_unittests\": \"462a981e7fb284300a8d556926eafddf11d67adf\", \"pdf_unittests\": \"539831ed85e97c4961a1d8586f7e7fedd71c6e52\", \"perfetto_unittests\": \"e26f45f7cfb9f7c86a6f3927dfdb2c22e707497d\", \"ppapi_unittests\": \"3f3b4890fe5602414c58d0b9dcf1ada93511bb6d\", \"printing_unittests\": \"cf33bab6769bd469445c528d6581e1385dfe63e5\", \"remoting_unittests\": \"63d8d6205857777e1bbdf5db5295ac68adc13938\", \"sbox_integration_tests\": \"362400fca18f6eb3dc8b0aaaa7acae27bb72f8fa\", \"sbox_unittests\": \"346139aa123aabada021834405b492381ac48b30\", \"sbox_validation_tests\": \"2c47a7279953de0af7cbb7eb52f3771ae8b9b70f\", \"service_manager_unittests\": \"ea37406d382f5bb9b2d1c195aca290a8e57f956b\", \"services_unittests\": \"fb18166c6c93f4ab178a586d9589d6b7c3b8cb5a\", \"setup_unittests\": \"494c04aaeb26d656f741beb51136cecc3e284933\", \"shell_dialogs_unittests\": \"1653946bb04e72a798e3765ceaa4485de3f4c1c4\", \"skia_unittests\": \"c0380e954c2918596009a555078fbd4645a68106\", \"snapshot_unittests\": \"b27ec20c6b19bafd879368b16b831685d5a2c3b4\", \"sql_unittests\": \"4bdb684ccd1bd6fa2acaa4e9c4df7c254ae2d8e5\", \"storage_unittests\": \"d6147ac01445a593a5889f97e9d1e838f45f727b\", \"sync_integration_tests\": \"b56bc8cf8c9669b22e36b5ed370df42045c8795a\", \"traffic_annotation_auditor_unittests\": \"32484a5915f7d7058613067f6f0722a923b6b4e4\", \"ui_base_unittests\": \"4dc45fe67347f9a9882a7974e5d9695eebf81b3c\", \"ui_touch_selection_unittests\": \"c9a87152d4dd42461af526def67faeec89dcb7ed\", \"unit_tests\": \"576356eb2a766adc8196578450d6cbf1091d24f7\", \"updater_tests\": \"26b66832c7a2ea0430676e6ba637c44e98afbd52\", \"url_unittests\": \"2bf424868e78912f4d98e444fed6854a8da0d800\", \"views_unittests\": \"b461cd6fcdf0adccbc81ec2c43af7002f4ad4f86\", \"viz_unittests\": \"4a747194da598a6987afe06df464ad6127f867e3\", \"vr_common_unittests\": \"b87a8e17fc7bba6a1fa873ebb8049dc2b2809d76\", \"vr_pixeltests\": \"950daddd9e3402c2c2f19143a8268a90e575aaec\", \"weblayer_browsertests\": \"67ee4871c9c8a778058435fa3651ae363636cd3a\", \"weblayer_unittests\": \"f06d7365d4b5a4c502aac86ab9e447d08f76a32c\", \"wm_unittests\": \"27be50aec3f1ffef4a17f7a57fd4c606d4a97b29\", \"wtf_unittests\": \"e3282f5a39ad7d0135554ac46122ce296568d68f\", \"zlib_unittests\": \"a00dc59ab6d5709883183d97c12c7376b66062a8\", \"zucchini_unittests\": \"38d2844117cfb1def6325f047d5b35fd6b3096ee\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_91c730c3e6ead475130e2ae78bc3f03e09cb9dcb91c3469d4450b29020110401_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-10-3ote\"], \"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\": [\"5671-1d9dea8\"], \"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": "1611454822030093",
"status": "COMPLETED",
"status_changed_ts": "1611458693565855",
"tags": [
"build_address:luci.chromium.ci/win-asan/16978",
"builder:win-asan",
"buildset:commit/git/4faf87cedc54075679475b369ff902820bef3160",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4faf87cedc54075679475b369ff902820bef3160",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022690932022167920",
"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/8857260869805308112/+/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:514b02aea25a3e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611458693572281",
"url": "https://ci.chromium.org/b/8857260869805308112",
"utcnow_ts": "1611458694341338"
}
}
[03:24:54.352] Invocation finished in 1h4m38.885300088s with status SUCCEEDED