[11:21:50.774] New invocation is queued and will start shortly
[11:21:51.905] Starting the invocation (attempt 1)
[11:21:52.000] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:21:52.008] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:21:52.008] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021841485542613680",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"24ab4d3dfc08dfdffc3383fabd1fce83793f9fc7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021841485542613680",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/24ab4d3dfc08dfdffc3383fabd1fce83793f9fc7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/24ab4d3dfc08dfdffc3383fabd1fce83793f9fc7",
"gitiles_ref:refs/heads/master"
]
}
[11:21:52.838] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612264912104189",
"id": "8856411423483501968",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"24ab4d3dfc08dfdffc3383fabd1fce83793f9fc7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612264912682821",
"tags": [
"build_address:luci.chromium.ci/win-asan/17129",
"builder:win-asan",
"buildset:commit/git/24ab4d3dfc08dfdffc3383fabd1fce83793f9fc7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/24ab4d3dfc08dfdffc3383fabd1fce83793f9fc7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021841485542613680",
"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/8856411423483501968/+/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": "1612264912682941",
"url": "https://ci.chromium.org/b/8856411423483501968",
"utcnow_ts": "1612264912829139"
}
}
[11:21:52.838] Task URL: https://ci.chromium.org/b/8856411423483501968
[11:21:52.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:2:0) after 1m0s
[11:22:25.021] Received PubSub notification, asking Buildbucket for the build status
[11:22:25.130] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:52.943] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:2:0)
[11:22:52.943] Timer tick, asking Buildbucket for the build status
[11:22:53.006] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:53.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:4:0) after 1m0s
[11:23:53.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:4:0)
[11:23:53.277] Timer tick, asking Buildbucket for the build status
[11:23:53.384] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:53.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:5:0) after 1m0s
[11:24:53.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:5:0)
[11:24:53.410] Timer tick, asking Buildbucket for the build status
[11:24:56.009] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:56.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:6:0) after 1m0s
[11:25:56.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:6:0)
[11:25:56.455] Timer tick, asking Buildbucket for the build status
[11:25:56.552] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:56.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:7:0) after 1m0s
[11:26:56.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:7:0)
[11:26:56.703] Timer tick, asking Buildbucket for the build status
[11:26:56.862] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:56.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:8:0) after 1m0s
[11:27:56.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:8:0)
[11:27:56.882] Timer tick, asking Buildbucket for the build status
[11:27:56.986] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:56.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:9:0) after 1m0s
[11:28:57.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:9:0)
[11:28:57.243] Timer tick, asking Buildbucket for the build status
[11:28:57.330] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:57.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:10:0) after 1m0s
[11:29:57.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:10:0)
[11:29:57.694] Timer tick, asking Buildbucket for the build status
[11:29:57.832] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:57.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:11:0) after 1m0s
[11:30:58.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:11:0)
[11:30:58.008] Timer tick, asking Buildbucket for the build status
[11:30:58.139] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:58.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:12:0) after 1m0s
[11:31:58.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:12:0)
[11:31:58.224] Timer tick, asking Buildbucket for the build status
[11:31:58.287] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:58.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:13:0) after 1m0s
[11:32:58.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:13:0)
[11:32:58.298] Timer tick, asking Buildbucket for the build status
[11:32:58.505] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:58.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:14:0) after 1m0s
[11:33:58.590] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:14:0)
[11:33:58.590] Timer tick, asking Buildbucket for the build status
[11:33:58.737] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:58.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:15:0) after 1m0s
[11:34:58.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:15:0)
[11:34:58.856] Timer tick, asking Buildbucket for the build status
[11:34:59.002] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:59.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:16:0) after 1m0s
[11:35:59.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:16:0)
[11:35:59.049] Timer tick, asking Buildbucket for the build status
[11:35:59.115] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:59.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:17:0) after 1m0s
[11:36:59.163] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:17:0)
[11:36:59.163] Timer tick, asking Buildbucket for the build status
[11:36:59.224] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:59.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:18:0) after 1m0s
[11:37:59.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:18:0)
[11:37:59.304] Timer tick, asking Buildbucket for the build status
[11:37:59.695] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:59.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:19:0) after 1m0s
[11:38:59.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:19:0)
[11:38:59.768] Timer tick, asking Buildbucket for the build status
[11:38:59.896] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:59.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:20:0) after 1m0s
[11:39:59.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:20:0)
[11:39:59.981] Timer tick, asking Buildbucket for the build status
[11:40:00.549] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:00.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:21:0) after 1m0s
[11:41:01.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:21:0)
[11:41:01.248] Timer tick, asking Buildbucket for the build status
[11:41:01.525] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:01.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:22:0) after 1m0s
[11:42:01.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:22:0)
[11:42:01.617] Timer tick, asking Buildbucket for the build status
[11:42:01.834] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:01.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:23:0) after 1m0s
[11:43:01.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:23:0)
[11:43:01.909] Timer tick, asking Buildbucket for the build status
[11:43:02.095] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:02.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:24:0) after 1m0s
[11:44:02.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:24:0)
[11:44:02.147] Timer tick, asking Buildbucket for the build status
[11:44:02.546] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:02.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:25:0) after 1m0s
[11:45:02.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:25:0)
[11:45:02.569] Timer tick, asking Buildbucket for the build status
[11:45:02.946] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:02.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:26:0) after 1m0s
[11:46:02.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:26:0)
[11:46:02.945] Timer tick, asking Buildbucket for the build status
[11:46:03.380] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:03.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:27:0) after 1m0s
[11:47:03.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:27:0)
[11:47:03.486] Timer tick, asking Buildbucket for the build status
[11:47:03.621] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:03.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:28:0) after 1m0s
[11:48:03.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:28:0)
[11:48:03.630] Timer tick, asking Buildbucket for the build status
[11:48:03.762] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:03.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:29:0) after 1m0s
[11:49:03.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:29:0)
[11:49:03.801] Timer tick, asking Buildbucket for the build status
[11:49:04.133] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:04.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:30:0) after 1m0s
[11:50:04.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:30:0)
[11:50:04.207] Timer tick, asking Buildbucket for the build status
[11:50:04.359] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:04.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:31:0) after 1m0s
[11:51:04.370] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:31:0)
[11:51:04.370] Timer tick, asking Buildbucket for the build status
[11:51:04.826] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:04.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:32:0) after 1m0s
[11:52:04.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:32:0)
[11:52:04.944] Timer tick, asking Buildbucket for the build status
[11:52:05.413] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:05.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:33:0) after 1m0s
[11:53:05.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:33:0)
[11:53:05.542] Timer tick, asking Buildbucket for the build status
[11:53:06.204] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:06.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:34:0) after 1m0s
[11:54:06.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:34:0)
[11:54:06.246] Timer tick, asking Buildbucket for the build status
[11:54:06.404] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:06.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:35:0) after 1m0s
[11:55:06.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:35:0)
[11:55:06.503] Timer tick, asking Buildbucket for the build status
[11:55:06.621] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:06.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:36:0) after 1m0s
[11:56:06.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:36:0)
[11:56:06.653] Timer tick, asking Buildbucket for the build status
[11:56:06.733] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:06.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:37:0) after 1m0s
[11:57:06.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:37:0)
[11:57:06.755] Timer tick, asking Buildbucket for the build status
[11:57:06.848] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:06.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:38:0) after 1m0s
[11:58:06.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:38:0)
[11:58:06.901] Timer tick, asking Buildbucket for the build status
[11:58:07.023] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:07.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:39:0) after 1m0s
[11:59:07.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:39:0)
[11:59:07.053] Timer tick, asking Buildbucket for the build status
[11:59:07.290] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:07.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:40:0) after 1m0s
[12:00:07.366] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:40:0)
[12:00:07.366] Timer tick, asking Buildbucket for the build status
[12:00:07.450] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:07.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:41:0) after 1m0s
[12:01:07.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:41:0)
[12:01:07.658] Timer tick, asking Buildbucket for the build status
[12:01:08.197] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:08.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:42:0) after 1m0s
[12:02:08.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:42:0)
[12:02:08.287] Timer tick, asking Buildbucket for the build status
[12:02:08.413] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:08.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:43:0) after 1m0s
[12:03:08.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:43:0)
[12:03:08.523] Timer tick, asking Buildbucket for the build status
[12:03:08.725] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:08.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:44:0) after 1m0s
[12:04:08.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:44:0)
[12:04:08.753] Timer tick, asking Buildbucket for the build status
[12:04:09.082] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:09.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:45:0) after 1m0s
[12:05:09.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:45:0)
[12:05:09.216] Timer tick, asking Buildbucket for the build status
[12:05:09.334] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:09.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:46:0) after 1m0s
[12:06:09.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:46:0)
[12:06:09.403] Timer tick, asking Buildbucket for the build status
[12:06:09.708] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:09.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:47:0) after 1m0s
[12:07:09.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:47:0)
[12:07:09.818] Timer tick, asking Buildbucket for the build status
[12:07:09.898] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:09.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:48:0) after 1m0s
[12:08:09.927] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:48:0)
[12:08:09.927] Timer tick, asking Buildbucket for the build status
[12:08:10.128] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:10.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:49:0) after 1m0s
[12:09:10.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:49:0)
[12:09:10.154] Timer tick, asking Buildbucket for the build status
[12:09:10.437] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:10.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:50:0) after 1m0s
[12:10:10.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:50:0)
[12:10:10.523] Timer tick, asking Buildbucket for the build status
[12:10:10.662] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:10.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:51:0) after 1m0s
[12:11:10.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:51:0)
[12:11:10.751] Timer tick, asking Buildbucket for the build status
[12:11:10.904] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:10.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:52:0) after 1m0s
[12:12:10.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:52:0)
[12:12:10.969] Timer tick, asking Buildbucket for the build status
[12:12:11.107] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:11.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:53:0) after 1m0s
[12:13:11.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:53:0)
[12:13:11.145] Timer tick, asking Buildbucket for the build status
[12:13:12.004] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:12.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:54:0) after 1m0s
[12:14:12.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:54:0)
[12:14:12.031] Timer tick, asking Buildbucket for the build status
[12:14:12.174] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:12.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:55:0) after 1m0s
[12:15:12.163] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:55:0)
[12:15:12.163] Timer tick, asking Buildbucket for the build status
[12:15:12.514] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:12.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:56:0) after 1m0s
[12:16:12.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:56:0)
[12:16:12.569] Timer tick, asking Buildbucket for the build status
[12:16:12.701] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:12.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:57:0) after 1m0s
[12:17:12.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:57:0)
[12:17:12.704] Timer tick, asking Buildbucket for the build status
[12:17:12.855] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:12.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:58:0) after 1m0s
[12:18:12.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:58:0)
[12:18:12.958] Timer tick, asking Buildbucket for the build status
[12:18:13.115] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:13.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:59:0) after 1m0s
[12:19:13.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:59:0)
[12:19:13.212] Timer tick, asking Buildbucket for the build status
[12:19:13.350] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:13.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:60:0) after 1m0s
[12:20:13.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:60:0)
[12:20:13.452] Timer tick, asking Buildbucket for the build status
[12:20:13.610] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:13.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:61:0) after 1m0s
[12:21:13.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:61:0)
[12:21:13.733] Timer tick, asking Buildbucket for the build status
[12:21:13.843] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:13.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:62:0) after 1m0s
[12:22:13.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:62:0)
[12:22:13.878] Timer tick, asking Buildbucket for the build status
[12:22:14.449] Build 8856411423483501968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:14.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021841485542613680:63:0) after 1m0s
[12:22:45.102] Received PubSub notification, asking Buildbucket for the build status
[12:22:45.200] Build 8856411423483501968: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:22:45.200] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612268563722165",
"created_by": "project:chromium",
"created_ts": "1612264912104189",
"id": "8856411423483501968",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"24ab4d3dfc08dfdffc3383fabd1fce83793f9fc7\"}}",
"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\": \"24ab4d3dfc08dfdffc3383fabd1fce83793f9fc7\", \"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-8-uuz6\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17129, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"7e81056a54691bea83cbea37cb6a94dadb7c4bda\", \"got_dawn_revision\": \"9a2174a37cfdc771a9c7a494e6fb773c541bd941\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"24ab4d3dfc08dfdffc3383fabd1fce83793f9fc7\", \"got_revision_cp\": \"refs/heads/master@{#849546}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"51045556acd0fe0a1b687ef00725a57f0f19a91b\", \"got_v8_revision\": \"079f7c53751ac589cf05880522b3fa2fcb5b5645\", \"got_v8_revision_cp\": \"refs/heads/9.0.109@{#1}\", \"got_webrtc_revision\": \"e7c79fd3d66e0f6c54c027f880a8b1682ebae58a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33124}\", \"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\": \"24ab4d3dfc08dfdffc3383fabd1fce83793f9fc7\", \"swarm_hashes_refs/heads/master(at){#849546}_without_patch\": {\"absl_hardening_tests\": \"1726408473e9e22f85bc6812521f41950f255d8d\", \"accessibility_unittests\": \"57fb5974393b9d89ac1edb69f9ec9269aa583159\", \"angle_unittests\": \"ee0b23d7277055a7b8e76770ccb710e296e894e9\", \"app_shell_unittests\": \"443ed6e5e6de984702a968aa35f319700771bfa8\", \"aura_unittests\": \"0f6ece40f8e41ed4dbeba94d48ba5d69dacac192\", \"base_unittests\": \"7f4f2668dc7121d3c522b6ee67b0781b17bd935a\", \"base_util_unittests\": \"d911f30504db7f149eec9de9565facfcfd7ad9fc\", \"blink_common_unittests\": \"4aba41c527e3b4f4f0d9911779fc5fa3190a9098\", \"blink_fuzzer_unittests\": \"a768d42a9ef0c48b039bc5ea9a292ff47aeda313\", \"blink_heap_unittests\": \"2c8029de6ae753d362e98af5ada1c52a688421ce\", \"blink_platform_unittests\": \"2892a644ad4787a81c684b3111c2dc440ecda102\", \"blink_unittests\": \"cae8c69c5465f1b17181bff7cc14797e36e285bb\", \"boringssl_crypto_tests\": \"edf2e10614e4d7fbabb6e2b4e56a2630204c35a1\", \"boringssl_ssl_tests\": \"f061c32389acaf2905500266add5eb654ecd8ea7\", \"browser_switcher_bho_unittests\": \"88ab1df89fcd45ad1435f45abbc651a56ffdf7a8\", \"browser_tests\": \"8f21415419c425e8be90857e64086fccc19452d3\", \"capture_unittests\": \"960945e045b28bfe4e12e8490009d89532121e98\", \"cast_unittests\": \"f38cf0d0a7734be67d73c931f6e4d015366a885a\", \"cc_unittests\": \"57d0a466231400eede80d7999436a1cde32a53c6\", \"chrome_app_unittests\": \"d876219accf251577d8936ace2bb60efcc3622cc\", \"chrome_cleaner_unittests\": \"ff0aa9fa7c0d8f7092213473c7089f8f4c8088e6\", \"chrome_elf_unittests\": \"21d8f39fd4a58c4926c5ff0b77debee9a47dc7d0\", \"chromedriver_unittests\": \"5c2a9b022736dffbec9c5111ec80c7d9e61023c2\", \"components_browsertests\": \"50deb36c0deb838c450ad4b5b3c3b1a3c9607d55\", \"components_unittests\": \"987420a183edbcb6f808765533676b2b7b61fb43\", \"compositor_unittests\": \"c569aefc14e7b79a4270badfeac52d34dca81d2a\", \"content_browsertests\": \"6c8286ad30b78b510299cc071d95f401ea547fa1\", \"content_unittests\": \"6ad6c9706a0b146fb5e43b08d6fb4ca96a962a43\", \"courgette_unittests\": \"9560636e129ef54e040b686330bc90577209603c\", \"crashpad_tests\": \"b66e6e1b90acab1eba13d89e7e792d7d638931f1\", \"cronet_tests\": \"9641c5f2e780ea71250ecdc98d95c220c202a5a5\", \"cronet_unittests\": \"7adb1876b5681a4d22caef8a43f2baddde4adc11\", \"crypto_unittests\": \"be3d8d8ae68e229a11699aa2ce6ffff67d5a2891\", \"delayloads_unittests\": \"5a2e137155c096ac30e5df25a879496a4bd4336f\", \"device_unittests\": \"b77cf0e5c67f118528c8c7c5a656536ee07e82f3\", \"display_unittests\": \"0436cd89b5165803f91ddbb326699f5607fb8e95\", \"elevation_service_unittests\": \"fc2cdf07728d858d0898b6d4d8893f471a83aee9\", \"events_unittests\": \"152dece0d949b462d1b63503841f3fc0b81f0ac4\", \"extensions_browsertests\": \"0cbbecd180fd74a4c03fad802ce9c3d3daf30403\", \"extensions_unittests\": \"6301cda489fa4198d6f02b53492570af78a53f52\", \"filesystem_service_unittests\": \"180fea9c2ab961e538acb24dd742fcb637dc521f\", \"gcm_unit_tests\": \"a58ce13a813ca90ebdffb76a10ec4c90958afd52\", \"gcp_unittests\": \"9ff3ff7ed7f418231f0c7807e9c41c80d0786ea2\", \"gfx_unittests\": \"2b1557ba0f45f9807f48e6702433e4a4eaf1d342\", \"gin_unittests\": \"c8a8d34983fe69b2ee6b73dfee03403e5a34a2f5\", \"google_apis_unittests\": \"42d316f79c93e7399b98ad80c68779fa63052b0f\", \"gpu_unittests\": \"50fcdff65a2fe46fddcb0241acbb1f8e22a38044\", \"gwp_asan_unittests\": \"274019a9a50d17638738f5f56172318033a1766e\", \"headless_browsertests\": \"6daa0d6c78a84d92e5f700727b46dc2ac97a94bc\", \"headless_unittests\": \"69d3717b17922f4c08c0f87ba3fab922683c6bba\", \"install_static_unittests\": \"de4f61419988d00ee482297f926de2016af7cac1\", \"installer_util_unittests\": \"a9957eae89abb7d030e963380fc1eff2d65108a9\", \"interactive_ui_tests\": \"fae6e40fe200a7c90a96f5dd9cee2d7b29711700\", \"ipc_tests\": \"19c57755c8afe6f27ffa55472e9c8cd5169964fd\", \"jingle_unittests\": \"8720f362340ecfe337deebbf11a0be0e000676e6\", \"latency_unittests\": \"43ee2b795d9ce604912688e0da68f26891d8cb36\", \"libjingle_xmpp_unittests\": \"fb81887492bc2124d09936f957193c29d24a0e47\", \"liburlpattern_unittests\": \"8a6519df76d7b835e16a0bb23c4b6aecb08fdf2c\", \"media_blink_unittests\": \"2714755dd4716fcb711359e5a0d3c38139f56228\", \"media_unittests\": \"59aa0f20c10e32ba570a36dbcd3a533db0f28254\", \"message_center_unittests\": \"3150ab3e0517cc8f7b34def22b3ea832b21731c7\", \"midi_unittests\": \"a7aba47ee99e6c0800c6366d74415a79de889132\", \"mojo_core_unittests\": \"e981b3f2ed86ee1e0a459978c20571a19920bd68\", \"mojo_unittests\": \"5be9f71edab225e54436290943092048f43a331b\", \"nacl_loader_unittests\": \"e446f7f9f96a1e742922a54ed957bd27ea19a461\", \"native_theme_unittests\": \"0fad6c78ff82e28ac46a413f1a521d9cf36c44e8\", \"net_unittests\": \"a953ccb7254aa97952ca7a1b8d6d8bb9e743b71e\", \"notification_helper_unittests\": \"494db83b17e6b6275f9a4e02552d2b85bf3ed4f0\", \"pdf_unittests\": \"231440c545512b2120e10dbefa9ced923dc9b6bc\", \"perfetto_unittests\": \"8625b7542f05d72eaf2638b55a5d8e03b9758226\", \"ppapi_unittests\": \"4b66d726bc6a4933db84f0957955ee03c1580565\", \"printing_unittests\": \"58949b5aff5c132e862a9da16c91c7a39d64b486\", \"remoting_unittests\": \"bfb41a753fbdcfb9d7276ec159e38546c846e0b9\", \"sbox_integration_tests\": \"21ee6a037cb7da1ff0feeb741ff94595c613d372\", \"sbox_unittests\": \"0a42b578c4fa4815e44f943630a5b76b94a53141\", \"sbox_validation_tests\": \"03106d5ea08dfff43ceab6bd769ae52e28ac65a9\", \"service_manager_unittests\": \"85222a1a442254e8a90bfffb0f9657e10c938427\", \"services_unittests\": \"4dcd6cbb85b269d407229546b3814d181dfe0d8e\", \"setup_unittests\": \"309e629a3621080f931ed5e8b8ec081e5d770157\", \"shell_dialogs_unittests\": \"70b0c806b01082b1e130671dd99126b5fae4ff50\", \"skia_unittests\": \"6b292b4afa1e9cd32dd37a34e32d300ff8828dc3\", \"snapshot_unittests\": \"3bad1fbe4317861555f979af35945ae60c52d6a3\", \"sql_unittests\": \"f681d2cd85d2e0543860113ad252300499a2a9ef\", \"storage_unittests\": \"ca5a07471945c6403c717d94ea918e71b04a5859\", \"sync_integration_tests\": \"b0ac96e7ad5a18787e6757e49b096f45e49099b5\", \"traffic_annotation_auditor_unittests\": \"cf12724f76b0661d542c03cbb7b15d391c2d70aa\", \"ui_base_unittests\": \"512d9d9ceca221d69c100ae7920a362fec4a985b\", \"ui_touch_selection_unittests\": \"aeb45b41d978365237ea7a0fcb3a857195f2a784\", \"unit_tests\": \"d225fa7a16cff20df6a64362dda20ed65790fc3b\", \"updater_tests\": \"e1849eb98ee2729ff1ac73337815ef49050cd105\", \"url_unittests\": \"e8f33a5551472cf3b8fd69209c5a77c316906e01\", \"views_unittests\": \"091e6faba4bfec7c732bbcf87cf600c4a4959b3e\", \"viz_unittests\": \"557d93c122253ab9e274804cca9a9b116c1c7b4b\", \"vr_common_unittests\": \"7b909a5c551c2b476825263f4a2e708ae53f379b\", \"vr_pixeltests\": \"da9eb83a13e6c6e081bbe1270d401ec776659dc1\", \"weblayer_browsertests\": \"25a4e72c4857b8d2a9cedff62ada4cb8a649259b\", \"weblayer_unittests\": \"10f4e313199ba2988988be6e68be0c2dd111653d\", \"wm_unittests\": \"06ed210dbc22d705bb0b16a80e10cd25f5c72fad\", \"wtf_unittests\": \"78b348882d1cbbd27223d78edf1f0c25409688e4\", \"zlib_unittests\": \"9345de151e6e1e5571f055e4fb7eec6965f80741\", \"zucchini_unittests\": \"d571109f4423f275add2933062286100dfb7b720\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_v2\", \"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"builder_a4ee364c4b5996d1649f16c77d7186222b75eb5b75762fbbe3baeec97aac12d9_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"builder_f77c1c08af9d0a4522ad60c9181aa925a8ae87fd7ec8443f284fb40f3b6bc27b_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-8-uuz6\"], \"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\": [\"5673-2413400\"], \"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": "1612264942745019",
"status": "COMPLETED",
"status_changed_ts": "1612268564400325",
"tags": [
"build_address:luci.chromium.ci/win-asan/17129",
"builder:win-asan",
"buildset:commit/git/24ab4d3dfc08dfdffc3383fabd1fce83793f9fc7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/24ab4d3dfc08dfdffc3383fabd1fce83793f9fc7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021841485542613680",
"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/8856411423483501968/+/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:517b4c0be5035b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612268564465200",
"url": "https://ci.chromium.org/b/8856411423483501968",
"utcnow_ts": "1612268565186118"
}
}
[12:22:45.200] Invocation finished in 1h0m54.443601529s with status SUCCEEDED