[02:11:37.754] New invocation is queued and will start shortly
[02:11:38.895] Starting the invocation (attempt 1)
[02:11:38.937] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:11:38.937] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:11:38.937] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019067596245273440",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4d077d482975f2e7c77a5b7a49b9ebc365f22c76\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019067596245273440",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/4d077d482975f2e7c77a5b7a49b9ebc365f22c76",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4d077d482975f2e7c77a5b7a49b9ebc365f22c76",
"gitiles_ref:refs/heads/master"
]
}
[02:11:39.859] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614910299049445",
"id": "8853637534222344240",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4d077d482975f2e7c77a5b7a49b9ebc365f22c76\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614910299687972",
"tags": [
"build_address:luci.chromium.ci/win-asan/17638",
"builder:win-asan",
"buildset:commit/git/4d077d482975f2e7c77a5b7a49b9ebc365f22c76",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4d077d482975f2e7c77a5b7a49b9ebc365f22c76",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019067596245273440",
"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/8853637534222344240/+/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": "1614910299688102",
"url": "https://ci.chromium.org/b/8853637534222344240",
"utcnow_ts": "1614910299852644"
}
}
[02:11:39.859] Task URL: https://ci.chromium.org/b/8853637534222344240
[02:11:39.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:2:0) after 1m0s
[02:11:41.858] Received PubSub notification, asking Buildbucket for the build status
[02:11:41.915] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:39.876] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:2:0)
[02:12:39.876] Timer tick, asking Buildbucket for the build status
[02:12:39.952] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:39.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:4:0) after 1m0s
[02:13:39.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:4:0)
[02:13:39.992] Timer tick, asking Buildbucket for the build status
[02:13:40.069] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:40.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:5:0) after 1m0s
[02:14:40.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:5:0)
[02:14:40.107] Timer tick, asking Buildbucket for the build status
[02:14:41.177] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:41.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:6:0) after 1m0s
[02:15:41.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:6:0)
[02:15:41.206] Timer tick, asking Buildbucket for the build status
[02:15:41.274] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:41.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:7:0) after 1m0s
[02:16:41.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:7:0)
[02:16:41.329] Timer tick, asking Buildbucket for the build status
[02:16:41.445] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:41.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:8:0) after 1m0s
[02:17:42.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:8:0)
[02:17:42.227] Timer tick, asking Buildbucket for the build status
[02:17:42.333] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:42.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:9:0) after 1m0s
[02:18:42.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:9:0)
[02:18:42.515] Timer tick, asking Buildbucket for the build status
[02:18:42.657] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:42.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:10:0) after 1m0s
[02:19:42.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:10:0)
[02:19:42.957] Timer tick, asking Buildbucket for the build status
[02:19:43.228] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:43.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:11:0) after 1m0s
[02:20:43.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:11:0)
[02:20:43.361] Timer tick, asking Buildbucket for the build status
[02:20:43.725] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:43.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:12:0) after 1m0s
[02:21:45.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:12:0)
[02:21:45.034] Timer tick, asking Buildbucket for the build status
[02:21:45.132] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:45.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:13:0) after 1m0s
[02:22:45.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:13:0)
[02:22:45.902] Timer tick, asking Buildbucket for the build status
[02:22:46.151] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:46.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:14:0) after 1m0s
[02:23:46.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:14:0)
[02:23:46.603] Timer tick, asking Buildbucket for the build status
[02:23:46.693] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:46.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:15:0) after 1m0s
[02:24:47.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:15:0)
[02:24:47.786] Timer tick, asking Buildbucket for the build status
[02:24:47.953] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:47.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:16:0) after 1m0s
[02:25:48.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:16:0)
[02:25:48.208] Timer tick, asking Buildbucket for the build status
[02:25:48.300] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:48.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:17:0) after 1m0s
[02:26:49.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:17:0)
[02:26:49.190] Timer tick, asking Buildbucket for the build status
[02:26:49.273] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:49.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:18:0) after 1m0s
[02:27:49.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:18:0)
[02:27:49.981] Timer tick, asking Buildbucket for the build status
[02:27:50.063] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:50.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:19:0) after 1m0s
[02:28:50.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:19:0)
[02:28:50.467] Timer tick, asking Buildbucket for the build status
[02:28:50.544] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:50.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:20:0) after 1m0s
[02:29:50.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:20:0)
[02:29:50.648] Timer tick, asking Buildbucket for the build status
[02:29:50.754] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:50.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:21:0) after 1m0s
[02:30:50.773] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:21:0)
[02:30:50.773] Timer tick, asking Buildbucket for the build status
[02:30:50.854] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:50.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:22:0) after 1m0s
[02:31:51.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:22:0)
[02:31:51.651] Timer tick, asking Buildbucket for the build status
[02:31:51.979] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:51.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:23:0) after 1m0s
[02:32:52.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:23:0)
[02:32:52.028] Timer tick, asking Buildbucket for the build status
[02:32:52.110] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:52.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:24:0) after 1m0s
[02:33:52.141] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:24:0)
[02:33:52.141] Timer tick, asking Buildbucket for the build status
[02:33:53.420] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:53.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:25:0) after 1m0s
[02:34:53.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:25:0)
[02:34:53.456] Timer tick, asking Buildbucket for the build status
[02:34:53.569] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:53.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:26:0) after 1m0s
[02:35:53.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:26:0)
[02:35:53.558] Timer tick, asking Buildbucket for the build status
[02:35:53.817] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:53.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:27:0) after 1m0s
[02:36:53.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:27:0)
[02:36:53.805] Timer tick, asking Buildbucket for the build status
[02:36:53.936] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:53.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:28:0) after 1m0s
[02:37:53.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:28:0)
[02:37:53.973] Timer tick, asking Buildbucket for the build status
[02:37:54.049] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:54.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:29:0) after 1m0s
[02:38:54.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:29:0)
[02:38:54.057] Timer tick, asking Buildbucket for the build status
[02:38:54.172] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:54.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:30:0) after 1m0s
[02:39:54.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:30:0)
[02:39:54.228] Timer tick, asking Buildbucket for the build status
[02:39:54.348] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:54.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:31:0) after 1m0s
[02:40:54.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:31:0)
[02:40:54.434] Timer tick, asking Buildbucket for the build status
[02:40:54.542] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:54.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:32:0) after 1m0s
[02:41:54.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:32:0)
[02:41:54.603] Timer tick, asking Buildbucket for the build status
[02:41:54.747] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:54.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:33:0) after 1m0s
[02:42:54.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:33:0)
[02:42:54.931] Timer tick, asking Buildbucket for the build status
[02:42:55.020] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:55.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:34:0) after 1m0s
[02:43:55.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:34:0)
[02:43:55.118] Timer tick, asking Buildbucket for the build status
[02:43:55.192] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:55.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:35:0) after 1m0s
[02:44:55.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:35:0)
[02:44:55.287] Timer tick, asking Buildbucket for the build status
[02:44:55.369] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:55.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:36:0) after 1m0s
[02:45:55.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:36:0)
[02:45:55.400] Timer tick, asking Buildbucket for the build status
[02:45:55.560] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:55.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:37:0) after 1m0s
[02:46:55.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:37:0)
[02:46:55.593] Timer tick, asking Buildbucket for the build status
[02:46:55.684] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:55.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:38:0) after 1m0s
[02:47:56.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:38:0)
[02:47:56.025] Timer tick, asking Buildbucket for the build status
[02:47:56.589] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:56.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:39:0) after 1m0s
[02:48:56.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:39:0)
[02:48:56.898] Timer tick, asking Buildbucket for the build status
[02:48:56.998] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:56.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:40:0) after 1m0s
[02:49:57.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:40:0)
[02:49:57.164] Timer tick, asking Buildbucket for the build status
[02:49:57.842] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:57.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:41:0) after 1m0s
[02:50:58.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:41:0)
[02:50:58.189] Timer tick, asking Buildbucket for the build status
[02:50:58.808] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:58.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:42:0) after 1m0s
[02:51:58.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:42:0)
[02:51:58.915] Timer tick, asking Buildbucket for the build status
[02:51:59.068] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:59.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:43:0) after 1m0s
[02:52:59.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:43:0)
[02:52:59.208] Timer tick, asking Buildbucket for the build status
[02:52:59.614] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:59.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:44:0) after 1m0s
[02:53:59.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:44:0)
[02:53:59.699] Timer tick, asking Buildbucket for the build status
[02:53:59.795] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:59.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:45:0) after 1m0s
[02:54:59.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:45:0)
[02:54:59.813] Timer tick, asking Buildbucket for the build status
[02:54:59.956] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:59.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:46:0) after 1m0s
[02:55:59.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:46:0)
[02:55:59.949] Timer tick, asking Buildbucket for the build status
[02:56:00.077] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:00.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:47:0) after 1m0s
[02:57:00.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:47:0)
[02:57:00.098] Timer tick, asking Buildbucket for the build status
[02:57:00.329] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:00.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:48:0) after 1m0s
[02:58:00.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:48:0)
[02:58:00.304] Timer tick, asking Buildbucket for the build status
[02:58:00.455] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:00.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:49:0) after 1m0s
[02:59:00.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:49:0)
[02:59:00.490] Timer tick, asking Buildbucket for the build status
[02:59:00.572] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:00.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:50:0) after 1m0s
[03:00:00.862] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:50:0)
[03:00:00.862] Timer tick, asking Buildbucket for the build status
[03:00:00.969] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:00.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:51:0) after 1m0s
[03:01:01.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:51:0)
[03:01:01.055] Timer tick, asking Buildbucket for the build status
[03:01:01.340] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:01.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:52:0) after 1m0s
[03:02:01.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:52:0)
[03:02:01.606] Timer tick, asking Buildbucket for the build status
[03:02:01.745] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:01.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:53:0) after 1m0s
[03:03:02.175] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:53:0)
[03:03:02.175] Timer tick, asking Buildbucket for the build status
[03:03:02.407] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:02.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:54:0) after 1m0s
[03:04:03.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:54:0)
[03:04:03.195] Timer tick, asking Buildbucket for the build status
[03:04:03.321] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:03.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:55:0) after 1m0s
[03:05:03.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:55:0)
[03:05:03.578] Timer tick, asking Buildbucket for the build status
[03:05:03.703] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:03.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:56:0) after 1m0s
[03:06:03.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:56:0)
[03:06:03.855] Timer tick, asking Buildbucket for the build status
[03:06:03.936] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:03.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:57:0) after 1m0s
[03:07:04.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:57:0)
[03:07:04.159] Timer tick, asking Buildbucket for the build status
[03:07:04.463] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:04.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:58:0) after 1m0s
[03:08:04.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:58:0)
[03:08:04.625] Timer tick, asking Buildbucket for the build status
[03:08:05.001] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:05.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:59:0) after 1m0s
[03:09:05.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:59:0)
[03:09:05.095] Timer tick, asking Buildbucket for the build status
[03:09:05.183] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:05.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:60:0) after 1m0s
[03:10:05.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:60:0)
[03:10:05.408] Timer tick, asking Buildbucket for the build status
[03:10:05.497] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:05.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:61:0) after 1m0s
[03:11:06.071] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:61:0)
[03:11:06.071] Timer tick, asking Buildbucket for the build status
[03:11:06.182] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:06.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:62:0) after 1m0s
[03:12:06.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:62:0)
[03:12:06.231] Timer tick, asking Buildbucket for the build status
[03:12:06.421] Build 8853637534222344240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:06.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019067596245273440:63:0) after 1m0s
[03:12:29.780] Received PubSub notification, asking Buildbucket for the build status
[03:12:29.867] Build 8853637534222344240: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:12:29.867] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614913948197837",
"created_by": "project:chromium",
"created_ts": "1614910299049445",
"id": "8853637534222344240",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4d077d482975f2e7c77a5b7a49b9ebc365f22c76\"}}",
"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\": \"4d077d482975f2e7c77a5b7a49b9ebc365f22c76\", \"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-6-55ju\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17638, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"8092ed7271d972fe40df1900537a6711eaa595ae\", \"got_dawn_revision\": \"80915849ce7d5f4c0fe834924ed420ed0002b210\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"4d077d482975f2e7c77a5b7a49b9ebc365f22c76\", \"got_revision_cp\": \"refs/heads/master@{#860084}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"6846389f25cad4233a4bd04fd3fc6c60ae30ff35\", \"got_v8_revision\": \"a88754d974902819a903536937527fd8465d871d\", \"got_v8_revision_cp\": \"refs/heads/9.1.46@{#1}\", \"got_webrtc_revision\": \"3208bf102b75e8ec1eaa6640dfa3b545310b1e02\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33382}\", \"is_cached\": true, \"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\": \"4d077d482975f2e7c77a5b7a49b9ebc365f22c76\", \"swarm_hashes_refs/heads/master(at){#860084}_without_patch\": {\"absl_hardening_tests\": \"4c0ff2b151fe68688fadf7cd9b3e73c50f53f113\", \"accessibility_unittests\": \"45fdead2e2e6d4dba64c073591e1e065600ddfe1\", \"angle_unittests\": \"d54ad282167bebe39a5b1483b38935e36528a9f0\", \"app_shell_unittests\": \"ce0e4bfd114038e8b4bd3bcf74f213c9dc07fa46\", \"aura_unittests\": \"9eaf135b309b2235c24009d8198a15116eb5bf7a\", \"base_unittests\": \"e1b5ba0ab5ba6f1ef889c8e07dc0ace7499e551b\", \"base_util_unittests\": \"d1834486dbf9e483c6fdf215877761191b15a9cc\", \"blink_common_unittests\": \"1753bfed2d0a2ebee5b05eda3cd471294ceb8397\", \"blink_fuzzer_unittests\": \"92c52fe4d74eb2d64cfdc7be2e76b74638e3d0a1\", \"blink_heap_unittests\": \"ae01766275ead00a01c66df7ff647741fda3e177\", \"blink_platform_unittests\": \"e809e6aaefc06e50fa99c0a8247cbb02c7650278\", \"blink_unittests\": \"9721753311e1b2174d89e0ba98cb2469134a67ca\", \"boringssl_crypto_tests\": \"2ac06684b2c0f391cbfe668a3d6607b81142186c\", \"boringssl_ssl_tests\": \"1b4dd1c5c271ff4e55a57641fe4e699abc5a3aa6\", \"browser_switcher_bho_unittests\": \"faba96fd3db89d9b021276438cf50d4debdc0bd3\", \"browser_tests\": \"308e4738800f4c0eb4a20bcd8392b2945f67db7d\", \"capture_unittests\": \"19fd7363736416adad64e8dfca6e9a56fe9ffe7c\", \"cast_unittests\": \"c05136d93ed6b97d78fb1f2696befef3a24f860d\", \"cc_unittests\": \"4a646d36872d87e2fb349a0e88a1a9cecc3aba66\", \"chrome_app_unittests\": \"3888a661028d5ed4a8e772da3de698c7b64ef0e5\", \"chrome_cleaner_unittests\": \"5b88f60e2d9cf56b4808133162fb78e4f5befcf7\", \"chrome_elf_unittests\": \"b47239902a571a1716c81553293c244245f5559b\", \"chromedriver_unittests\": \"327be190343851697503455a1ee6dabe51bb953b\", \"color_unittests\": \"9c08edf85116fb971bba13b2e6149a18dcfa2aee\", \"components_browsertests\": \"282f364bb953ef1bc22a775681aa0b11008f07f8\", \"components_unittests\": \"26bff85c454db3f0251ca2adb76303c9bacae91d\", \"compositor_unittests\": \"db37404404e8e20b5e56c0df426d4098fa5d2fa1\", \"content_browsertests\": \"59b5e6c4145c28dff633ce681d4e51e5bef2e325\", \"content_unittests\": \"2e20b17419e85f003ea0df3a0a773c62584c99ff\", \"courgette_unittests\": \"e3bb8804578a0087bce78426dd330d6d6bcfae44\", \"crashpad_tests\": \"fee4713bac455aaeb6fa867eacb17476227c37a2\", \"cronet_tests\": \"e30847859ee4b46f2f4a338349a184d1943be348\", \"cronet_unittests\": \"05c9e0201f30855571512d5bfbde15b51cf6ebc0\", \"crypto_unittests\": \"1b223f753a47d40d47671c3834ae7a25e279cedb\", \"delayloads_unittests\": \"78066db7fabd11897fd92bb5b243acbb92400c92\", \"device_unittests\": \"d2d7c7a9594dc7000c29fcce039c3eeceb1fb191\", \"display_unittests\": \"269f82cc4d88da6ea39104ba467130b57ec07d10\", \"elevation_service_unittests\": \"fdc0d85f009b97f98980e5555943af2b8dbe442f\", \"events_unittests\": \"0386aaefa3f4b315e22c0853414851e43a05d985\", \"extensions_browsertests\": \"d336c9d5428de18450860180cc21f84c9d23ea69\", \"extensions_unittests\": \"3605f85e586f6a8e3e4d91c8299be0c5f160da7d\", \"filesystem_service_unittests\": \"898b86b2e5798f44eb4ccbc667d7d41fdb870d7f\", \"gcm_unit_tests\": \"144290bc240e878b2daf1cb7867e85609a2faa26\", \"gcp_unittests\": \"c5e4cc0914e1beeec723186a140e74f0ecf68218\", \"gfx_unittests\": \"08cc4338cb3a3442b3185fb66faceaed3bd91235\", \"gin_unittests\": \"f0cbc2b7ff36d8264cf6bc2e6af1eb62530c16bd\", \"google_apis_unittests\": \"aac6f5856ea2c7d387d6d4546c326585b017eda5\", \"gpu_unittests\": \"529edd66eb3d39b79fa57613762bb891e03df655\", \"gwp_asan_unittests\": \"d0f9fc5a4a9ad3b28a107fc2f3431475824c0fb9\", \"headless_browsertests\": \"6096f5187b6b444e4ac9bade4bf17887350ba71b\", \"headless_unittests\": \"b4a208e72bea088cb0317dd50c4ec7a8505d5164\", \"install_static_unittests\": \"92af9152ec2482dd51b72bbb3dad10763fb3e556\", \"installer_util_unittests\": \"aca82ac1a49ec0f8a9f8083afaf9015124076874\", \"interactive_ui_tests\": \"548c7c741f2dcb39061c7461920fd0df55ba114b\", \"ipc_tests\": \"3686b3ce1a5f7200c73b0ce1a32321ee3865070e\", \"jingle_unittests\": \"dca1a7412e9ebf462445e5bc0ec1a36afba8c898\", \"latency_unittests\": \"daf4b45ed8b7d95f586458cf1ea6a63d656d533e\", \"libjingle_xmpp_unittests\": \"c5d609f4ef24b9ac851ce3f181d595a595bcfc81\", \"liburlpattern_unittests\": \"f3d3f83bdfeddb27da7d751e7cb62c565b7f7673\", \"media_blink_unittests\": \"3b2a0b00d66f5a156baf930f541266dbeaefaab2\", \"media_unittests\": \"e2179c4d74911b1cb48a7cb7d2854c56c4a06f18\", \"message_center_unittests\": \"eed129b308b9ba90f7e6ce2abcab404de4a29ac3\", \"midi_unittests\": \"fc5bbbfe7a737a9df41dc25abf90b042dab0cc1a\", \"mojo_core_unittests\": \"8b8854d3b5ec812fdf3eae7acef5e2ab2c631453\", \"mojo_unittests\": \"382ab00cbe396be3da3e25c24582de630a01ef03\", \"nacl_loader_unittests\": \"c205221bf6f9ef216597389eea4482f1bbd6c0c8\", \"native_theme_unittests\": \"d2daacfed4dee46ddb6a0d21bb885e896e131c8f\", \"net_unittests\": \"9763dca178a17aa80799e487f29d079c13937c1b\", \"notification_helper_unittests\": \"11d06d74066f9acb5113120c3b07cef200ddfdea\", \"pdf_unittests\": \"7d0a4d65f42d410174664878084b408e4306add4\", \"perfetto_unittests\": \"3231ae23cf2e6f1b93979c28a3b613c76670246b\", \"ppapi_unittests\": \"05ef6b1d5b2a706d72a7b7f9db73de58d855265a\", \"printing_unittests\": \"664d43b28b1111aea3985307e20163318026a2b8\", \"remoting_unittests\": \"297e0942ec79d3a7bb6881723f359a6924b4ada4\", \"sbox_integration_tests\": \"8fc44f46b91f29c116c9eef80537ef7b4dc44abe\", \"sbox_unittests\": \"eccd358e8fda047076d7b6dbc705d75e5b3fc9f5\", \"sbox_validation_tests\": \"d2a5f46a191a80f8dfb5ef93f0a364e4a190c50c\", \"service_manager_unittests\": \"f6befeeba4abd43e93d74076854bffb13798b1d9\", \"services_unittests\": \"812ebe3deefeb0e41a2ec225b1be23fdda11d2fd\", \"setup_unittests\": \"56f463b2144a9989e591e45494ee38dd9ab247b1\", \"shell_dialogs_unittests\": \"887663a88408f49643d09dd3028b7e0a0b1bc356\", \"skia_unittests\": \"1c3679f567bada234bd046385746d0e45583f6bc\", \"snapshot_unittests\": \"89c840605225517380863e60cf347c7679813fb5\", \"sql_unittests\": \"a2f7662074eb28788c37be41674cefa87c892502\", \"storage_unittests\": \"ddb4489a1ca4d2c4317e25b2d72c0f405d616b07\", \"sync_integration_tests\": \"e82fe354db89037d60f4ea7c79da1065795adb0e\", \"traffic_annotation_auditor_unittests\": \"167c2f5249cf13470ad7e41854cf37d9302705d2\", \"ui_base_unittests\": \"780e05f8bc5232c131d9d394cfa8e6223b45cf83\", \"ui_touch_selection_unittests\": \"210457a9a44586e8be0704334d9a760f21f4c8d5\", \"unit_tests\": \"20cb8ef9535d73dc9089d03301068299e3d5f88d\", \"updater_tests\": \"4acd8b3a9b2213b6baef29a9b77db5886dc90c71\", \"url_unittests\": \"1622783435b8137e08e1229f129c111b41475267\", \"views_unittests\": \"8bfc1a59c19e0726176d4ffc5b19b4b9bdd462a6\", \"viz_unittests\": \"a8b543196f026d446f0606b1fed2e9d63f7b314c\", \"vr_common_unittests\": \"ace12773430e88980962b3d9582fb264ea49c8d6\", \"vr_pixeltests\": \"d7d598582c4196153b645b29451a6d81c621b79b\", \"weblayer_browsertests\": \"d46b804141037c28f35565a7597f2c4d436e2f52\", \"weblayer_unittests\": \"9431bbc7e06cc1632b9f22b52f69209596d23201\", \"wm_unittests\": \"b1f911146b3a9ce850e7824420d4d065e5063848\", \"wtf_unittests\": \"4eea727aa0f2d3acd519e6616c198320bbe67cf0\", \"zlib_unittests\": \"0c19d05422c62501130102b3fe10cd80f5bd0746\", \"zucchini_unittests\": \"b765e02c2867b5e283e41d009517c330dbbec958\"}}, \"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-6-55ju\"], \"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\": [\"5725-1a0d85e\"], \"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": "1614910301311650",
"status": "COMPLETED",
"status_changed_ts": "1614913948812163",
"tags": [
"build_address:luci.chromium.ci/win-asan/17638",
"builder:win-asan",
"buildset:commit/git/4d077d482975f2e7c77a5b7a49b9ebc365f22c76",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4d077d482975f2e7c77a5b7a49b9ebc365f22c76",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019067596245273440",
"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/8853637534222344240/+/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:5218f9271e436610",
"user_agent:luci-scheduler"
],
"updated_ts": "1614913948816954",
"url": "https://ci.chromium.org/b/8853637534222344240",
"utcnow_ts": "1614913949859731"
}
}
[03:12:29.867] Invocation finished in 1h0m52.130534792s with status SUCCEEDED