[23:02:50.724] New invocation is queued and will start shortly
[23:02:51.949] Starting the invocation (attempt 1)
[23:02:51.984] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:02:51.984] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:02:51.985] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020166637090043584",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"029c8502901c3cf068e43f02e1189b7d62b4c725\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020166637090043584",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/029c8502901c3cf068e43f02e1189b7d62b4c725",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/029c8502901c3cf068e43f02e1189b7d62b4c725",
"gitiles_ref:refs/heads/master"
]
}
[23:02:53.066] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1613862172152849",
"id": "8854736574931715472",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"029c8502901c3cf068e43f02e1189b7d62b4c725\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613862172850867",
"tags": [
"build_address:luci.chromium.ci/win-asan/17450",
"builder:win-asan",
"buildset:commit/git/029c8502901c3cf068e43f02e1189b7d62b4c725",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/029c8502901c3cf068e43f02e1189b7d62b4c725",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020166637090043584",
"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/8854736574931715472/+/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": "1613862172850985",
"url": "https://ci.chromium.org/b/8854736574931715472",
"utcnow_ts": "1613862173055910"
}
}
[23:02:53.066] Task URL: https://ci.chromium.org/b/8854736574931715472
[23:02:53.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:2:0) after 1m0s
[23:02:56.077] Received PubSub notification, asking Buildbucket for the build status
[23:02:56.164] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:53.082] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:2:0)
[23:03:53.082] Timer tick, asking Buildbucket for the build status
[23:03:53.159] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:53.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:4:0) after 1m0s
[23:04:53.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:4:0)
[23:04:53.171] Timer tick, asking Buildbucket for the build status
[23:04:53.250] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:53.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:5:0) after 1m0s
[23:05:53.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:5:0)
[23:05:53.280] Timer tick, asking Buildbucket for the build status
[23:05:53.368] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:53.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:6:0) after 1m0s
[23:06:53.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:6:0)
[23:06:53.427] Timer tick, asking Buildbucket for the build status
[23:06:54.071] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:54.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:7:0) after 1m0s
[23:07:54.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:7:0)
[23:07:54.124] Timer tick, asking Buildbucket for the build status
[23:07:54.208] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:54.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:8:0) after 1m0s
[23:08:54.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:8:0)
[23:08:54.247] Timer tick, asking Buildbucket for the build status
[23:08:54.408] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:54.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:9:0) after 1m0s
[23:09:54.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:9:0)
[23:09:54.419] Timer tick, asking Buildbucket for the build status
[23:09:54.489] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:54.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:10:0) after 1m0s
[23:10:54.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:10:0)
[23:10:54.504] Timer tick, asking Buildbucket for the build status
[23:10:54.576] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:54.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:11:0) after 1m0s
[23:11:54.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:11:0)
[23:11:54.670] Timer tick, asking Buildbucket for the build status
[23:11:54.742] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:54.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:12:0) after 1m0s
[23:12:54.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:12:0)
[23:12:54.767] Timer tick, asking Buildbucket for the build status
[23:12:55.133] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:55.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:13:0) after 1m0s
[23:13:55.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:13:0)
[23:13:55.212] Timer tick, asking Buildbucket for the build status
[23:13:55.295] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:55.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:14:0) after 1m0s
[23:14:55.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:14:0)
[23:14:55.372] Timer tick, asking Buildbucket for the build status
[23:14:55.440] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:55.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:15:0) after 1m0s
[23:15:55.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:15:0)
[23:15:55.565] Timer tick, asking Buildbucket for the build status
[23:15:55.868] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:55.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:16:0) after 1m0s
[23:16:55.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:16:0)
[23:16:55.952] Timer tick, asking Buildbucket for the build status
[23:16:56.041] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:56.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:17:0) after 1m0s
[23:17:56.069] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:17:0)
[23:17:56.069] Timer tick, asking Buildbucket for the build status
[23:17:56.225] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:56.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:18:0) after 1m0s
[23:18:56.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:18:0)
[23:18:56.426] Timer tick, asking Buildbucket for the build status
[23:18:56.533] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:56.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:19:0) after 1m0s
[23:19:56.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:19:0)
[23:19:56.599] Timer tick, asking Buildbucket for the build status
[23:19:56.665] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:56.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:20:0) after 1m0s
[23:20:56.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:20:0)
[23:20:56.716] Timer tick, asking Buildbucket for the build status
[23:20:56.793] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:56.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:21:0) after 1m0s
[23:21:56.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:21:0)
[23:21:56.854] Timer tick, asking Buildbucket for the build status
[23:21:56.936] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:56.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:22:0) after 1m0s
[23:22:56.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:22:0)
[23:22:56.963] Timer tick, asking Buildbucket for the build status
[23:22:57.135] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:57.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:23:0) after 1m0s
[23:23:57.160] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:23:0)
[23:23:57.160] Timer tick, asking Buildbucket for the build status
[23:23:58.027] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:58.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:24:0) after 1m0s
[23:24:58.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:24:0)
[23:24:58.044] Timer tick, asking Buildbucket for the build status
[23:24:58.194] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:58.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:25:0) after 1m0s
[23:25:58.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:25:0)
[23:25:58.244] Timer tick, asking Buildbucket for the build status
[23:25:58.340] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:58.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:26:0) after 1m0s
[23:26:58.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:26:0)
[23:26:58.456] Timer tick, asking Buildbucket for the build status
[23:26:58.698] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:58.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:27:0) after 1m0s
[23:27:58.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:27:0)
[23:27:58.883] Timer tick, asking Buildbucket for the build status
[23:27:59.078] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:59.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:28:0) after 1m0s
[23:28:59.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:28:0)
[23:28:59.209] Timer tick, asking Buildbucket for the build status
[23:28:59.289] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:59.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:29:0) after 1m0s
[23:29:59.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:29:0)
[23:29:59.312] Timer tick, asking Buildbucket for the build status
[23:29:59.386] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:59.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:30:0) after 1m0s
[23:30:59.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:30:0)
[23:30:59.456] Timer tick, asking Buildbucket for the build status
[23:30:59.637] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:59.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:31:0) after 1m0s
[23:31:59.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:31:0)
[23:31:59.651] Timer tick, asking Buildbucket for the build status
[23:31:59.928] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:59.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:32:0) after 1m0s
[23:32:59.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:32:0)
[23:32:59.964] Timer tick, asking Buildbucket for the build status
[23:33:00.337] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:00.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:33:0) after 1m0s
[23:34:00.350] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:33:0)
[23:34:00.350] Timer tick, asking Buildbucket for the build status
[23:34:00.506] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:00.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:34:0) after 1m0s
[23:35:00.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:34:0)
[23:35:00.540] Timer tick, asking Buildbucket for the build status
[23:35:00.615] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:00.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:35:0) after 1m0s
[23:36:01.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:35:0)
[23:36:01.253] Timer tick, asking Buildbucket for the build status
[23:36:01.333] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:01.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:36:0) after 1m0s
[23:37:01.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:36:0)
[23:37:01.365] Timer tick, asking Buildbucket for the build status
[23:37:01.795] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:01.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:37:0) after 1m0s
[23:38:01.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:37:0)
[23:38:01.865] Timer tick, asking Buildbucket for the build status
[23:38:02.313] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:02.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:38:0) after 1m0s
[23:39:02.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:38:0)
[23:39:02.448] Timer tick, asking Buildbucket for the build status
[23:39:02.526] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:02.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:39:0) after 1m0s
[23:40:02.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:39:0)
[23:40:02.617] Timer tick, asking Buildbucket for the build status
[23:40:02.711] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:02.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:40:0) after 1m0s
[23:41:02.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:40:0)
[23:41:02.820] Timer tick, asking Buildbucket for the build status
[23:41:02.905] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:02.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:41:0) after 1m0s
[23:42:02.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:41:0)
[23:42:02.991] Timer tick, asking Buildbucket for the build status
[23:42:03.137] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:03.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:42:0) after 1m0s
[23:43:03.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:42:0)
[23:43:03.122] Timer tick, asking Buildbucket for the build status
[23:43:03.233] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:03.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:43:0) after 1m0s
[23:44:03.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:43:0)
[23:44:03.269] Timer tick, asking Buildbucket for the build status
[23:44:03.531] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:03.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:44:0) after 1m0s
[23:45:03.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:44:0)
[23:45:03.536] Timer tick, asking Buildbucket for the build status
[23:45:03.672] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:03.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:45:0) after 1m0s
[23:46:03.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:45:0)
[23:46:03.701] Timer tick, asking Buildbucket for the build status
[23:46:03.776] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:03.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:46:0) after 1m0s
[23:47:03.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:46:0)
[23:47:03.805] Timer tick, asking Buildbucket for the build status
[23:47:03.898] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:03.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:47:0) after 1m0s
[23:48:03.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:47:0)
[23:48:03.929] Timer tick, asking Buildbucket for the build status
[23:48:04.211] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:04.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:48:0) after 1m0s
[23:49:04.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:48:0)
[23:49:04.284] Timer tick, asking Buildbucket for the build status
[23:49:04.378] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:04.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:49:0) after 1m0s
[23:50:04.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:49:0)
[23:50:04.426] Timer tick, asking Buildbucket for the build status
[23:50:04.515] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:04.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:50:0) after 1m0s
[23:51:04.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:50:0)
[23:51:04.539] Timer tick, asking Buildbucket for the build status
[23:51:04.956] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:04.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:51:0) after 1m0s
[23:52:04.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:51:0)
[23:52:04.986] Timer tick, asking Buildbucket for the build status
[23:52:05.067] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:05.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:52:0) after 1m0s
[23:53:05.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:52:0)
[23:53:05.117] Timer tick, asking Buildbucket for the build status
[23:53:05.207] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:05.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:53:0) after 1m0s
[23:54:05.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:53:0)
[23:54:05.287] Timer tick, asking Buildbucket for the build status
[23:54:05.369] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:05.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:54:0) after 1m0s
[23:55:05.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:54:0)
[23:55:05.397] Timer tick, asking Buildbucket for the build status
[23:55:05.554] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:05.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:55:0) after 1m0s
[23:56:05.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:55:0)
[23:56:05.607] Timer tick, asking Buildbucket for the build status
[23:56:05.905] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:05.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:56:0) after 1m0s
[23:57:05.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:56:0)
[23:57:05.923] Timer tick, asking Buildbucket for the build status
[23:57:06.004] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:06.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:57:0) after 1m0s
[23:58:06.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:57:0)
[23:58:06.039] Timer tick, asking Buildbucket for the build status
[23:58:06.141] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:06.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:58:0) after 1m0s
[23:59:06.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:58:0)
[23:59:06.188] Timer tick, asking Buildbucket for the build status
[23:59:06.350] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:06.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:59:0) after 1m0s
[00:00:06.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:59:0)
[00:00:06.385] Timer tick, asking Buildbucket for the build status
[00:00:06.511] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:06.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:60:0) after 1m0s
[00:01:06.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:60:0)
[00:01:06.738] Timer tick, asking Buildbucket for the build status
[00:01:06.907] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:06.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:61:0) after 1m0s
[00:02:06.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:61:0)
[00:02:06.926] Timer tick, asking Buildbucket for the build status
[00:02:07.031] Build 8854736574931715472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:07.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020166637090043584:62:0) after 1m0s
[00:02:42.185] Received PubSub notification, asking Buildbucket for the build status
[00:02:42.641] Build 8854736574931715472: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:02:42.641] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1613865761034155",
"created_by": "project:chromium",
"created_ts": "1613862172152849",
"id": "8854736574931715472",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"029c8502901c3cf068e43f02e1189b7d62b4c725\"}}",
"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\": \"029c8502901c3cf068e43f02e1189b7d62b4c725\", \"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-us-central1-b-32-1-r8z7\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17450, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"5c931b61258f96fb1956cd8adc9f5b7d982e8c63\", \"got_dawn_revision\": \"1258e245a978083cab0b707c84dea814eadb3fbb\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"029c8502901c3cf068e43f02e1189b7d62b4c725\", \"got_revision_cp\": \"refs/heads/master@{#856119}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"d85a7a27b27269741a779a72f33436b83d98687c\", \"got_v8_revision\": \"2430d8fffa7d4f8a0992bcb5eb3725e5367cd9ac\", \"got_v8_revision_cp\": \"refs/heads/9.0.229@{#1}\", \"got_webrtc_revision\": \"753c76a705199ea752933f3688a8e4c8233dca53\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33298}\", \"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\": \"029c8502901c3cf068e43f02e1189b7d62b4c725\", \"swarm_hashes_refs/heads/master(at){#856119}_without_patch\": {\"absl_hardening_tests\": \"e04f1674c79d473a3e2ec7f43b98ab10afe8a948\", \"accessibility_unittests\": \"aaaf397e8d81bd6b13d9e86eafc924e1179307dd\", \"angle_unittests\": \"2d5a51961e4342bde0ff3d7022906e7df52341ed\", \"app_shell_unittests\": \"60be38753c9fc113ae33e386e55cbf602e7fb562\", \"aura_unittests\": \"7564fb9708c130020cc3d652e92d5e216c74567d\", \"base_unittests\": \"1ceb22df0f743b895465b4bef1cb2600b451aa3c\", \"base_util_unittests\": \"4a24ae9205265c61bfb1a9220c14f27d1b9d590c\", \"blink_common_unittests\": \"dfd94adb53125e91e9aa75249e2358eef8345743\", \"blink_fuzzer_unittests\": \"17c310a329ba8607d16a7453ff96a6f2c58a9cca\", \"blink_heap_unittests\": \"00c2634fa28dd0ce07c917053cd98e139b809e65\", \"blink_platform_unittests\": \"bc026a0bcd7ac62f8a4c0a6463323e42c8154d3f\", \"blink_unittests\": \"72a0dc110890e29890457802a091a4478f04ad06\", \"boringssl_crypto_tests\": \"2b0ecd2bff56d19ca45e48454a413c50a1b09777\", \"boringssl_ssl_tests\": \"8b3a5f3ddcb4b2e816e8227b339488d56dfa6f56\", \"browser_switcher_bho_unittests\": \"1211ef2ee8fb1f6eef648516423e73cdf0d102f9\", \"browser_tests\": \"1592d4b0d23e010b3c8bd21f0f06e7649d18a44b\", \"capture_unittests\": \"c62f19f06e8f805b859cecf0d9ec8ac32cbd8759\", \"cast_unittests\": \"597634597be2e04388e53eea57facf8e2d3377fd\", \"cc_unittests\": \"e808945780f327993faaa247b50b300c4dd07fd3\", \"chrome_app_unittests\": \"099e68881628e07ffad2a1f3798b23345415d17b\", \"chrome_cleaner_unittests\": \"8a9f2f7b2224f3a633a57b7e8378596cdfecc0bd\", \"chrome_elf_unittests\": \"579a93577539752f9a73592a1e5c1aed7813fbfd\", \"chromedriver_unittests\": \"d6a3061473ff70ca0efb73961d172c15cd4077c6\", \"components_browsertests\": \"6005372c122e43e5d7479d961d2d6d4794f41c08\", \"components_unittests\": \"8e6b5f737031d14081f482438e58009fd44228ef\", \"compositor_unittests\": \"d593b31a31328fc4494f8d24b6156c6ecf5c2b45\", \"content_browsertests\": \"bdd637255afec297eb945788ed09b5e4c2bfb924\", \"content_unittests\": \"3b05348df9fda5375171ab4933712f2143fd6017\", \"courgette_unittests\": \"3a8374677f99fe0e5c5776e38619bfada7d55a1d\", \"crashpad_tests\": \"7404d2fd388f46939e5fb883f9612ee165874e0f\", \"cronet_tests\": \"f026b81c09e147eafbf2bf4754788e0fb30f46d7\", \"cronet_unittests\": \"87a32b0e6371d65958025e887cfc647480c8fb78\", \"crypto_unittests\": \"bb7ab6223d44490aed5b625810f3bb94c6364428\", \"delayloads_unittests\": \"c949bdb865627fc8ff8a79d7259ec3275e03733f\", \"device_unittests\": \"fdc1c1a1a116b0e8a57ad50483f8b1a311b81cb0\", \"display_unittests\": \"b12e9f65f970d5b728d54d426635d444bae72692\", \"elevation_service_unittests\": \"7dcea3bff2f705b96d66f47dd21feaa3099d0ec6\", \"events_unittests\": \"4211b9c1f8a700d0db8a36ecd089105ec1702d6d\", \"extensions_browsertests\": \"52ef01ae5faac2e3e782fde4d8498cce207c1744\", \"extensions_unittests\": \"1455909fb4f5ec594fa46602e3b26ad3ab39643d\", \"filesystem_service_unittests\": \"894e8d9357c459cbee6bb5caa66fe77b9dcf7971\", \"gcm_unit_tests\": \"61f378f481fabc8392209e8d6e4a8abbbd7905d0\", \"gcp_unittests\": \"ab56d9ab3dc204f4b6e7d15b0be495aa39829cae\", \"gfx_unittests\": \"5592cc1deaaa6c171c909372a7a851524fcb3266\", \"gin_unittests\": \"4870a5d97c4ee8fa44241007482db1c691266529\", \"google_apis_unittests\": \"b27648f0244a23ad73845d21ad68e6d8079298f9\", \"gpu_unittests\": \"ca85d3e39b485230c1b8e6cd731cf04927bb2cf9\", \"gwp_asan_unittests\": \"c00f048445175ea5a75d5a5c9d055469a4f4a0e0\", \"headless_browsertests\": \"67451cf3065ad666a743a65a82c76d950b8ed0b2\", \"headless_unittests\": \"f817bc12954fb4d652121ad31ed822555e8e3f37\", \"install_static_unittests\": \"8202ce095aef2ad17f9d4fb46b5808e883f0276e\", \"installer_util_unittests\": \"dc0570f498a940c4349dbe3d03ad7465cd879dad\", \"interactive_ui_tests\": \"89c8f2682f62fa1985c2af2f65dae45dbffee5c3\", \"ipc_tests\": \"3d021fd03e790946e8fb17d066ac2cf55385e4cd\", \"jingle_unittests\": \"5e0efaab74b5a2dee3a9de9d2ef27554ba50e881\", \"latency_unittests\": \"ea2685b6f4604621d06d795d51c6b20e250a0671\", \"libjingle_xmpp_unittests\": \"bf0b14490060fd4f08c16b4e5b8ab594c84e0ecd\", \"liburlpattern_unittests\": \"c0d6dbc61365d645cf1f0d3e3bac5a1d4c05a510\", \"media_blink_unittests\": \"ac93d2fbbec7675277c15463e491090bf4004e29\", \"media_unittests\": \"26c27b3281db761fca20db4bb07eb492b3dda567\", \"message_center_unittests\": \"8e9ec1ff779d0a2ac25ce64e47026dd0edcb7d3c\", \"midi_unittests\": \"98373d8b72aae903046e691418cfa863230caa2d\", \"mojo_core_unittests\": \"f06ee34a8e63aa33bd666172a5eeb4cbdae01946\", \"mojo_unittests\": \"cad5a2bbd2d032a6c73734e5960d8b2550663121\", \"nacl_loader_unittests\": \"03cdd920dd034e99b05534b99f7d4444110ea1fd\", \"native_theme_unittests\": \"af7fdd6abafbb4e4040b361e96d9fda89ec3fc7f\", \"net_unittests\": \"429084f3544828d66d866249b464b62349f1fa37\", \"notification_helper_unittests\": \"0515c6357853d44c683df0549461d78136742d20\", \"pdf_unittests\": \"894a11190b0c241bcbb06279bb726fba80dabb50\", \"perfetto_unittests\": \"b29ca25b2015564b48607123201865eb7a0deb08\", \"ppapi_unittests\": \"f21966006c5f5cdb704b764a6a2cc69259a1cb25\", \"printing_unittests\": \"79c37c86fae63f72592a666898407d932241b78e\", \"remoting_unittests\": \"427bbc01efd0b4a1733ee416bc27f423057140c7\", \"sbox_integration_tests\": \"3995e50329370ea0713d1b92ee85bf47a43cc2fe\", \"sbox_unittests\": \"4f22d4da6535f959b0d550c8cf0b5aabb427af7f\", \"sbox_validation_tests\": \"c5e991b12786e38905ad66add06f53bdbb7abee0\", \"service_manager_unittests\": \"8c64820cc57c54cb99fdca744583646bd1bb8ce7\", \"services_unittests\": \"664da45b1e0ec1ddbfc0c54881b77af2df811061\", \"setup_unittests\": \"8ffe48bee602be3473209f2820ce06a1599eb0a7\", \"shell_dialogs_unittests\": \"50d050f14af2250fa08df760adff42dee6672d6a\", \"skia_unittests\": \"090e384862540cc73c663153aee0b469cc37b322\", \"snapshot_unittests\": \"6fbdb76f0d8533a2fca721dae43b735cadd2e066\", \"sql_unittests\": \"5b323fa2f39d43d5c9dc9b266449c526ff2bf153\", \"storage_unittests\": \"eb9ec6365b431504da2e6d1bdb17f94ace4d2afc\", \"sync_integration_tests\": \"7c7a7d03ef83e42838fefee0f78f001b9379bb63\", \"traffic_annotation_auditor_unittests\": \"774059a4d1525c99a60dd3eee241c4046b561136\", \"ui_base_unittests\": \"50ab1cc4a272146f5ce64235b911d90693a656b0\", \"ui_touch_selection_unittests\": \"794db4325cf52a907dc6eb838e2c4f446431dee6\", \"unit_tests\": \"a478db0e1f55be8be76691433f4e2099b620d7d9\", \"updater_tests\": \"7ebceeca4198b496568dbc193def5c9ef849321d\", \"url_unittests\": \"9e21669aae25157e821cf8e8da3282e69e2d943c\", \"views_unittests\": \"383ebb9af2338304c16a9e2d8b2d0ac25843cb89\", \"viz_unittests\": \"15748fe458aa50c3d4a72ebcdd59ed8b781673ee\", \"vr_common_unittests\": \"c648e4eaf4cce6fc4b958de0ae9080089b25c1bd\", \"vr_pixeltests\": \"435ffb83cd1c1447635ecc883ca310dbb5667fff\", \"weblayer_browsertests\": \"009b50c231ce92bd4001f4859deda381e72c7679\", \"weblayer_unittests\": \"0ffde135dbffe97d202fd7ac32e5765c63aa00e1\", \"wm_unittests\": \"941f5a9b937f21336e574bf056b429c2a65572e4\", \"wtf_unittests\": \"8d6140a73f6eeba5ea5da2f4c136a820c346756c\", \"zlib_unittests\": \"d17ddf218a891d025d74dea3cd99a9a800ef8238\", \"zucchini_unittests\": \"9907891d20b99c8b09101c204aa768f0902fbb25\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-r8z7\"], \"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\": [\"5706-397711c\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613862175553946",
"status": "COMPLETED",
"status_changed_ts": "1613865761581088",
"tags": [
"build_address:luci.chromium.ci/win-asan/17450",
"builder:win-asan",
"buildset:commit/git/029c8502901c3cf068e43f02e1189b7d62b4c725",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/029c8502901c3cf068e43f02e1189b7d62b4c725",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020166637090043584",
"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/8854736574931715472/+/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:51da80027635c710",
"user_agent:luci-scheduler"
],
"updated_ts": "1613865761585899",
"url": "https://ci.chromium.org/b/8854736574931715472",
"utcnow_ts": "1613865762613974"
}
}
[00:02:42.641] Invocation finished in 59m51.931600691s with status SUCCEEDED