[14:26:51.092] New invocation is queued and will start shortly
[14:26:52.543] Starting the invocation (attempt 1)
[14:26:52.593] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:26:52.593] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:26:52.593] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030074169963048720",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"bf6c0f61d76868eeff2fefb0ca61a5104723fb0f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9030074169963048720",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/bf6c0f61d76868eeff2fefb0ca61a5104723fb0f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bf6c0f61d76868eeff2fefb0ca61a5104723fb0f",
"gitiles_ref:refs/heads/master"
]
}
[14:26:53.325] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604413612695419",
"id": "8864644107612739488",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bf6c0f61d76868eeff2fefb0ca61a5104723fb0f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604413613169827",
"tags": [
"build_address:luci.chromium.ci/win-asan/15435",
"builder:win-asan",
"buildset:commit/git/bf6c0f61d76868eeff2fefb0ca61a5104723fb0f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bf6c0f61d76868eeff2fefb0ca61a5104723fb0f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030074169963048720",
"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/8864644107612739488/+/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": "1604413613169939",
"url": "https://ci.chromium.org/b/8864644107612739488",
"utcnow_ts": "1604413613313268"
}
}
[14:26:53.325] Task URL: https://ci.chromium.org/b/8864644107612739488
[14:26:53.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:2:0) after 1m0s
[14:26:59.305] Received PubSub notification, asking Buildbucket for the build status
[14:26:59.491] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:53.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:2:0)
[14:27:53.340] Timer tick, asking Buildbucket for the build status
[14:27:53.610] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:53.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:4:0) after 1m0s
[14:28:53.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:4:0)
[14:28:53.711] Timer tick, asking Buildbucket for the build status
[14:28:53.830] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:53.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:5:0) after 1m0s
[14:29:53.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:5:0)
[14:29:53.846] Timer tick, asking Buildbucket for the build status
[14:29:54.049] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:54.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:6:0) after 1m0s
[14:30:54.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:6:0)
[14:30:54.119] Timer tick, asking Buildbucket for the build status
[14:30:54.366] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:54.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:7:0) after 1m0s
[14:31:54.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:7:0)
[14:31:54.495] Timer tick, asking Buildbucket for the build status
[14:31:54.647] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:54.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:8:0) after 1m0s
[14:32:54.784] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:8:0)
[14:32:54.784] Timer tick, asking Buildbucket for the build status
[14:32:54.869] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:54.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:9:0) after 1m0s
[14:33:54.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:9:0)
[14:33:54.930] Timer tick, asking Buildbucket for the build status
[14:33:55.158] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:55.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:10:0) after 1m0s
[14:34:55.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:10:0)
[14:34:55.190] Timer tick, asking Buildbucket for the build status
[14:34:55.581] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:55.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:11:0) after 1m0s
[14:35:55.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:11:0)
[14:35:55.668] Timer tick, asking Buildbucket for the build status
[14:35:55.761] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:55.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:12:0) after 1m0s
[14:36:55.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:12:0)
[14:36:55.824] Timer tick, asking Buildbucket for the build status
[14:36:56.600] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:56.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:13:0) after 1m0s
[14:37:56.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:13:0)
[14:37:56.804] Timer tick, asking Buildbucket for the build status
[14:37:56.876] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:56.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:14:0) after 1m0s
[14:38:56.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:14:0)
[14:38:56.912] Timer tick, asking Buildbucket for the build status
[14:38:57.035] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:57.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:15:0) after 1m0s
[14:39:57.103] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:15:0)
[14:39:57.103] Timer tick, asking Buildbucket for the build status
[14:39:57.337] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:57.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:16:0) after 1m0s
[14:40:57.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:16:0)
[14:40:57.388] Timer tick, asking Buildbucket for the build status
[14:40:57.508] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:57.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:17:0) after 1m0s
[14:41:57.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:17:0)
[14:41:57.631] Timer tick, asking Buildbucket for the build status
[14:41:57.844] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:57.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:18:0) after 1m0s
[14:42:57.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:18:0)
[14:42:57.865] Timer tick, asking Buildbucket for the build status
[14:42:57.969] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:57.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:19:0) after 1m0s
[14:43:57.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:19:0)
[14:43:57.988] Timer tick, asking Buildbucket for the build status
[14:43:58.132] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:58.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:20:0) after 1m0s
[14:44:58.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:20:0)
[14:44:58.186] Timer tick, asking Buildbucket for the build status
[14:44:58.293] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:58.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:21:0) after 1m0s
[14:45:58.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:21:0)
[14:45:58.322] Timer tick, asking Buildbucket for the build status
[14:45:58.451] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:58.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:22:0) after 1m0s
[14:46:58.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:22:0)
[14:46:58.504] Timer tick, asking Buildbucket for the build status
[14:46:58.673] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:58.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:23:0) after 1m0s
[14:47:58.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:23:0)
[14:47:58.665] Timer tick, asking Buildbucket for the build status
[14:47:58.931] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:58.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:24:0) after 1m0s
[14:48:58.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:24:0)
[14:48:58.971] Timer tick, asking Buildbucket for the build status
[14:48:59.141] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:59.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:25:0) after 1m0s
[14:49:59.199] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:25:0)
[14:49:59.199] Timer tick, asking Buildbucket for the build status
[14:49:59.354] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:59.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:26:0) after 1m0s
[14:50:59.417] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:26:0)
[14:50:59.417] Timer tick, asking Buildbucket for the build status
[14:50:59.571] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:59.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:27:0) after 1m0s
[14:51:59.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:27:0)
[14:51:59.636] Timer tick, asking Buildbucket for the build status
[14:52:00.208] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:00.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:28:0) after 1m0s
[14:53:00.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:28:0)
[14:53:00.233] Timer tick, asking Buildbucket for the build status
[14:53:00.374] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:00.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:29:0) after 1m0s
[14:54:00.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:29:0)
[14:54:00.395] Timer tick, asking Buildbucket for the build status
[14:54:00.572] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:00.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:30:0) after 1m0s
[14:55:00.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:30:0)
[14:55:00.622] Timer tick, asking Buildbucket for the build status
[14:55:01.574] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:01.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:31:0) after 1m0s
[14:56:01.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:31:0)
[14:56:01.601] Timer tick, asking Buildbucket for the build status
[14:56:02.103] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:02.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:32:0) after 1m0s
[14:57:02.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:32:0)
[14:57:02.151] Timer tick, asking Buildbucket for the build status
[14:57:02.618] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:02.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:33:0) after 1m0s
[14:58:02.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:33:0)
[14:58:02.643] Timer tick, asking Buildbucket for the build status
[14:58:02.802] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:02.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:34:0) after 1m0s
[14:59:02.858] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:34:0)
[14:59:02.858] Timer tick, asking Buildbucket for the build status
[14:59:03.046] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:03.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:35:0) after 1m0s
[15:00:03.184] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:35:0)
[15:00:03.184] Timer tick, asking Buildbucket for the build status
[15:00:03.607] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:03.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:36:0) after 1m0s
[15:01:03.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:36:0)
[15:01:03.621] Timer tick, asking Buildbucket for the build status
[15:01:03.711] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:03.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:37:0) after 1m0s
[15:02:03.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:37:0)
[15:02:03.742] Timer tick, asking Buildbucket for the build status
[15:02:03.955] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:03.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:38:0) after 1m0s
[15:03:03.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:38:0)
[15:03:03.958] Timer tick, asking Buildbucket for the build status
[15:03:04.109] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:04.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:39:0) after 1m0s
[15:04:04.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:39:0)
[15:04:04.186] Timer tick, asking Buildbucket for the build status
[15:04:04.415] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:04.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:40:0) after 1m0s
[15:05:04.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:40:0)
[15:05:04.504] Timer tick, asking Buildbucket for the build status
[15:05:04.649] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:04.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:41:0) after 1m0s
[15:06:04.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:41:0)
[15:06:04.680] Timer tick, asking Buildbucket for the build status
[15:06:05.279] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:05.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:42:0) after 1m0s
[15:07:05.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:42:0)
[15:07:05.314] Timer tick, asking Buildbucket for the build status
[15:07:05.567] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:05.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:43:0) after 1m0s
[15:08:05.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:43:0)
[15:08:05.619] Timer tick, asking Buildbucket for the build status
[15:08:05.760] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:05.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:44:0) after 1m0s
[15:09:05.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:44:0)
[15:09:05.765] Timer tick, asking Buildbucket for the build status
[15:09:06.008] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:06.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:45:0) after 1m0s
[15:10:06.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:45:0)
[15:10:06.018] Timer tick, asking Buildbucket for the build status
[15:10:06.168] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:06.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:46:0) after 1m0s
[15:11:06.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:46:0)
[15:11:06.222] Timer tick, asking Buildbucket for the build status
[15:11:06.309] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:06.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:47:0) after 1m0s
[15:12:06.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:47:0)
[15:12:06.378] Timer tick, asking Buildbucket for the build status
[15:12:06.538] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:06.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:48:0) after 1m0s
[15:13:06.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:48:0)
[15:13:06.566] Timer tick, asking Buildbucket for the build status
[15:13:06.745] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:06.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:49:0) after 1m0s
[15:14:06.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:49:0)
[15:14:06.785] Timer tick, asking Buildbucket for the build status
[15:14:07.185] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:07.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:50:0) after 1m0s
[15:15:07.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:50:0)
[15:15:07.241] Timer tick, asking Buildbucket for the build status
[15:15:07.563] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:07.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:51:0) after 1m0s
[15:16:07.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:51:0)
[15:16:07.595] Timer tick, asking Buildbucket for the build status
[15:16:07.745] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:07.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:52:0) after 1m0s
[15:17:07.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:52:0)
[15:17:07.782] Timer tick, asking Buildbucket for the build status
[15:17:07.951] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:07.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:53:0) after 1m0s
[15:18:07.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:53:0)
[15:18:07.979] Timer tick, asking Buildbucket for the build status
[15:18:09.482] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:09.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:54:0) after 1m0s
[15:19:09.473] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:54:0)
[15:19:09.473] Timer tick, asking Buildbucket for the build status
[15:19:09.943] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:09.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:55:0) after 1m0s
[15:20:10.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:55:0)
[15:20:10.042] Timer tick, asking Buildbucket for the build status
[15:20:10.224] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:10.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:56:0) after 1m0s
[15:21:10.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:56:0)
[15:21:10.257] Timer tick, asking Buildbucket for the build status
[15:21:10.998] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:10.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:57:0) after 1m0s
[15:22:11.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:57:0)
[15:22:11.092] Timer tick, asking Buildbucket for the build status
[15:22:11.500] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:11.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:58:0) after 1m0s
[15:23:11.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:58:0)
[15:23:11.618] Timer tick, asking Buildbucket for the build status
[15:23:11.841] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:11.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:59:0) after 1m0s
[15:24:11.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:59:0)
[15:24:11.959] Timer tick, asking Buildbucket for the build status
[15:24:12.114] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:12.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:60:0) after 1m0s
[15:25:12.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:60:0)
[15:25:12.223] Timer tick, asking Buildbucket for the build status
[15:25:12.408] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:12.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:61:0) after 1m0s
[15:26:12.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:61:0)
[15:26:12.461] Timer tick, asking Buildbucket for the build status
[15:26:12.589] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:12.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:62:0) after 1m0s
[15:27:12.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:62:0)
[15:27:12.635] Timer tick, asking Buildbucket for the build status
[15:27:13.021] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:13.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:63:0) after 1m0s
[15:28:13.114] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:63:0)
[15:28:13.114] Timer tick, asking Buildbucket for the build status
[15:28:13.241] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:13.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:64:0) after 1m0s
[15:29:13.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:64:0)
[15:29:13.292] Timer tick, asking Buildbucket for the build status
[15:29:13.549] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:13.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:65:0) after 1m0s
[15:30:13.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:65:0)
[15:30:13.573] Timer tick, asking Buildbucket for the build status
[15:30:13.727] Build 8864644107612739488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:13.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030074169963048720:66:0) after 1m0s
[15:30:15.211] Received PubSub notification, asking Buildbucket for the build status
[15:30:15.339] Build 8864644107612739488: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:30:15.339] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604417407757428",
"created_by": "project:chromium",
"created_ts": "1604413612695419",
"id": "8864644107612739488",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bf6c0f61d76868eeff2fefb0ca61a5104723fb0f\"}}",
"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\": \"bf6c0f61d76868eeff2fefb0ca61a5104723fb0f\", \"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-5-vrjs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15435, \"clang_revision\": \"llvmorg-12-init-5627-gf086e85e-3\", \"got_angle_revision\": \"ed916ddf61cc0021cebf55fb6901ba71fe9e6a2e\", \"got_dawn_revision\": \"43ef0a365b8443226916c090b88b3a0b09d6df96\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"bf6c0f61d76868eeff2fefb0ca61a5104723fb0f\", \"got_revision_cp\": \"refs/heads/master@{#823526}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"b5bf82693f85b53ce31846705d323764a582489b\", \"got_v8_revision\": \"33fdba02a56f94cd6e96efdcb4f8a01f670a2a79\", \"got_v8_revision_cp\": \"refs/heads/8.8.221@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bf6c0f61d76868eeff2fefb0ca61a5104723fb0f\", \"swarm_hashes_refs/heads/master(at){#823526}_without_patch\": {\"absl_hardening_tests\": \"0979e211dff75b2bedbff36038687ed6c868a44f\", \"accessibility_unittests\": \"e0d9718f767383b937fcc28ff0776ee0d5ef7f12\", \"angle_unittests\": \"4773d438f0ac2b7f11b3a820eec42f7dbb081b27\", \"app_shell_unittests\": \"e761534c865c8868d46c8a6cc05e4dd946ab3c5a\", \"aura_unittests\": \"ff7523d49e2e8ced14ab180d9f5ac41969e8b151\", \"base_unittests\": \"9527d32e23c99f5805e4427f36aa9e12d4d89dae\", \"base_util_unittests\": \"34d8cb3b0907c95c3ec16fa47e471275f38ec7eb\", \"blink_common_unittests\": \"fc7c14bc1a47c72a9cb09342a3340d6250e28de4\", \"blink_fuzzer_unittests\": \"271104edae73b131f6275dd76055fd48d2164001\", \"blink_heap_unittests\": \"24559399eb24f43605437b71671d85d4376aa2f5\", \"blink_platform_unittests\": \"ba5cd20e6bd0726e8a99f030994783a64fd8aec9\", \"blink_unittests\": \"2b7cacdae0fb2c89d124ea3075f7c2706955d599\", \"boringssl_crypto_tests\": \"4188a0deabd2561ec21e35ba72ce5417158ffeea\", \"boringssl_ssl_tests\": \"d220d7e48d053546c8098a82a6c677e97ebd13f9\", \"browser_switcher_bho_unittests\": \"204b9791280c4d4914a6ac8786aef4226cef7937\", \"browser_tests\": \"7b2a6648661eb48d88cccece5309ea01bccc7fd9\", \"capture_unittests\": \"453749850be9b09c373f5813372536e81b2f89a9\", \"cast_unittests\": \"0011f2c6038f0fd2754b1128b6ac128f62346f38\", \"cc_unittests\": \"861fd0521252ecaa8e684024208bd3b5921f3577\", \"chrome_app_unittests\": \"f718f84bc8484d62696df217dd2d982a9b8c82fd\", \"chrome_cleaner_unittests\": \"117752adf0943d7bcd8c7ddeb88e0add0839d216\", \"chrome_elf_unittests\": \"07bca23335a1aecca5e2bd8e2ab4ba6953763c59\", \"chromedriver_unittests\": \"9c54769fe052ff071e8fc41dcd601db6abcea249\", \"components_browsertests\": \"76ef1fe3ee288bd175ae55fb9d4e1c1ea385a12d\", \"components_unittests\": \"a9b386fbe14b83fd8e0ac34ba7b39a8af83cefae\", \"compositor_unittests\": \"6d5b1b6979c7065256ca0d5bb3545162e1fc6b5b\", \"content_browsertests\": \"801f3bac31b3cae60223691df166a90b9f32be34\", \"content_unittests\": \"55f88d389d17330ff04de23f4d829fbb0d6ba068\", \"courgette_unittests\": \"2eb97ce94f62c69ec62970508b0eab41e71fb5d0\", \"crashpad_tests\": \"ac1f05ace3e6cead49d9efeaa7a1702594e39a57\", \"cronet_tests\": \"6fe5c6216f70755f337cbb2528d44a871d0fa735\", \"cronet_unittests\": \"d0ff52fb100f7b0001d4e0fa1782e181d1bd04c5\", \"crypto_unittests\": \"93c3ecca9a65545b4986253cdde82af897ed6998\", \"delayloads_unittests\": \"aa64fd05270734946194ce7d3818ba170974bf0a\", \"device_unittests\": \"d6c5fe6c43e52c25d2969780fa8fa5ae94dd6efa\", \"display_unittests\": \"7e9733250e0903610e4d7f4b5d6d5eba835b56c1\", \"elevation_service_unittests\": \"c412b1717d24c147b6824de9a7d9031b669c4cc5\", \"events_unittests\": \"ad2941510c29e2c45c39f5688fa08226cc34cb36\", \"extensions_browsertests\": \"4ce92e479dfcfdbfa2fb323b6b84728b7e97bc7a\", \"extensions_unittests\": \"213e5d3ba10f4a725e770287972d3305fa888e33\", \"filesystem_service_unittests\": \"03e8c3596ba43f3fa7dec051cf1514262b3ca47a\", \"gcm_unit_tests\": \"1be1d1afdb1a8d0025b5b23e8b7fa71e11e3e947\", \"gcp_unittests\": \"619db1f7089cc2d83de12c33fd6507b8be611e1b\", \"gfx_unittests\": \"21c6df237a501a59746fbe5759853f891a4c8075\", \"gin_unittests\": \"7e0221cb040f16ef55dbc63497aa9c895f52da13\", \"google_apis_unittests\": \"2d5899451e73e27c36b9110b20ee9c471b136179\", \"gpu_unittests\": \"bcb2e35b98965d785fd60dec69167fafe438247d\", \"gwp_asan_unittests\": \"0b5a3f75e6c689ab3cf054fb93b981bf7885d739\", \"headless_browsertests\": \"090cbe597fc3f448edf6809657175ffa11e6f37b\", \"headless_unittests\": \"3627c3714d50352ac75703e6437da63b45f7dd4d\", \"install_static_unittests\": \"29ec5a806c693e9d6d8738a84f7bd9d3100752c1\", \"installer_util_unittests\": \"fffad232fc668744b7c75e9b406cc711b2983c34\", \"interactive_ui_tests\": \"d43bdc3730a932874c1a9c813148666190973fbf\", \"ipc_tests\": \"29d91483963d0c303bc27a99fdf4bf5953aafca6\", \"jingle_unittests\": \"06aaa8603b1b79387b37d47cecd26fab699d177a\", \"latency_unittests\": \"ca45fd33a16725bd70c7adf6847440fb5838d3f1\", \"libjingle_xmpp_unittests\": \"a932424e1805bb50a6885586c2eee7e35092a869\", \"liburlpattern_unittests\": \"fbe139c3e4d0a82a33b09f020496b9bfbf60802a\", \"media_blink_unittests\": \"ff7fc5e6a4382fac8ebb2e39bb5a33457f4286d9\", \"media_unittests\": \"b79b532e49cc38d3c7f614e3994ab31497a24381\", \"message_center_unittests\": \"339e9034e5b9a94024e533aedb10cc0840d4599c\", \"midi_unittests\": \"40d8deb0a61f84d9fe6e2d5444d6f09981cc33a1\", \"mojo_core_unittests\": \"3051f29f90dd6d75c2e1cc9ca7638f4eb89582bd\", \"mojo_unittests\": \"d99cca28d366a3c35d7e4f7ac11bf86c0e2d33bc\", \"nacl_loader_unittests\": \"5a040772ed518923ce28b6a6841a9dd5af6c8eaa\", \"native_theme_unittests\": \"f3c1c3d6936cec54920127a42fcb80809fe5bcb5\", \"net_unittests\": \"719d4d106d4abab9fbb5dcfa3436953825ae5dfa\", \"notification_helper_unittests\": \"fd9241e9ffc4d347bdd166a14405893bcc60bada\", \"pdf_unittests\": \"d1199a4a696979a74328d677a575824e1d778a31\", \"perfetto_unittests\": \"1824cd7a0b718afc2243dc065ad489f4e0077b7a\", \"ppapi_unittests\": \"810ef8e779a3a96628d56f967df7f7b439152b4a\", \"printing_unittests\": \"5181145d005ee46c10540d6ba6e75536ed6ee35c\", \"remoting_unittests\": \"f378f17794132c38ed1ad9cffcd8423718c01b44\", \"sbox_integration_tests\": \"a16fb5cc19cd926d95ace79c998e51876515c5f9\", \"sbox_unittests\": \"a21b4c8cb1c0eeb88ec2bbb1554908253865a84e\", \"sbox_validation_tests\": \"03888581661332c067847984d48458b77c6c5af5\", \"service_manager_unittests\": \"3439921202a2be99f00369b1bc12321bc49313ee\", \"services_unittests\": \"a7488101117423166475eaab332d481758cc5446\", \"setup_unittests\": \"d17b0f9b186ccb1833288af93614b06440344252\", \"shell_dialogs_unittests\": \"2e2680b28a85491bfc42507b9c8fc6cee609a65c\", \"skia_unittests\": \"57a0256daf39ac79a61febe573aec52365c8052a\", \"snapshot_unittests\": \"6703ab3bee938bf9d3422990580ee6cea99e7184\", \"sql_unittests\": \"945443c20014d036b56e82ee39b247f562a0ed03\", \"storage_unittests\": \"ef041cf879eab15860d9a59c3a286342658cd489\", \"sync_integration_tests\": \"fec6f07829b3b0ff6a72fbdc763f2578f4e52c15\", \"traffic_annotation_auditor_unittests\": \"dac6d230774a3192ffc9ce952358472e7fdee2d7\", \"ui_base_unittests\": \"77150ee21b05deeff4b31f58aea4b85f8019ffbf\", \"ui_touch_selection_unittests\": \"4fae7678ff825fa9b1104ab8b609747ec13d75e0\", \"unit_tests\": \"0eb8e621ae04d9af21933458b30c4ebe9f858cca\", \"updater_tests\": \"6a9e909130df658e00df83a7e89c711e33e71e9b\", \"url_unittests\": \"3beb4107f711afda3a475270bc917040754972e3\", \"views_unittests\": \"c0b89506a24385c75b2890c709bd88dfcc80d1e7\", \"viz_unittests\": \"cc79d7e6d3812adb3e1e3802586bca619c6f8906\", \"vr_common_unittests\": \"04eacab9ae5bc3bbb5214b2e1ec3407e1a3883f9\", \"vr_pixeltests\": \"3c3b734dd23461c865d3e94a01b9e106cde91bcd\", \"weblayer_browsertests\": \"24762cf8841de3d9542a9bbbc197b7f8ee369073\", \"weblayer_unittests\": \"07458cf885a050b2611dbc4699403459f1e8df66\", \"wm_unittests\": \"f8fc862e3f1e9d781322971ed194c677b9aacbca\", \"wtf_unittests\": \"7a30e30f2141b9c71b761509d83b406ead4cb9bb\", \"zlib_unittests\": \"e07821a9877f2b741464b9a344166e0e0b02ec24\", \"zucchini_unittests\": \"34a4a72b7a8fe827830b57d7cdb6715ee8240b02\"}}, \"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-5-vrjs\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5614-d862eaa\"], \"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": "1604413617432710",
"status": "COMPLETED",
"status_changed_ts": "1604417413954432",
"tags": [
"build_address:luci.chromium.ci/win-asan/15435",
"builder:win-asan",
"buildset:commit/git/bf6c0f61d76868eeff2fefb0ca61a5104723fb0f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bf6c0f61d76868eeff2fefb0ca61a5104723fb0f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030074169963048720",
"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/8864644107612739488/+/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:4fa7526e20a55110",
"user_agent:luci-scheduler"
],
"updated_ts": "1604417413972756",
"url": "https://ci.chromium.org/b/8864644107612739488",
"utcnow_ts": "1604417415326687"
}
}
[15:30:15.339] Invocation finished in 1h3m24.269168947s with status SUCCEEDED