[12:41:49.273] New invocation is queued and will start shortly
[12:41:50.365] Starting the invocation (attempt 1)
[12:41:50.403] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:41:50.403] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:41:50.403] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021111678218640464",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3ed91ebe67fe84536d6d22d688bb60814433421e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021111678218640464",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/3ed91ebe67fe84536d6d22d688bb60814433421e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3ed91ebe67fe84536d6d22d688bb60814433421e",
"gitiles_ref:refs/heads/master"
]
}
[12:42:50.366] Failed to add buildbucket build - Put https://cr-buildbucket.appspot.com/_ah/api/buildbucket/v1/builds?alt=json&prettyPrint=false: API error 5 (urlfetch: DEADLINE_EXCEEDED)
[12:42:50.366] The invocation will be retried
[12:42:55.422] Starting the invocation (attempt 2)
[12:42:55.469] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:42:55.469] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:42:55.469] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021111678218640464",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3ed91ebe67fe84536d6d22d688bb60814433421e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021111678218640464",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/3ed91ebe67fe84536d6d22d688bb60814433421e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3ed91ebe67fe84536d6d22d688bb60814433421e",
"gitiles_ref:refs/heads/master"
]
}
[12:42:56.254] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612960975615218",
"id": "8855681547991277568",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3ed91ebe67fe84536d6d22d688bb60814433421e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612960976022169",
"tags": [
"build_address:luci.chromium.ci/win-asan/17270",
"builder:win-asan",
"buildset:commit/git/3ed91ebe67fe84536d6d22d688bb60814433421e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3ed91ebe67fe84536d6d22d688bb60814433421e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021111678218640464",
"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/8855681547991277568/+/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": "1612960976022355",
"url": "https://ci.chromium.org/b/8855681547991277568",
"utcnow_ts": "1612960976246123"
}
}
[12:42:56.254] Task URL: https://ci.chromium.org/b/8855681547991277568
[12:42:56.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:5:0) after 1m0s
[12:43:06.182] Received PubSub notification, asking Buildbucket for the build status
[12:43:06.241] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:56.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:5:0)
[12:43:56.299] Timer tick, asking Buildbucket for the build status
[12:43:56.514] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:56.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:7:0) after 1m0s
[12:44:56.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:7:0)
[12:44:56.562] Timer tick, asking Buildbucket for the build status
[12:44:56.746] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:56.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:8:0) after 1m0s
[12:45:56.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:8:0)
[12:45:56.796] Timer tick, asking Buildbucket for the build status
[12:45:57.039] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:57.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:9:0) after 1m0s
[12:46:57.050] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:9:0)
[12:46:57.050] Timer tick, asking Buildbucket for the build status
[12:46:57.138] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:57.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:10:0) after 1m0s
[12:47:57.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:10:0)
[12:47:57.192] Timer tick, asking Buildbucket for the build status
[12:47:57.802] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:57.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:11:0) after 1m0s
[12:48:57.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:11:0)
[12:48:57.822] Timer tick, asking Buildbucket for the build status
[12:48:57.882] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:57.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:12:0) after 1m0s
[12:49:57.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:12:0)
[12:49:57.902] Timer tick, asking Buildbucket for the build status
[12:49:57.971] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:57.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:13:0) after 1m0s
[12:50:57.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:13:0)
[12:50:57.990] Timer tick, asking Buildbucket for the build status
[12:50:58.050] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:58.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:14:0) after 1m0s
[12:51:58.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:14:0)
[12:51:58.106] Timer tick, asking Buildbucket for the build status
[12:51:58.431] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:58.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:15:0) after 1m0s
[12:52:58.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:15:0)
[12:52:58.444] Timer tick, asking Buildbucket for the build status
[12:52:59.055] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:59.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:16:0) after 1m0s
[12:53:59.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:16:0)
[12:53:59.064] Timer tick, asking Buildbucket for the build status
[12:53:59.156] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:59.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:17:0) after 1m0s
[12:54:59.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:17:0)
[12:54:59.208] Timer tick, asking Buildbucket for the build status
[12:54:59.441] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:59.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:18:0) after 1m0s
[12:55:59.463] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:18:0)
[12:55:59.463] Timer tick, asking Buildbucket for the build status
[12:55:59.723] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:59.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:19:0) after 1m0s
[12:56:59.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:19:0)
[12:56:59.725] Timer tick, asking Buildbucket for the build status
[12:56:59.774] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:59.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:20:0) after 1m0s
[12:57:59.842] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:20:0)
[12:57:59.842] Timer tick, asking Buildbucket for the build status
[12:57:59.951] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:59.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:21:0) after 1m0s
[12:58:59.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:21:0)
[12:58:59.974] Timer tick, asking Buildbucket for the build status
[12:59:00.126] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:00.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:22:0) after 1m0s
[13:00:00.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:22:0)
[13:00:00.149] Timer tick, asking Buildbucket for the build status
[13:00:00.235] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:00.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:23:0) after 1m0s
[13:01:00.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:23:0)
[13:01:00.322] Timer tick, asking Buildbucket for the build status
[13:01:00.568] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:00.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:24:0) after 1m0s
[13:02:00.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:24:0)
[13:02:00.780] Timer tick, asking Buildbucket for the build status
[13:02:01.257] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:01.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:25:0) after 1m0s
[13:03:01.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:25:0)
[13:03:01.365] Timer tick, asking Buildbucket for the build status
[13:03:01.635] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:01.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:26:0) after 1m0s
[13:04:01.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:26:0)
[13:04:01.659] Timer tick, asking Buildbucket for the build status
[13:04:01.800] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:01.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:27:0) after 1m0s
[13:05:01.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:27:0)
[13:05:01.831] Timer tick, asking Buildbucket for the build status
[13:05:02.251] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:02.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:28:0) after 1m0s
[13:06:02.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:28:0)
[13:06:02.315] Timer tick, asking Buildbucket for the build status
[13:06:03.084] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:03.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:29:0) after 1m0s
[13:07:03.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:29:0)
[13:07:03.150] Timer tick, asking Buildbucket for the build status
[13:07:03.513] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:03.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:30:0) after 1m0s
[13:08:03.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:30:0)
[13:08:03.523] Timer tick, asking Buildbucket for the build status
[13:08:03.716] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:03.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:31:0) after 1m0s
[13:09:03.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:31:0)
[13:09:03.747] Timer tick, asking Buildbucket for the build status
[13:09:04.117] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:04.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:32:0) after 1m0s
[13:10:04.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:32:0)
[13:10:04.154] Timer tick, asking Buildbucket for the build status
[13:10:04.915] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:04.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:33:0) after 1m0s
[13:11:04.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:33:0)
[13:11:04.960] Timer tick, asking Buildbucket for the build status
[13:11:05.114] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:05.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:34:0) after 1m0s
[13:12:05.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:34:0)
[13:12:05.131] Timer tick, asking Buildbucket for the build status
[13:12:05.881] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:05.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:35:0) after 1m0s
[13:13:05.904] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:35:0)
[13:13:05.904] Timer tick, asking Buildbucket for the build status
[13:13:06.325] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:06.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:36:0) after 1m0s
[13:14:06.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:36:0)
[13:14:06.345] Timer tick, asking Buildbucket for the build status
[13:14:07.296] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:07.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:37:0) after 1m0s
[13:15:07.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:37:0)
[13:15:07.308] Timer tick, asking Buildbucket for the build status
[13:15:07.474] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:07.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:38:0) after 1m0s
[13:16:07.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:38:0)
[13:16:07.520] Timer tick, asking Buildbucket for the build status
[13:16:08.103] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:08.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:39:0) after 1m0s
[13:17:08.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:39:0)
[13:17:08.107] Timer tick, asking Buildbucket for the build status
[13:17:08.514] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:08.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:40:0) after 1m0s
[13:18:08.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:40:0)
[13:18:08.541] Timer tick, asking Buildbucket for the build status
[13:18:08.689] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:08.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:41:0) after 1m0s
[13:19:08.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:41:0)
[13:19:08.733] Timer tick, asking Buildbucket for the build status
[13:19:08.866] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:08.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:42:0) after 1m0s
[13:20:08.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:42:0)
[13:20:08.872] Timer tick, asking Buildbucket for the build status
[13:20:08.962] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:08.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:43:0) after 1m0s
[13:21:09.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:43:0)
[13:21:09.007] Timer tick, asking Buildbucket for the build status
[13:21:09.133] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:09.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:44:0) after 1m0s
[13:22:09.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:44:0)
[13:22:09.218] Timer tick, asking Buildbucket for the build status
[13:22:09.571] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:09.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:45:0) after 1m0s
[13:23:09.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:45:0)
[13:23:09.609] Timer tick, asking Buildbucket for the build status
[13:23:10.049] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:10.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:46:0) after 1m0s
[13:24:10.091] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:46:0)
[13:24:10.091] Timer tick, asking Buildbucket for the build status
[13:24:10.171] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:10.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:47:0) after 1m0s
[13:25:10.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:47:0)
[13:25:10.181] Timer tick, asking Buildbucket for the build status
[13:25:11.814] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:11.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:48:0) after 1m0s
[13:26:12.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:48:0)
[13:26:12.004] Timer tick, asking Buildbucket for the build status
[13:26:12.133] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:12.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:49:0) after 1m0s
[13:27:12.173] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:49:0)
[13:27:12.173] Timer tick, asking Buildbucket for the build status
[13:27:12.330] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:12.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:50:0) after 1m0s
[13:28:12.463] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:50:0)
[13:28:12.463] Timer tick, asking Buildbucket for the build status
[13:28:12.605] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:12.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:51:0) after 1m0s
[13:29:12.645] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:51:0)
[13:29:12.645] Timer tick, asking Buildbucket for the build status
[13:29:12.742] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:12.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:52:0) after 1m0s
[13:30:12.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:52:0)
[13:30:12.721] Timer tick, asking Buildbucket for the build status
[13:30:12.825] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:12.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:53:0) after 1m0s
[13:31:12.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:53:0)
[13:31:12.866] Timer tick, asking Buildbucket for the build status
[13:31:13.004] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:13.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:54:0) after 1m0s
[13:32:13.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:54:0)
[13:32:13.079] Timer tick, asking Buildbucket for the build status
[13:32:13.221] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:13.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:55:0) after 1m0s
[13:33:13.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:55:0)
[13:33:13.254] Timer tick, asking Buildbucket for the build status
[13:33:13.359] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:13.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:56:0) after 1m0s
[13:34:13.394] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:56:0)
[13:34:13.394] Timer tick, asking Buildbucket for the build status
[13:34:13.726] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:13.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:57:0) after 1m0s
[13:35:13.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:57:0)
[13:35:13.748] Timer tick, asking Buildbucket for the build status
[13:35:13.855] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:13.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:58:0) after 1m0s
[13:36:13.906] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:58:0)
[13:36:13.906] Timer tick, asking Buildbucket for the build status
[13:36:14.080] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:14.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:59:0) after 1m0s
[13:37:14.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:59:0)
[13:37:14.140] Timer tick, asking Buildbucket for the build status
[13:37:14.504] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:14.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:60:0) after 1m0s
[13:38:14.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:60:0)
[13:38:14.611] Timer tick, asking Buildbucket for the build status
[13:38:14.825] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:14.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:61:0) after 1m0s
[13:39:14.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:61:0)
[13:39:14.845] Timer tick, asking Buildbucket for the build status
[13:39:14.968] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:14.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:62:0) after 1m0s
[13:40:14.980] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:62:0)
[13:40:14.980] Timer tick, asking Buildbucket for the build status
[13:40:15.060] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:15.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:63:0) after 1m0s
[13:41:15.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:63:0)
[13:41:15.172] Timer tick, asking Buildbucket for the build status
[13:41:15.488] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:15.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:64:0) after 1m0s
[13:42:15.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:64:0)
[13:42:15.522] Timer tick, asking Buildbucket for the build status
[13:42:15.696] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:15.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:65:0) after 1m0s
[13:43:15.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:65:0)
[13:43:15.718] Timer tick, asking Buildbucket for the build status
[13:43:15.858] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:15.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:66:0) after 1m0s
[13:44:15.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:66:0)
[13:44:15.910] Timer tick, asking Buildbucket for the build status
[13:44:16.043] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:16.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:67:0) after 1m0s
[13:45:16.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:67:0)
[13:45:16.064] Timer tick, asking Buildbucket for the build status
[13:45:16.241] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:16.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:68:0) after 1m0s
[13:46:16.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:68:0)
[13:46:16.301] Timer tick, asking Buildbucket for the build status
[13:46:16.399] Build 8855681547991277568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:16.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021111678218640464:69:0) after 1m0s
[13:46:28.696] Received PubSub notification, asking Buildbucket for the build status
[13:46:28.802] Build 8855681547991277568: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:46:28.802] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612964787408921",
"created_by": "project:chromium",
"created_ts": "1612960975615218",
"id": "8855681547991277568",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3ed91ebe67fe84536d6d22d688bb60814433421e\"}}",
"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\": \"3ed91ebe67fe84536d6d22d688bb60814433421e\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-3-02em\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17270, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"80308e20a5612bc107fa164fa0f848905e3a1bf1\", \"got_dawn_revision\": \"02fd17c75403a399672c65aa98e577c7c2ae9010\", \"got_nacl_revision\": \"4de55da44c4b511989359381e8b7a88a01fe0634\", \"got_revision\": \"3ed91ebe67fe84536d6d22d688bb60814433421e\", \"got_revision_cp\": \"refs/heads/master@{#852600}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"673a7fe5c3ff6686c98951cafb24a93ca5bd1303\", \"got_v8_revision\": \"980c42831556b1dad5936edd3dabe6286a7bfc04\", \"got_v8_revision_cp\": \"refs/heads/9.0.154@{#1}\", \"got_webrtc_revision\": \"028d5be9c72cbeddce8236f68ed177a512bc67d8\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33211}\", \"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\": \"3ed91ebe67fe84536d6d22d688bb60814433421e\", \"swarm_hashes_refs/heads/master(at){#852600}_without_patch\": {\"absl_hardening_tests\": \"cde780505880b9584ebb74ef472c1418cbb17fc8\", \"accessibility_unittests\": \"900bdbff092b3692b6f6098d396f69977f6c534e\", \"angle_unittests\": \"109e1271da96673e80ad46624e631e5a84fcc462\", \"app_shell_unittests\": \"27292c690dda8853273d7f60099936fe88f103eb\", \"aura_unittests\": \"144289c3be7a0ddc673ad026bbeea3a89a86b739\", \"base_unittests\": \"5b30425c272fff9d75d9167728a3d9b0e5f2c664\", \"base_util_unittests\": \"9f32f283778197d2a8b6ce464731c90218788625\", \"blink_common_unittests\": \"61710c0c6dce4c55d2261aa974aa366dde27f7cf\", \"blink_fuzzer_unittests\": \"ec843f9be6e524f7dd57bf5bf9caed7d3e6c6a3e\", \"blink_heap_unittests\": \"4b70c55327044d18ef0eed14a58fee496b856404\", \"blink_platform_unittests\": \"bf6ad9509d58f707917b978f4bf9a41a17789a79\", \"blink_unittests\": \"55795e2ab10b9f2b9418297801a9af32614a7be0\", \"boringssl_crypto_tests\": \"d90624676f3db78100b443984ba107cc920f211a\", \"boringssl_ssl_tests\": \"597157931946f87b7994f2ad5d184a757762fbc4\", \"browser_switcher_bho_unittests\": \"b6f422b71b6629bb4c66e6739feaa49406e3f204\", \"browser_tests\": \"868faa861db60abd2832609c9043c48531587a5d\", \"capture_unittests\": \"de7d4b0542b0f2eb71e13abde832e3039c32fef0\", \"cast_unittests\": \"3b1edacc29d3c8123bb90e6ac24a40d1d2c8bd47\", \"cc_unittests\": \"1da5228e75091c9114d3e8b71eea85f9367e7f07\", \"chrome_app_unittests\": \"e29dea57811980d8411ccff808e598fb614d0465\", \"chrome_cleaner_unittests\": \"df70c7ae49ee4bd625f96578bf497f1cdf2d90e7\", \"chrome_elf_unittests\": \"939972684b7044df951eec537040d373ecba4554\", \"chromedriver_unittests\": \"61167e76b9adf25651018e646f722969e1a3766d\", \"components_browsertests\": \"fba51302577ef577f1ca8e4a8e41d96e1fcf2091\", \"components_unittests\": \"8b7ce79b1417bf85f8881daf756cea6a918429fc\", \"compositor_unittests\": \"34a735530fda4ac5f222ab0dc0ece72ac4c67c58\", \"content_browsertests\": \"b91ff87d769fc46b484c75b7b26f74e5823d33e6\", \"content_unittests\": \"0ec2b2a04e7a65ed5fdd79aaf41a84dcf40ee527\", \"courgette_unittests\": \"87d85228af83d1bf1bcdd71d51c1fe8214c3741b\", \"crashpad_tests\": \"eb01f8c7c025d418eb76644636ced3ac60d0eed7\", \"cronet_tests\": \"100245bf34b0b2ad19a19ba971e704caa418bb17\", \"cronet_unittests\": \"dfcd9180c231253f2e0982cd4e8f264f340c6946\", \"crypto_unittests\": \"c3309436aca2ec89414df9853da9184c3ba6c2b3\", \"delayloads_unittests\": \"4355047f22ab22bca6b888ce5319ba778e01b074\", \"device_unittests\": \"39c8a6ac55b828e43776658c8133b3cf5a1bd560\", \"display_unittests\": \"ea11fad6c3b83d0dcdcf80078e0b4d0d47b83be7\", \"elevation_service_unittests\": \"838432bcd6448f0c138f2564c12ea27cb4e19f79\", \"events_unittests\": \"492e378d92038ae3fb558c91a08214f56b71428c\", \"extensions_browsertests\": \"50da33042f62b77d201256f4f825f0957ee95966\", \"extensions_unittests\": \"b8127c81011154ead764e4903fb79fa828980b1b\", \"filesystem_service_unittests\": \"7d8034f3d349a142f8f7418405c0ce4ddb1295a4\", \"gcm_unit_tests\": \"63dece7268ccb571c5978f52824d5b1f433f680c\", \"gcp_unittests\": \"65174108e4c034a2054d681e5e01b06e311646e2\", \"gfx_unittests\": \"646b205b12db71e1e7a85791ec1404501b6342c7\", \"gin_unittests\": \"be87a1e3c6e6a10cd0172c34003eb3cad407481d\", \"google_apis_unittests\": \"d821a194df910829742bead5c6924050d0186b89\", \"gpu_unittests\": \"80567b2c7aa19d8aeae34015b2b1edfa99fc45ed\", \"gwp_asan_unittests\": \"4225b8cfea78af70d446fa4c68c44a56051fec90\", \"headless_browsertests\": \"f736787d7972a7f0e62713cd93d122e810407975\", \"headless_unittests\": \"6d82a2440cd26664b2de75ed15ce00a2cf0e5c2c\", \"install_static_unittests\": \"a3f7e8ba5f8fa22e292690d1a00a44f78cfeb235\", \"installer_util_unittests\": \"c97099243c271cc7f51c12318aaa462bfd151651\", \"interactive_ui_tests\": \"a22a927f45ac0a5c0cf35397a9cfdabec4f1e35d\", \"ipc_tests\": \"f0f667edf5ed29ce95d9beee23081f131a745c82\", \"jingle_unittests\": \"cb6d721e1330bd1b9bde3427b409516ca5161883\", \"latency_unittests\": \"9112b67bf81d09bc04c41452125d150ac89378a1\", \"libjingle_xmpp_unittests\": \"babb2ceb09b31917767368f99bada755032bba53\", \"liburlpattern_unittests\": \"354c99e7dfebe901907a45a9e6b589c15dc2c6e7\", \"media_blink_unittests\": \"48495f5e431fb65a29865f9083b28c257562a8a9\", \"media_unittests\": \"e059ab83d77fe535cef1a10581389c0902727986\", \"message_center_unittests\": \"0e103ba5097ded889a01aae50cec90c5a3a880c0\", \"midi_unittests\": \"743348fb8b27f75beb6f81ad5c77b0b2fdea2bd6\", \"mojo_core_unittests\": \"dd963342ae6149af81fa23fedd6e63fe7835798b\", \"mojo_unittests\": \"c01d91ae09abe5b67b64db42eb61c6146518a817\", \"nacl_loader_unittests\": \"55bc5a0ea621979ee07f4c081157b9e6a21a9501\", \"native_theme_unittests\": \"6ebaf136148afb0f26ce0e09e9dd133df75d23fc\", \"net_unittests\": \"b9bfbd60e7e0b227ea11527085c3aca82756d767\", \"notification_helper_unittests\": \"d9b60255887961b8cb9b230df9f6d004209c52f7\", \"pdf_unittests\": \"0e9158b928aac78391a7eb01fb467650cf025d9a\", \"perfetto_unittests\": \"eebc7d2139c71047edaedd9251fc70f10eb8b509\", \"ppapi_unittests\": \"16b0bd2f2c5a327b0277270599088ddce5d71193\", \"printing_unittests\": \"c8b072d89b97be8cc90a7751a2505b99ee78b721\", \"remoting_unittests\": \"ad39021b5f6eb5db3937aee57634a1c858d70737\", \"sbox_integration_tests\": \"49ad584bb00cfe7688476a9abd0d7b6323d8e70a\", \"sbox_unittests\": \"6eb9eb2345f9fe5074ed26518d97b3dcf8a05b8c\", \"sbox_validation_tests\": \"864e4ecce0d9ada67d09d9e9e53fcadadbc762b9\", \"service_manager_unittests\": \"a9b968f52cbb90f82bcd4d4a2bf7266ba8b79f96\", \"services_unittests\": \"9bf65c6222c48daa45ad9dbad18750146df57fc8\", \"setup_unittests\": \"733e1f013978063a6239ff3227d90023d9d8a8f6\", \"shell_dialogs_unittests\": \"cd6baa9774689ac731bd1920602f5897857f70eb\", \"skia_unittests\": \"79625e0ef5552ae9ea1f4720cfb033d946217222\", \"snapshot_unittests\": \"028c3875fd522d8854d1e0059e17105f03f0b502\", \"sql_unittests\": \"94c25398eaf5a8f0b5560ffd67160ba3b02cbc2d\", \"storage_unittests\": \"5a16690c36c2ef32455c034f90ab975d71dff900\", \"sync_integration_tests\": \"d3dc215328e88550c487daa1eebf783bc3e22015\", \"traffic_annotation_auditor_unittests\": \"677f7ed3468bdfe20e6655b2fa885336f3c0fe7e\", \"ui_base_unittests\": \"c7748fd9cd4cf059b05fd7b985b61ef9294fd955\", \"ui_touch_selection_unittests\": \"a5b8f32ecae2fc42825aa7402ec04263145da202\", \"unit_tests\": \"0487c647a67cf584be5bdc9b87802aa3f68bd834\", \"updater_tests\": \"37cfb075c1e0b56724d2644586c46c0d55b107b8\", \"url_unittests\": \"49492a7ac79e909f81226e0d1d9b93bfd1daaa8e\", \"views_unittests\": \"fc0ae87c1f79db034531704c425457096809697a\", \"viz_unittests\": \"3c3475db3f7065c3c30d91f9dc86538d41187594\", \"vr_common_unittests\": \"369148cb55d0de1e453d77d6f56dc0e7896c121f\", \"vr_pixeltests\": \"27db108c74aaa153bef82e8b6808688d7a95acf5\", \"weblayer_browsertests\": \"887464e3239b1979ae6e00109db1404f24f17e4d\", \"weblayer_unittests\": \"70e40356462f92c35c944242cbae3fe4495cfb0f\", \"wm_unittests\": \"eb72b068b45ec34c6ce13ff61c8763a9ad8dd351\", \"wtf_unittests\": \"14dea47a4c9b3fdf009c4f1bdcd16b886e443293\", \"zlib_unittests\": \"bdac36073bc8d85d93e29e7e5a13df25956661e4\", \"zucchini_unittests\": \"7ac2340370852a787a26b21f5134c5e876c46937\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-3-02em\"], \"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\": [\"5685-1877293\"], \"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": "1612960985430824",
"status": "COMPLETED",
"status_changed_ts": "1612964787899399",
"tags": [
"build_address:luci.chromium.ci/win-asan/17270",
"builder:win-asan",
"buildset:commit/git/3ed91ebe67fe84536d6d22d688bb60814433421e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3ed91ebe67fe84536d6d22d688bb60814433421e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021111678218640464",
"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/8855681547991277568/+/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:51a4c8d60411db10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612964787979498",
"url": "https://ci.chromium.org/b/8855681547991277568",
"utcnow_ts": "1612964788791727"
}
}
[13:46:28.802] Invocation finished in 1h4m39.543761128s with status SUCCEEDED