[14:42:42.161] New invocation is queued and will start shortly
[14:42:43.265] Starting the invocation (attempt 1)
[14:42:43.300] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:42:43.300] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:42:43.300] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021557057845322288",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"50726aee165794312051c884efcb6ba52d140faf\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021557057845322288",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/50726aee165794312051c884efcb6ba52d140faf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/50726aee165794312051c884efcb6ba52d140faf",
"gitiles_ref:refs/heads/master"
]
}
[14:42:44.013] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612536163389207",
"id": "8856126995896638304",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"50726aee165794312051c884efcb6ba52d140faf\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612536163816312",
"tags": [
"build_address:luci.chromium.ci/win-asan/17181",
"builder:win-asan",
"buildset:commit/git/50726aee165794312051c884efcb6ba52d140faf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/50726aee165794312051c884efcb6ba52d140faf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021557057845322288",
"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/8856126995896638304/+/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": "1612536163816482",
"url": "https://ci.chromium.org/b/8856126995896638304",
"utcnow_ts": "1612536164002466"
}
}
[14:42:44.013] Task URL: https://ci.chromium.org/b/8856126995896638304
[14:42:44.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:2:0) after 1m0s
[14:42:47.302] Received PubSub notification, asking Buildbucket for the build status
[14:42:47.371] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:44.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:2:0)
[14:43:44.049] Timer tick, asking Buildbucket for the build status
[14:43:44.155] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:44.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:4:0) after 1m0s
[14:44:44.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:4:0)
[14:44:44.202] Timer tick, asking Buildbucket for the build status
[14:44:44.314] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:44.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:5:0) after 1m0s
[14:45:44.394] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:5:0)
[14:45:44.394] Timer tick, asking Buildbucket for the build status
[14:45:44.504] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:44.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:6:0) after 1m0s
[14:46:44.563] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:6:0)
[14:46:44.563] Timer tick, asking Buildbucket for the build status
[14:46:44.683] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:44.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:7:0) after 1m0s
[14:47:44.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:7:0)
[14:47:44.726] Timer tick, asking Buildbucket for the build status
[14:47:45.031] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:45.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:8:0) after 1m0s
[14:48:45.099] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:8:0)
[14:48:45.099] Timer tick, asking Buildbucket for the build status
[14:48:45.407] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:45.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:9:0) after 1m0s
[14:49:45.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:9:0)
[14:49:45.544] Timer tick, asking Buildbucket for the build status
[14:49:45.809] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:45.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:10:0) after 1m0s
[14:50:45.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:10:0)
[14:50:45.822] Timer tick, asking Buildbucket for the build status
[14:50:45.882] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:45.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:11:0) after 1m0s
[14:51:45.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:11:0)
[14:51:45.911] Timer tick, asking Buildbucket for the build status
[14:51:46.497] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:46.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:12:0) after 1m0s
[14:52:46.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:12:0)
[14:52:46.515] Timer tick, asking Buildbucket for the build status
[14:52:46.648] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:46.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:13:0) after 1m0s
[14:53:46.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:13:0)
[14:53:46.747] Timer tick, asking Buildbucket for the build status
[14:53:46.816] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:46.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:14:0) after 1m0s
[14:54:46.842] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:14:0)
[14:54:46.842] Timer tick, asking Buildbucket for the build status
[14:54:46.915] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:46.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:15:0) after 1m0s
[14:55:46.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:15:0)
[14:55:46.972] Timer tick, asking Buildbucket for the build status
[14:55:47.369] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:47.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:16:0) after 1m0s
[14:56:47.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:16:0)
[14:56:47.526] Timer tick, asking Buildbucket for the build status
[14:56:47.614] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:47.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:17:0) after 1m0s
[14:57:47.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:17:0)
[14:57:47.674] Timer tick, asking Buildbucket for the build status
[14:57:48.305] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:48.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:18:0) after 1m0s
[14:58:48.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:18:0)
[14:58:48.435] Timer tick, asking Buildbucket for the build status
[14:58:48.539] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:48.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:19:0) after 1m0s
[14:59:48.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:19:0)
[14:59:48.612] Timer tick, asking Buildbucket for the build status
[14:59:48.809] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:48.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:20:0) after 1m0s
[15:00:48.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:20:0)
[15:00:48.894] Timer tick, asking Buildbucket for the build status
[15:00:49.034] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:49.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:21:0) after 1m0s
[15:01:49.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:21:0)
[15:01:49.111] Timer tick, asking Buildbucket for the build status
[15:01:49.330] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:49.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:22:0) after 1m0s
[15:02:49.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:22:0)
[15:02:49.382] Timer tick, asking Buildbucket for the build status
[15:02:49.521] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:49.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:23:0) after 1m0s
[15:03:49.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:23:0)
[15:03:49.582] Timer tick, asking Buildbucket for the build status
[15:03:49.693] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:49.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:24:0) after 1m0s
[15:04:49.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:24:0)
[15:04:49.714] Timer tick, asking Buildbucket for the build status
[15:04:49.859] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:49.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:25:0) after 1m0s
[15:05:49.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:25:0)
[15:05:49.861] Timer tick, asking Buildbucket for the build status
[15:05:50.008] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:50.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:26:0) after 1m0s
[15:06:50.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:26:0)
[15:06:50.049] Timer tick, asking Buildbucket for the build status
[15:06:50.171] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:50.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:27:0) after 1m0s
[15:07:50.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:27:0)
[15:07:50.193] Timer tick, asking Buildbucket for the build status
[15:07:50.422] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:50.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:28:0) after 1m0s
[15:08:50.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:28:0)
[15:08:50.414] Timer tick, asking Buildbucket for the build status
[15:08:50.507] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:50.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:29:0) after 1m0s
[15:09:50.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:29:0)
[15:09:50.573] Timer tick, asking Buildbucket for the build status
[15:09:50.805] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:50.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:30:0) after 1m0s
[15:10:50.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:30:0)
[15:10:50.944] Timer tick, asking Buildbucket for the build status
[15:10:51.113] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:51.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:31:0) after 1m0s
[15:11:51.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:31:0)
[15:11:51.183] Timer tick, asking Buildbucket for the build status
[15:11:51.305] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:51.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:32:0) after 1m0s
[15:12:51.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:32:0)
[15:12:51.354] Timer tick, asking Buildbucket for the build status
[15:12:51.506] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:51.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:33:0) after 1m0s
[15:13:51.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:33:0)
[15:13:51.529] Timer tick, asking Buildbucket for the build status
[15:13:51.682] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:51.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:34:0) after 1m0s
[15:14:51.698] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:34:0)
[15:14:51.698] Timer tick, asking Buildbucket for the build status
[15:14:52.443] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:52.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:35:0) after 1m0s
[15:15:52.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:35:0)
[15:15:52.484] Timer tick, asking Buildbucket for the build status
[15:15:52.617] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:52.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:36:0) after 1m0s
[15:16:52.640] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:36:0)
[15:16:52.640] Timer tick, asking Buildbucket for the build status
[15:16:52.707] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:52.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:37:0) after 1m0s
[15:17:52.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:37:0)
[15:17:52.728] Timer tick, asking Buildbucket for the build status
[15:17:53.072] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:53.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:38:0) after 1m0s
[15:18:53.093] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:38:0)
[15:18:53.093] Timer tick, asking Buildbucket for the build status
[15:18:53.393] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:53.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:39:0) after 1m0s
[15:19:53.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:39:0)
[15:19:53.404] Timer tick, asking Buildbucket for the build status
[15:19:53.838] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:53.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:40:0) after 1m0s
[15:20:53.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:40:0)
[15:20:53.856] Timer tick, asking Buildbucket for the build status
[15:20:54.485] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:54.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:41:0) after 1m0s
[15:21:54.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:41:0)
[15:21:54.513] Timer tick, asking Buildbucket for the build status
[15:21:54.644] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:54.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:42:0) after 1m0s
[15:22:54.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:42:0)
[15:22:54.623] Timer tick, asking Buildbucket for the build status
[15:22:54.734] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:54.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:43:0) after 1m0s
[15:23:54.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:43:0)
[15:23:54.756] Timer tick, asking Buildbucket for the build status
[15:23:54.828] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:54.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:44:0) after 1m0s
[15:24:54.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:44:0)
[15:24:54.843] Timer tick, asking Buildbucket for the build status
[15:24:54.922] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:54.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:45:0) after 1m0s
[15:25:54.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:45:0)
[15:25:54.957] Timer tick, asking Buildbucket for the build status
[15:25:55.810] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:55.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:46:0) after 1m0s
[15:26:55.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:46:0)
[15:26:55.825] Timer tick, asking Buildbucket for the build status
[15:26:55.932] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:55.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:47:0) after 1m0s
[15:27:56.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:47:0)
[15:27:56.027] Timer tick, asking Buildbucket for the build status
[15:27:56.593] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:56.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:48:0) after 1m0s
[15:28:56.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:48:0)
[15:28:56.614] Timer tick, asking Buildbucket for the build status
[15:28:56.776] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:56.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:49:0) after 1m0s
[15:29:56.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:49:0)
[15:29:56.848] Timer tick, asking Buildbucket for the build status
[15:29:56.913] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:56.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:50:0) after 1m0s
[15:30:57.083] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:50:0)
[15:30:57.083] Timer tick, asking Buildbucket for the build status
[15:30:57.196] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:57.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:51:0) after 1m0s
[15:31:57.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:51:0)
[15:31:57.194] Timer tick, asking Buildbucket for the build status
[15:31:57.313] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:57.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:52:0) after 1m0s
[15:32:57.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:52:0)
[15:32:57.354] Timer tick, asking Buildbucket for the build status
[15:32:57.475] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:57.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:53:0) after 1m0s
[15:33:57.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:53:0)
[15:33:57.566] Timer tick, asking Buildbucket for the build status
[15:33:57.639] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:57.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:54:0) after 1m0s
[15:34:57.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:54:0)
[15:34:57.648] Timer tick, asking Buildbucket for the build status
[15:34:57.747] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:57.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:55:0) after 1m0s
[15:35:57.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:55:0)
[15:35:57.780] Timer tick, asking Buildbucket for the build status
[15:35:58.393] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:58.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:56:0) after 1m0s
[15:36:58.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:56:0)
[15:36:58.439] Timer tick, asking Buildbucket for the build status
[15:36:58.510] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:58.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:57:0) after 1m0s
[15:37:58.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:57:0)
[15:37:58.572] Timer tick, asking Buildbucket for the build status
[15:37:58.681] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:58.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:58:0) after 1m0s
[15:38:58.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:58:0)
[15:38:58.701] Timer tick, asking Buildbucket for the build status
[15:38:58.887] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:58.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:59:0) after 1m0s
[15:39:58.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:59:0)
[15:39:58.903] Timer tick, asking Buildbucket for the build status
[15:39:59.412] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:59.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:60:0) after 1m0s
[15:40:59.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:60:0)
[15:40:59.438] Timer tick, asking Buildbucket for the build status
[15:40:59.694] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:59.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:61:0) after 1m0s
[15:41:59.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:61:0)
[15:41:59.706] Timer tick, asking Buildbucket for the build status
[15:41:59.783] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:59.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:62:0) after 1m0s
[15:42:59.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:62:0)
[15:42:59.899] Timer tick, asking Buildbucket for the build status
[15:43:00.282] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:00.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:63:0) after 1m0s
[15:44:00.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:63:0)
[15:44:00.688] Timer tick, asking Buildbucket for the build status
[15:44:00.818] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:00.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:64:0) after 1m0s
[15:45:01.161] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:64:0)
[15:45:01.161] Timer tick, asking Buildbucket for the build status
[15:45:01.445] Build 8856126995896638304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:01.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021557057845322288:65:0) after 1m0s
[15:45:02.553] Received PubSub notification, asking Buildbucket for the build status
[15:45:02.871] Build 8856126995896638304: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:45:02.871] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612539901360163",
"created_by": "project:chromium",
"created_ts": "1612536163389207",
"id": "8856126995896638304",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"50726aee165794312051c884efcb6ba52d140faf\"}}",
"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\": \"50726aee165794312051c884efcb6ba52d140faf\", \"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-0-be5b\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17181, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"82df803412eced8a2ec7e4ddc63570f64b7f35ac\", \"got_dawn_revision\": \"2b9707d3369addfef7f7f7aafa6416036e4aad52\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"50726aee165794312051c884efcb6ba52d140faf\", \"got_revision_cp\": \"refs/heads/master@{#851107}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"14dcbed947ce755af1fcbfb3ba8e643b4e769860\", \"got_v8_revision\": \"7a64550888c94b882033f89163526edc7ff4f863\", \"got_v8_revision_cp\": \"refs/heads/9.0.135@{#1}\", \"got_webrtc_revision\": \"1d71fd9c61db8f1dcf30b5d0625e514b91200926\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33155}\", \"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\": \"50726aee165794312051c884efcb6ba52d140faf\", \"swarm_hashes_refs/heads/master(at){#851107}_without_patch\": {\"absl_hardening_tests\": \"aad753e467464e4cbb4167d5ddbd33af9a596ffa\", \"accessibility_unittests\": \"a57da7357eeca4604241292b722a5b431f87e89a\", \"angle_unittests\": \"51a33e7211bf8acc6bbe4084bf365b8d0fb0d9ab\", \"app_shell_unittests\": \"8183af472cf0b09a8b1b8f7ff2439a23d5a3adf6\", \"aura_unittests\": \"c2d91183eb833a7654a20489b467e558543dbaaa\", \"base_unittests\": \"ec183eae61cfa2fde8ca0d19c169b5e294e658d3\", \"base_util_unittests\": \"be45a71b4475740afbaf157b84cd06177b7fcc56\", \"blink_common_unittests\": \"b82136c2bec1837e06b097ab7929c7bde7c56344\", \"blink_fuzzer_unittests\": \"905c05a311f851565c270647388f5c38c6c581db\", \"blink_heap_unittests\": \"b20ff254476de1c58aa805ee3373dfca5a2a2c8e\", \"blink_platform_unittests\": \"4b36f2647a2256427440736c0de7497aaca9a7a9\", \"blink_unittests\": \"b019fbc5e0661794b038ebb4cd54ed31fb4f8e3a\", \"boringssl_crypto_tests\": \"bdd426956f5a015fe891f1400ed1ce85e41a4bea\", \"boringssl_ssl_tests\": \"2d233be7065ce1289d8e61574153ec66681bf93a\", \"browser_switcher_bho_unittests\": \"0fc9d8fa3c47ebf0dc889b68c9403b1411f528d2\", \"browser_tests\": \"5b9267a10c9bc09f187c05e43cebff2b21729a99\", \"capture_unittests\": \"ec6e9369d3f94a8b727b2b09307a2462731dc3d4\", \"cast_unittests\": \"04605dc12b63a3d5c088003a6d3e853ba40a249b\", \"cc_unittests\": \"9a6b2df709137f0a596d8183d3592de13c804f9d\", \"chrome_app_unittests\": \"d0c3ddb4574a1313be858b2bdd42b34aeecf5788\", \"chrome_cleaner_unittests\": \"3fa9eda9a8289d3364bc80678ceebf8cfe13d5dd\", \"chrome_elf_unittests\": \"5057d1d26b32d3a85151439f343fe402c524ca5d\", \"chromedriver_unittests\": \"72072f78506ab834c6de8f5b103264915b3c75ea\", \"components_browsertests\": \"78c8cceef8611fe293f70d6ac9ca6cf16edbaa05\", \"components_unittests\": \"bc76b9cfd869efddad75a15a2fe80ba7544ffee0\", \"compositor_unittests\": \"9965e383cf02780f56f56822c0b9f513c31fd51a\", \"content_browsertests\": \"b68a4cd9bd7937fb417f37fef3460504fc9507d5\", \"content_unittests\": \"c3ed30bea2a035c3f56e6a13088286e85327cb67\", \"courgette_unittests\": \"fdd75350931f2f1276a02e94fd3f286a650e6d98\", \"crashpad_tests\": \"c70926bcd5a42c72e42b4ce652d2389212790344\", \"cronet_tests\": \"eca15dcf0015effe25c9d9637b31dc9fd55139eb\", \"cronet_unittests\": \"105d22c66df352394698af0d23281a691c2e9c6f\", \"crypto_unittests\": \"04ec92b616a3f0e9a31a9725e3a08dd6223cb10f\", \"delayloads_unittests\": \"5f17aa3266b9821f46bf14245cc30aece5c78256\", \"device_unittests\": \"d61d2307408b97731f9595d87dcb4c8835876889\", \"display_unittests\": \"289e38748ad57fec800b8e6a6893ae35e621bcb2\", \"elevation_service_unittests\": \"e4b18edf88d7dd7a15b0bdd86abdde123ccffe6a\", \"events_unittests\": \"fcf7d25ce1cc6ed6422b57bddcaf82334a0d38e2\", \"extensions_browsertests\": \"f661ca6c6a27ed09e9bd2ad390448993f62d0e18\", \"extensions_unittests\": \"4c1fcdea3e441cb14099d38c08a36aab0b9aa372\", \"filesystem_service_unittests\": \"caa2cbd62292e39b761c113216fb881258bdaf7c\", \"gcm_unit_tests\": \"1413ee7665ba4c44caff16d9c5d5e89b1554ad71\", \"gcp_unittests\": \"06b91414839b52728e157460981c89cdf495aa15\", \"gfx_unittests\": \"8f32fb785e20def3b9184d149dde749664893900\", \"gin_unittests\": \"a3aee4f7b608216e8f4731139a3dc9a03275a6ee\", \"google_apis_unittests\": \"e4b71d5a03d5904cd009ee7cb2d241a7a10f7988\", \"gpu_unittests\": \"41c0569343f7a364cf8ce88671bf3683068c9682\", \"gwp_asan_unittests\": \"eaf0242c811eafb5b67835314e5afa13ec5afe52\", \"headless_browsertests\": \"572f8ff97be2a8fa9f41c3e00746edd9cdd7423c\", \"headless_unittests\": \"43a7d0329f5c0e48fa2b571bde2750085aec296c\", \"install_static_unittests\": \"fdd4fda4d69ade1abfcb0ca9d28d7530d21e52f3\", \"installer_util_unittests\": \"9b65639ba75dd40c03ae9cc3173d664828eb5c2d\", \"interactive_ui_tests\": \"351b399d885c0f803c5d271be03c5e3543f4e319\", \"ipc_tests\": \"c252c4c1d46426dd22c7548fabdf3fd4815ddbda\", \"jingle_unittests\": \"2d3e3ab0155262c496b019c496667976e1f01a57\", \"latency_unittests\": \"8157ca591c0c121ebf9c7b048093f23287962116\", \"libjingle_xmpp_unittests\": \"828832b19e6caf1c3da005d9bf0e74ee01ad30e5\", \"liburlpattern_unittests\": \"a88e6ed4555c7ae40da9dc8c3e222b46dcb3ea4a\", \"media_blink_unittests\": \"ecedd19db6e3ebf0891dee201220d9dde562717c\", \"media_unittests\": \"546c893686d765eb151cedb7ed6c6e29c0263c9b\", \"message_center_unittests\": \"97253c553ef2676564d1e63c328d93a64f1df469\", \"midi_unittests\": \"febf39f4db2541f87b41d2679db86105f8c39d60\", \"mojo_core_unittests\": \"51884abcca801d2248eb7d3a047f9773e1410020\", \"mojo_unittests\": \"ae3c4e5f60009475e7a830a75a969d5322d49146\", \"nacl_loader_unittests\": \"d187af95dfbc9c95f6b19f35adca6939f2d34a9d\", \"native_theme_unittests\": \"205af29511dbcfab05f56383bad69a4650542a56\", \"net_unittests\": \"65b007d4f8548547e2cc7931863a9c233db20dc1\", \"notification_helper_unittests\": \"474ed201ff14d5ca818c3b22cfdb04d4d774119c\", \"pdf_unittests\": \"2cd0fca617755ab8635078fea40dc65d458cfa26\", \"perfetto_unittests\": \"06c3fbeb4e5d8f2b4bf6c80880a9b400c0080166\", \"ppapi_unittests\": \"d7295db598b3a6bd8e92543ad5912da82ee3c90c\", \"printing_unittests\": \"6467c2828726494f6b068ff679d4828d424afa98\", \"remoting_unittests\": \"ccc36a6ab5743ac1f3040f19aab32060867aed99\", \"sbox_integration_tests\": \"b0bb3278f2272757af1ece83dfddf30773b9a8cf\", \"sbox_unittests\": \"f1f1183c523473235d0c5da3f8188c9c08fac18b\", \"sbox_validation_tests\": \"201f4a10b1b20806fb991800f797aa9ef591d13d\", \"service_manager_unittests\": \"f0469c725990390eb148fc00f12e8a0174481a2d\", \"services_unittests\": \"9a5a26772532662034bfb8ae59683244a8ec88ca\", \"setup_unittests\": \"5b04ba82297445c48745255c8d8e63c71a852aed\", \"shell_dialogs_unittests\": \"2591ee66977db5e6c5cafc372b148e63a8359496\", \"skia_unittests\": \"92c1a2e2d2cb423e2182091ef18bd7a6aa7793b0\", \"snapshot_unittests\": \"e5a30e6d4a1683de3b4aba06ab3ffdd376647779\", \"sql_unittests\": \"d884bda823ebcc99aa5acaf790ce41a08d3db23d\", \"storage_unittests\": \"af1148be634ac4518187f933f000e4b97f44749f\", \"sync_integration_tests\": \"bd8fe369f7dece41c91be25888d09c36f6504081\", \"traffic_annotation_auditor_unittests\": \"d19d7ddb62815eba0f74e34ad15681e321266ee5\", \"ui_base_unittests\": \"56743155de73e0f064ae5948ec661810f92f7fca\", \"ui_touch_selection_unittests\": \"fe9fdb1152a5a179df4c8ba3e047638afa54e7fc\", \"unit_tests\": \"9bb3685521bb8b5fc05913fac79586cfc77ae722\", \"updater_tests\": \"345e02bc91adbc832e5caa0ebf68592c71eab862\", \"url_unittests\": \"3a75e2c3b2dd6b5b6e6e466dbc9791200148d5f0\", \"views_unittests\": \"5b5f924f3fa7f54e26a139af48c7071312bb32f5\", \"viz_unittests\": \"5e93598428762b2674024ded6c781b25bd6bb9fd\", \"vr_common_unittests\": \"446f9ac0ba807c9bdf2b0fc4084a6270a01d5786\", \"vr_pixeltests\": \"55055e7ba1db302ec03e4077f9059c11c8a77712\", \"weblayer_browsertests\": \"ad4eaa86eae494485cce84b7ac878de574abae62\", \"weblayer_unittests\": \"ab15e411ca5740caa7f8993b5698273d7f5a41fe\", \"wm_unittests\": \"dc0b7a9eb9b3035c0c8249492bef59750755b92e\", \"wtf_unittests\": \"8266925844584bf8d3a9293f96f2efbcb30aa120\", \"zlib_unittests\": \"242ad0aa555691551c39db1126490d32dd206b37\", \"zucchini_unittests\": \"1606c349feea14415d9593a8b780447577ef01f2\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_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-0-be5b\"], \"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\": [\"5676-c76598c\"], \"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": "1612536166716285",
"status": "COMPLETED",
"status_changed_ts": "1612539901827697",
"tags": [
"build_address:luci.chromium.ci/win-asan/17181",
"builder:win-asan",
"buildset:commit/git/50726aee165794312051c884efcb6ba52d140faf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/50726aee165794312051c884efcb6ba52d140faf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021557057845322288",
"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/8856126995896638304/+/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:518b76b733e8c410",
"user_agent:luci-scheduler"
],
"updated_ts": "1612539901886490",
"url": "https://ci.chromium.org/b/8856126995896638304",
"utcnow_ts": "1612539902847587"
}
}
[15:45:02.871] Invocation finished in 1h2m20.723853458s with status SUCCEEDED