[12:27:49.473] New invocation is queued and will start shortly
[12:27:50.867] Starting the invocation (attempt 1)
[12:27:50.904] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:27:50.905] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:27:50.905] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027454346477023600",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1269554cf397cb61afccc58c63deeecb5d20d570\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027454346477023600",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/1269554cf397cb61afccc58c63deeecb5d20d570",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1269554cf397cb61afccc58c63deeecb5d20d570",
"gitiles_ref:refs/heads/master"
]
}
[12:27:51.744] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606912071015729",
"id": "8862024284181129808",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1269554cf397cb61afccc58c63deeecb5d20d570\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606912071509346",
"tags": [
"build_address:luci.chromium.ci/win-asan/15985",
"builder:win-asan",
"buildset:commit/git/1269554cf397cb61afccc58c63deeecb5d20d570",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1269554cf397cb61afccc58c63deeecb5d20d570",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027454346477023600",
"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/8862024284181129808/+/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": "1606912071509570",
"url": "https://ci.chromium.org/b/8862024284181129808",
"utcnow_ts": "1606912071733302"
}
}
[12:27:51.744] Task URL: https://ci.chromium.org/b/8862024284181129808
[12:27:51.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:2:0) after 1m0s
[12:27:58.605] Received PubSub notification, asking Buildbucket for the build status
[12:27:58.687] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:51.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:2:0)
[12:28:51.861] Timer tick, asking Buildbucket for the build status
[12:28:52.193] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:52.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:4:0) after 1m0s
[12:29:52.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:4:0)
[12:29:52.264] Timer tick, asking Buildbucket for the build status
[12:29:52.370] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:52.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:5:0) after 1m0s
[12:30:52.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:5:0)
[12:30:52.351] Timer tick, asking Buildbucket for the build status
[12:30:52.816] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:52.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:6:0) after 1m0s
[12:31:52.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:6:0)
[12:31:52.826] Timer tick, asking Buildbucket for the build status
[12:31:53.022] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:53.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:7:0) after 1m0s
[12:32:53.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:7:0)
[12:32:53.062] Timer tick, asking Buildbucket for the build status
[12:32:53.264] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:53.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:8:0) after 1m0s
[12:33:53.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:8:0)
[12:33:53.287] Timer tick, asking Buildbucket for the build status
[12:33:53.422] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:53.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:9:0) after 1m0s
[12:34:53.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:9:0)
[12:34:53.469] Timer tick, asking Buildbucket for the build status
[12:34:53.602] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:53.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:10:0) after 1m0s
[12:35:53.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:10:0)
[12:35:53.595] Timer tick, asking Buildbucket for the build status
[12:35:53.740] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:53.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:11:0) after 1m0s
[12:36:53.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:11:0)
[12:36:53.734] Timer tick, asking Buildbucket for the build status
[12:36:53.819] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:53.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:12:0) after 1m0s
[12:37:53.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:12:0)
[12:37:53.891] Timer tick, asking Buildbucket for the build status
[12:37:54.114] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:54.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:13:0) after 1m0s
[12:38:54.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:13:0)
[12:38:54.247] Timer tick, asking Buildbucket for the build status
[12:38:54.425] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:54.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:14:0) after 1m0s
[12:39:54.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:14:0)
[12:39:54.523] Timer tick, asking Buildbucket for the build status
[12:39:54.735] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:54.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:15:0) after 1m0s
[12:40:55.005] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:15:0)
[12:40:55.005] Timer tick, asking Buildbucket for the build status
[12:40:55.114] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:55.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:16:0) after 1m0s
[12:41:55.295] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:16:0)
[12:41:55.295] Timer tick, asking Buildbucket for the build status
[12:41:55.396] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:55.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:17:0) after 1m0s
[12:42:55.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:17:0)
[12:42:55.542] Timer tick, asking Buildbucket for the build status
[12:42:56.431] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:56.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:18:0) after 1m0s
[12:43:56.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:18:0)
[12:43:56.480] Timer tick, asking Buildbucket for the build status
[12:43:56.588] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:56.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:19:0) after 1m0s
[12:44:56.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:19:0)
[12:44:56.617] Timer tick, asking Buildbucket for the build status
[12:44:56.680] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:56.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:20:0) after 1m0s
[12:45:56.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:20:0)
[12:45:56.733] Timer tick, asking Buildbucket for the build status
[12:45:56.862] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:56.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:21:0) after 1m0s
[12:46:56.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:21:0)
[12:46:56.897] Timer tick, asking Buildbucket for the build status
[12:46:57.042] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:57.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:22:0) after 1m0s
[12:47:57.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:22:0)
[12:47:57.098] Timer tick, asking Buildbucket for the build status
[12:47:57.287] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:57.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:23:0) after 1m0s
[12:48:57.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:23:0)
[12:48:57.299] Timer tick, asking Buildbucket for the build status
[12:48:58.213] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:58.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:24:0) after 1m0s
[12:49:58.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:24:0)
[12:49:58.233] Timer tick, asking Buildbucket for the build status
[12:49:58.299] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:58.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:25:0) after 1m0s
[12:50:58.344] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:25:0)
[12:50:58.344] Timer tick, asking Buildbucket for the build status
[12:50:58.452] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:58.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:26:0) after 1m0s
[12:51:58.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:26:0)
[12:51:58.499] Timer tick, asking Buildbucket for the build status
[12:51:58.683] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:58.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:27:0) after 1m0s
[12:52:58.702] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:27:0)
[12:52:58.702] Timer tick, asking Buildbucket for the build status
[12:52:58.821] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:58.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:28:0) after 1m0s
[12:53:58.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:28:0)
[12:53:58.832] Timer tick, asking Buildbucket for the build status
[12:53:59.933] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:59.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:29:0) after 1m0s
[12:54:59.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:29:0)
[12:54:59.919] Timer tick, asking Buildbucket for the build status
[12:55:00.214] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:00.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:30:0) after 1m0s
[12:56:00.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:30:0)
[12:56:00.265] Timer tick, asking Buildbucket for the build status
[12:56:00.339] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:00.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:31:0) after 1m0s
[12:57:00.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:31:0)
[12:57:00.359] Timer tick, asking Buildbucket for the build status
[12:57:00.475] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:00.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:32:0) after 1m0s
[12:58:00.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:32:0)
[12:58:00.498] Timer tick, asking Buildbucket for the build status
[12:58:00.607] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:00.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:33:0) after 1m0s
[12:59:00.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:33:0)
[12:59:00.636] Timer tick, asking Buildbucket for the build status
[12:59:00.818] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:00.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:34:0) after 1m0s
[13:00:00.821] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:34:0)
[13:00:00.821] Timer tick, asking Buildbucket for the build status
[13:00:01.111] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:01.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:35:0) after 1m0s
[13:01:01.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:35:0)
[13:01:01.135] Timer tick, asking Buildbucket for the build status
[13:01:01.305] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:01.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:36:0) after 1m0s
[13:02:01.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:36:0)
[13:02:01.333] Timer tick, asking Buildbucket for the build status
[13:02:01.503] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:01.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:37:0) after 1m0s
[13:03:01.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:37:0)
[13:03:01.548] Timer tick, asking Buildbucket for the build status
[13:03:02.084] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:02.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:38:0) after 1m0s
[13:04:02.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:38:0)
[13:04:02.108] Timer tick, asking Buildbucket for the build status
[13:04:02.560] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:02.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:39:0) after 1m0s
[13:05:02.642] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:39:0)
[13:05:02.642] Timer tick, asking Buildbucket for the build status
[13:05:02.960] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:02.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:40:0) after 1m0s
[13:06:03.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:40:0)
[13:06:03.014] Timer tick, asking Buildbucket for the build status
[13:06:03.784] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:03.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:41:0) after 1m0s
[13:07:03.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:41:0)
[13:07:03.828] Timer tick, asking Buildbucket for the build status
[13:07:03.983] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:03.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:42:0) after 1m0s
[13:08:04.021] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:42:0)
[13:08:04.021] Timer tick, asking Buildbucket for the build status
[13:08:04.130] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:04.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:43:0) after 1m0s
[13:09:04.221] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:43:0)
[13:09:04.221] Timer tick, asking Buildbucket for the build status
[13:09:04.329] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:04.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:44:0) after 1m0s
[13:10:04.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:44:0)
[13:10:04.392] Timer tick, asking Buildbucket for the build status
[13:10:04.840] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:04.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:45:0) after 1m0s
[13:11:04.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:45:0)
[13:11:04.870] Timer tick, asking Buildbucket for the build status
[13:11:04.941] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:04.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:46:0) after 1m0s
[13:12:05.021] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:46:0)
[13:12:05.021] Timer tick, asking Buildbucket for the build status
[13:12:05.785] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:05.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:47:0) after 1m0s
[13:13:05.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:47:0)
[13:13:05.875] Timer tick, asking Buildbucket for the build status
[13:13:06.804] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:06.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:48:0) after 1m0s
[13:14:06.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:48:0)
[13:14:06.826] Timer tick, asking Buildbucket for the build status
[13:14:07.283] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:07.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:49:0) after 1m0s
[13:15:07.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:49:0)
[13:15:07.309] Timer tick, asking Buildbucket for the build status
[13:15:07.590] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:07.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:50:0) after 1m0s
[13:16:07.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:50:0)
[13:16:07.758] Timer tick, asking Buildbucket for the build status
[13:16:07.928] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:07.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:51:0) after 1m0s
[13:17:07.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:51:0)
[13:17:07.947] Timer tick, asking Buildbucket for the build status
[13:17:10.098] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:10.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:52:0) after 1m0s
[13:18:10.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:52:0)
[13:18:10.162] Timer tick, asking Buildbucket for the build status
[13:18:10.305] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:10.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:53:0) after 1m0s
[13:19:10.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:53:0)
[13:19:10.351] Timer tick, asking Buildbucket for the build status
[13:19:10.432] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:10.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:54:0) after 1m0s
[13:20:10.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:54:0)
[13:20:10.504] Timer tick, asking Buildbucket for the build status
[13:20:10.655] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:10.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:55:0) after 1m0s
[13:21:10.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:55:0)
[13:21:10.728] Timer tick, asking Buildbucket for the build status
[13:21:10.844] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:10.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:56:0) after 1m0s
[13:22:10.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:56:0)
[13:22:10.888] Timer tick, asking Buildbucket for the build status
[13:22:11.013] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:11.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:57:0) after 1m0s
[13:23:11.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:57:0)
[13:23:11.036] Timer tick, asking Buildbucket for the build status
[13:23:11.165] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:11.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:58:0) after 1m0s
[13:24:11.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:58:0)
[13:24:11.192] Timer tick, asking Buildbucket for the build status
[13:24:11.262] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:11.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:59:0) after 1m0s
[13:25:11.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:59:0)
[13:25:11.294] Timer tick, asking Buildbucket for the build status
[13:25:11.480] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:11.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:60:0) after 1m0s
[13:26:11.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:60:0)
[13:26:11.524] Timer tick, asking Buildbucket for the build status
[13:26:11.696] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:11.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:61:0) after 1m0s
[13:27:11.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:61:0)
[13:27:11.767] Timer tick, asking Buildbucket for the build status
[13:27:11.866] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:11.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:62:0) after 1m0s
[13:28:11.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:62:0)
[13:28:11.885] Timer tick, asking Buildbucket for the build status
[13:28:12.033] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:12.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:63:0) after 1m0s
[13:29:12.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:63:0)
[13:29:12.079] Timer tick, asking Buildbucket for the build status
[13:29:12.757] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:12.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:64:0) after 1m0s
[13:30:12.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:64:0)
[13:30:12.813] Timer tick, asking Buildbucket for the build status
[13:30:13.299] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:13.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:65:0) after 1m0s
[13:31:13.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:65:0)
[13:31:13.365] Timer tick, asking Buildbucket for the build status
[13:31:13.459] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:13.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:66:0) after 1m0s
[13:32:13.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:66:0)
[13:32:13.512] Timer tick, asking Buildbucket for the build status
[13:32:13.903] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:13.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:67:0) after 1m0s
[13:33:13.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:67:0)
[13:33:13.968] Timer tick, asking Buildbucket for the build status
[13:33:18.257] Build 8862024284181129808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:18.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027454346477023600:68:0) after 1m0s
[13:34:15.966] Received PubSub notification, asking Buildbucket for the build status
[13:34:16.102] Build 8862024284181129808: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:34:16.102] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606916054711907",
"created_by": "project:chromium",
"created_ts": "1606912071015729",
"id": "8862024284181129808",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1269554cf397cb61afccc58c63deeecb5d20d570\"}}",
"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\": \"1269554cf397cb61afccc58c63deeecb5d20d570\", \"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-9-v8md\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15985, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"0c47015b57a03230e0cab10532c3deed80604512\", \"got_dawn_revision\": \"bb913a94da4f22f7322a1e8a55295ac69c618ff4\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"1269554cf397cb61afccc58c63deeecb5d20d570\", \"got_revision_cp\": \"refs/heads/master@{#832777}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"bfb211914759cc5deffbe9419cd1c2cae0573488\", \"got_v8_revision\": \"b3456dd37acf448bb7e41b1dfc6eee5d28f38081\", \"got_v8_revision_cp\": \"refs/heads/8.9.75@{#1}\", \"got_webrtc_revision\": \"1afe2be9a9228e0b9d546a7dd9099584ed3fce4c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32737}\", \"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\": \"1269554cf397cb61afccc58c63deeecb5d20d570\", \"swarm_hashes_refs/heads/master(at){#832777}_without_patch\": {\"absl_hardening_tests\": \"dc31d91eaa97b8dbb14fd2fb24a998859d155fa9\", \"accessibility_unittests\": \"7718f49f481ab0f2d49335692a6fb801debefb92\", \"angle_unittests\": \"32ac1df6cd4f25df56a87f70e8809a6a536998e7\", \"app_shell_unittests\": \"f107d44e75a6af168853d1426efe1805cd3eceaf\", \"aura_unittests\": \"8cd179c054af5d2ff9a2c834630ea0473e2cb9a1\", \"base_unittests\": \"860975071d88ea4bec1cb6a6b95458c988f665fc\", \"base_util_unittests\": \"aa903ff8482c504dc9e8880650959c9f4eef20eb\", \"blink_common_unittests\": \"3f6ef3dbe2427ae6ef10fae8c574f382f7880eff\", \"blink_fuzzer_unittests\": \"b90f1c229bdb06a4cf575b2ea78e3cc5dbd94007\", \"blink_heap_unittests\": \"76554a8a044a1cc210389c17ee216c5e654e4883\", \"blink_platform_unittests\": \"c0a45a9a760918cd0d05ddd70cdc41e3c0a790d5\", \"blink_unittests\": \"cee82204ccf89b0248e885ac316aac7a19e0ef38\", \"boringssl_crypto_tests\": \"8d12f39017f152be14470363b6c1536570ede1a3\", \"boringssl_ssl_tests\": \"972f989c7594fec3c0598c74f925202c6ae04563\", \"browser_switcher_bho_unittests\": \"57c2488da344d79efd83a8bf64dfb75d378bb721\", \"browser_tests\": \"ce1271edbce2e12331192980a4c94362e1664286\", \"capture_unittests\": \"1647bc18b70f9e93118b6e449bf8ec84da761ede\", \"cast_unittests\": \"44d2f63de37d8f83b80301de09f3ee6ac8fe7981\", \"cc_unittests\": \"8db6c36b8e844b0ee5c32385cad56c513c9f3f98\", \"chrome_app_unittests\": \"e1fbabf4c21d069988e549289d77b747009998b1\", \"chrome_cleaner_unittests\": \"c60b92c5a43c881024b12190a78a85d0c4419a70\", \"chrome_elf_unittests\": \"c264479f440679fb0fc3254dbf3e985faae93bac\", \"chromedriver_unittests\": \"d719ecc7420b23a30fb0450d6f5df62723ac8928\", \"components_browsertests\": \"6c52b3992595857e437bd9936959b6ae7c9edc4f\", \"components_unittests\": \"1489c6f2767d03557cec0518faa6fbd9f04c9f62\", \"compositor_unittests\": \"d396fd12f7379c63c44215f0beac979ed6fddaca\", \"content_browsertests\": \"18f2e641bd7edeadee46486d5513bc5fa9ac1939\", \"content_unittests\": \"f47b2d57acae017a6d4847f7cde9813f93564601\", \"courgette_unittests\": \"637cb00daf4805fef763cb92572486d4448fd6d5\", \"crashpad_tests\": \"b3dcf3039730513ed653e24a006020e8fa46434a\", \"cronet_tests\": \"e5af39c9e21090aa38ddfcc83dbe7923f4f52d62\", \"cronet_unittests\": \"a9573f6384003adfdd7a07a2bc913f06c61ebe92\", \"crypto_unittests\": \"5dbbe32f681c2568ae7124e5b40d0c59ba3d1ec1\", \"delayloads_unittests\": \"049e3ff3620950adc46483ff03ca009698818780\", \"device_unittests\": \"f1b7f2356005758fb7d6b2a3ddd7553ef5c83b72\", \"display_unittests\": \"ac979e6fc2f7d4099e51c8dafb376bb48a8fbff3\", \"elevation_service_unittests\": \"8a53ccbbdc51fdffc3be495bf27b2ecd0a181c5f\", \"events_unittests\": \"9e4255e1ed145412d9cbca1f089773bf11af8a76\", \"extensions_browsertests\": \"a13f7258f52fcebce66a7503ff82d5b799bc7d41\", \"extensions_unittests\": \"a9ed5452045e360cc769e354dad503bd89f5439a\", \"filesystem_service_unittests\": \"0f510db47e08aa1757683a24935467ede8d02485\", \"gcm_unit_tests\": \"016aa95a1abdec0f1b28b4c1a1bdb4558cbfd19c\", \"gcp_unittests\": \"00ca51cbf1e12afd85b44f94f5589b0793d32573\", \"gfx_unittests\": \"27c12b00076bdbb6f156ba9a157b3006647f9f8a\", \"gin_unittests\": \"c918f9c05d8776f04cb9bb19c5d11659854a90bd\", \"google_apis_unittests\": \"746760c984e4869e3a3bbd5b5dac0a9b1a20e368\", \"gpu_unittests\": \"ce8d1b9515412f4b0e800511131ec6e25bafa827\", \"gwp_asan_unittests\": \"12e6ff4403e22fc9834aa3854812da0e83e9335d\", \"headless_browsertests\": \"605c6aacfc1024d36d160662f2e27b0677ca7414\", \"headless_unittests\": \"9e502527545677ebc7221253b670502371ec2822\", \"install_static_unittests\": \"18a230321e72319182a48db5a9e35eb99ddebcd1\", \"installer_util_unittests\": \"dd2bf318742e5c80bbab1a8975459d9815f397ae\", \"interactive_ui_tests\": \"0b3e181aca028f18192783a9a5f3e9833ff2f115\", \"ipc_tests\": \"93727ea080ca25026e5bd59c6b9a4dcc62fcc899\", \"jingle_unittests\": \"893ad37dbd763c534c8093d91a3c347ff4c1c3fb\", \"latency_unittests\": \"77171bb7c5cc4809dbc86562e7cdb69b602f0e53\", \"libjingle_xmpp_unittests\": \"32526b2557490beb4cdcb1e12061b7e3a86871dd\", \"liburlpattern_unittests\": \"b948a567e9869f6572500a25cbf9c0cd404a142c\", \"media_blink_unittests\": \"65ade07130ba677b75c169c2d02ba95058452ea2\", \"media_unittests\": \"57825538af98142137a2bd55a96e5af8993839ea\", \"message_center_unittests\": \"f1b8880938ef6b1643682fa705ccf37a3abec9dd\", \"midi_unittests\": \"a68ee106d014c618ec4d568f0730d3e3e34974f2\", \"mojo_core_unittests\": \"6640c3c2e1737a870fc9368622375b810afb69c7\", \"mojo_unittests\": \"7ff04630cf934591710971722e3f3b02f9e629d5\", \"nacl_loader_unittests\": \"d690eced440423d55a7c3ab3f478f8b5cada2e22\", \"native_theme_unittests\": \"04ca29eaf6e1143837771bb7d43cfa88aff5d5c2\", \"net_unittests\": \"2fd5fc5ed76934cae339a26265387708fd6af11d\", \"notification_helper_unittests\": \"5b46fa75aa1e15a165d71e942cf5361c00488d0a\", \"pdf_unittests\": \"0b0262204349da977b8c4181f8b454a6b3944678\", \"perfetto_unittests\": \"cdd34f7d3ba8ad59e058cffc477c0a96a03f32f6\", \"ppapi_unittests\": \"1e82455a070b37f959705d8fb9dc176eefc33edf\", \"printing_unittests\": \"11eaf56d3cee658ede8843543d341099e04a2b7c\", \"remoting_unittests\": \"49a8114064b74c43687274903e4889d4689aecd0\", \"sbox_integration_tests\": \"082479627d426d3f303e687c986ac9199879056a\", \"sbox_unittests\": \"73f421fa2665a5ddca85669380462530b917fa94\", \"sbox_validation_tests\": \"dff7d8209aa5d32d2f1d4503a9a906758d375451\", \"service_manager_unittests\": \"1327dbfbd19317f5d38f1f312b1b6b0aae166d35\", \"services_unittests\": \"d5a22d0bcb5cf80c53f6f2a6f17b3fe6e4fc4f94\", \"setup_unittests\": \"c92931bb2a9985943173b33e8460d654a9b5c8e3\", \"shell_dialogs_unittests\": \"9923c72d9f21a4f8ab96cbedc2150ae1175d0dee\", \"skia_unittests\": \"f69a53bdc62642ab56bc208e22473ea4513d0ff0\", \"snapshot_unittests\": \"b0a69bb68bf1dd1f361b08a71606c2cb275265f6\", \"sql_unittests\": \"755d50d8467bd3742aea3bfaf1588276b58235f9\", \"storage_unittests\": \"3ffbdfaf1af872408e69f5e3682ba6c63096c430\", \"sync_integration_tests\": \"83ab0432bc20044c58fdf9e8d51592e4089aef67\", \"traffic_annotation_auditor_unittests\": \"9b769baa7d5e6fcf6611bcc5db6e8d10d4da96f0\", \"ui_base_unittests\": \"d67e7fd4f57b01f514f559a8531d22739a6216c4\", \"ui_touch_selection_unittests\": \"3065ea33fe23a1ba2b16c33c56415c26289d3c21\", \"unit_tests\": \"c1cc6e96a8d47e21ad73ddabdfa97ceae44d1b6a\", \"updater_tests\": \"5e211dbbc9db929d371349a9abbb9d5ef0b1e4cd\", \"url_unittests\": \"c41fc512f0b62326a33811f21b3886a4fd2aa9c5\", \"views_unittests\": \"9ff3c7a638e3e0f5ab8647b680b5278beb30ae6f\", \"viz_unittests\": \"8420645acfcd9746bbb487f048c95221d0c537ef\", \"vr_common_unittests\": \"b30578fd1526d8f3faa947da4c26dba7ed6c5a61\", \"vr_pixeltests\": \"0de4f4987137b3710cf8a6cb56906a19742c269d\", \"weblayer_browsertests\": \"9a9b935966540cbede6ad9a312f036e2893e2292\", \"weblayer_unittests\": \"1910617a7d64edd727e44fb5d1913ff2b9b31928\", \"wm_unittests\": \"489470b05ff1a6f3866beefdda6a836295d4ff28\", \"wtf_unittests\": \"b23c2acad1b13913cf80b8f303ea48f909302b5d\", \"zlib_unittests\": \"c2bee511ceba9b0bad394ccef7bc33fbb46dad1a\", \"zucchini_unittests\": \"854e9ea275f030c4a9543ad590f642f93a6cfe9b\"}}, \"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-9-v8md\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5640-d0eb544\"], \"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": "1606912073986559",
"status": "COMPLETED",
"status_changed_ts": "1606916055254056",
"tags": [
"build_address:luci.chromium.ci/win-asan/15985",
"builder:win-asan",
"buildset:commit/git/1269554cf397cb61afccc58c63deeecb5d20d570",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1269554cf397cb61afccc58c63deeecb5d20d570",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027454346477023600",
"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/8862024284181129808/+/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:503c3de0f9d2dd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1606916055258251",
"url": "https://ci.chromium.org/b/8862024284181129808",
"utcnow_ts": "1606916056078363"
}
}
[13:34:16.102] Invocation finished in 1h6m26.660351317s with status SUCCEEDED