[08:04:32.422] New invocation is queued and will start shortly
[08:04:33.566] Starting the invocation (attempt 1)
[08:04:33.646] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:04:33.646] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:04:33.647] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030279416832065152",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b1b7241ac6057397097f534f2cacaef3e29e4489\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9030279416832065152",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/b1b7241ac6057397097f534f2cacaef3e29e4489",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b1b7241ac6057397097f534f2cacaef3e29e4489",
"gitiles_ref:refs/heads/master"
]
}
[08:04:35.382] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604217873751060",
"id": "8864849354771555056",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b1b7241ac6057397097f534f2cacaef3e29e4489\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604217874200327",
"tags": [
"build_address:luci.chromium.ci/win-asan/15391",
"builder:win-asan",
"buildset:commit/git/b1b7241ac6057397097f534f2cacaef3e29e4489",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b1b7241ac6057397097f534f2cacaef3e29e4489",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030279416832065152",
"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/8864849354771555056/+/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": "1604217874200471",
"url": "https://ci.chromium.org/b/8864849354771555056",
"utcnow_ts": "1604217875371646"
}
}
[08:04:35.382] Task URL: https://ci.chromium.org/b/8864849354771555056
[08:04:35.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:2:0) after 1m0s
[08:04:47.983] Received PubSub notification, asking Buildbucket for the build status
[08:04:48.046] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:35.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:2:0)
[08:05:35.422] Timer tick, asking Buildbucket for the build status
[08:05:35.498] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:35.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:4:0) after 1m0s
[08:06:35.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:4:0)
[08:06:35.573] Timer tick, asking Buildbucket for the build status
[08:06:35.663] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:35.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:5:0) after 1m0s
[08:07:35.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:5:0)
[08:07:35.681] Timer tick, asking Buildbucket for the build status
[08:07:35.769] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:35.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:6:0) after 1m0s
[08:08:35.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:6:0)
[08:08:35.793] Timer tick, asking Buildbucket for the build status
[08:08:36.101] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:36.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:7:0) after 1m0s
[08:09:36.120] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:7:0)
[08:09:36.121] Timer tick, asking Buildbucket for the build status
[08:09:36.381] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:36.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:8:0) after 1m0s
[08:10:36.416] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:8:0)
[08:10:36.416] Timer tick, asking Buildbucket for the build status
[08:10:36.549] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:36.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:9:0) after 1m0s
[08:11:36.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:9:0)
[08:11:36.587] Timer tick, asking Buildbucket for the build status
[08:11:36.642] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:36.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:10:0) after 1m0s
[08:12:36.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:10:0)
[08:12:36.661] Timer tick, asking Buildbucket for the build status
[08:12:36.764] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:36.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:11:0) after 1m0s
[08:13:36.803] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:11:0)
[08:13:36.803] Timer tick, asking Buildbucket for the build status
[08:13:37.064] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:37.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:12:0) after 1m0s
[08:14:37.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:12:0)
[08:14:37.133] Timer tick, asking Buildbucket for the build status
[08:14:37.213] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:37.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:13:0) after 1m0s
[08:15:37.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:13:0)
[08:15:37.261] Timer tick, asking Buildbucket for the build status
[08:15:37.421] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:37.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:14:0) after 1m0s
[08:16:37.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:14:0)
[08:16:37.442] Timer tick, asking Buildbucket for the build status
[08:16:37.549] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:37.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:15:0) after 1m0s
[08:17:37.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:15:0)
[08:17:37.569] Timer tick, asking Buildbucket for the build status
[08:17:37.931] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:37.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:16:0) after 1m0s
[08:18:37.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:16:0)
[08:18:37.999] Timer tick, asking Buildbucket for the build status
[08:18:38.123] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:38.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:17:0) after 1m0s
[08:19:38.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:17:0)
[08:19:38.144] Timer tick, asking Buildbucket for the build status
[08:19:38.317] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:38.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:18:0) after 1m0s
[08:20:38.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:18:0)
[08:20:38.302] Timer tick, asking Buildbucket for the build status
[08:20:38.459] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:38.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:19:0) after 1m0s
[08:21:38.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:19:0)
[08:21:38.494] Timer tick, asking Buildbucket for the build status
[08:21:38.645] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:38.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:20:0) after 1m0s
[08:22:38.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:20:0)
[08:22:38.708] Timer tick, asking Buildbucket for the build status
[08:22:38.807] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:38.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:21:0) after 1m0s
[08:23:38.835] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:21:0)
[08:23:38.835] Timer tick, asking Buildbucket for the build status
[08:23:38.971] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:38.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:22:0) after 1m0s
[08:24:39.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:22:0)
[08:24:39.059] Timer tick, asking Buildbucket for the build status
[08:24:39.169] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:39.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:23:0) after 1m0s
[08:25:39.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:23:0)
[08:25:39.151] Timer tick, asking Buildbucket for the build status
[08:25:39.711] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:39.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:24:0) after 1m0s
[08:26:39.799] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:24:0)
[08:26:39.799] Timer tick, asking Buildbucket for the build status
[08:26:40.162] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:40.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:25:0) after 1m0s
[08:27:40.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:25:0)
[08:27:40.203] Timer tick, asking Buildbucket for the build status
[08:27:40.587] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:40.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:26:0) after 1m0s
[08:28:40.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:26:0)
[08:28:40.609] Timer tick, asking Buildbucket for the build status
[08:28:40.732] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:40.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:27:0) after 1m0s
[08:29:40.807] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:27:0)
[08:29:40.807] Timer tick, asking Buildbucket for the build status
[08:29:41.104] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:41.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:28:0) after 1m0s
[08:30:41.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:28:0)
[08:30:41.125] Timer tick, asking Buildbucket for the build status
[08:30:41.254] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:41.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:29:0) after 1m0s
[08:31:41.297] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:29:0)
[08:31:41.297] Timer tick, asking Buildbucket for the build status
[08:31:41.673] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:41.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:30:0) after 1m0s
[08:32:41.695] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:30:0)
[08:32:41.695] Timer tick, asking Buildbucket for the build status
[08:32:41.864] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:41.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:31:0) after 1m0s
[08:33:41.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:31:0)
[08:33:41.887] Timer tick, asking Buildbucket for the build status
[08:33:41.999] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:41.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:32:0) after 1m0s
[08:34:42.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:32:0)
[08:34:42.023] Timer tick, asking Buildbucket for the build status
[08:34:42.271] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:42.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:33:0) after 1m0s
[08:35:42.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:33:0)
[08:35:42.401] Timer tick, asking Buildbucket for the build status
[08:35:42.600] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:42.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:34:0) after 1m0s
[08:36:42.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:34:0)
[08:36:42.854] Timer tick, asking Buildbucket for the build status
[08:36:42.978] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:42.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:35:0) after 1m0s
[08:37:43.161] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:35:0)
[08:37:43.161] Timer tick, asking Buildbucket for the build status
[08:37:43.291] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:43.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:36:0) after 1m0s
[08:38:43.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:36:0)
[08:38:43.430] Timer tick, asking Buildbucket for the build status
[08:38:43.959] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:43.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:37:0) after 1m0s
[08:39:44.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:37:0)
[08:39:44.051] Timer tick, asking Buildbucket for the build status
[08:39:44.155] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:44.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:38:0) after 1m0s
[08:40:44.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:38:0)
[08:40:44.333] Timer tick, asking Buildbucket for the build status
[08:40:44.984] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:44.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:39:0) after 1m0s
[08:41:45.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:39:0)
[08:41:45.365] Timer tick, asking Buildbucket for the build status
[08:41:45.580] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:45.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:40:0) after 1m0s
[08:42:45.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:40:0)
[08:42:45.902] Timer tick, asking Buildbucket for the build status
[08:42:46.025] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:46.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:41:0) after 1m0s
[08:43:46.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:41:0)
[08:43:46.178] Timer tick, asking Buildbucket for the build status
[08:43:46.499] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:46.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:42:0) after 1m0s
[08:44:46.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:42:0)
[08:44:46.603] Timer tick, asking Buildbucket for the build status
[08:44:46.809] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:46.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:43:0) after 1m0s
[08:45:47.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:43:0)
[08:45:47.039] Timer tick, asking Buildbucket for the build status
[08:45:47.229] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:47.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:44:0) after 1m0s
[08:46:47.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:44:0)
[08:46:47.309] Timer tick, asking Buildbucket for the build status
[08:46:47.421] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:47.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:45:0) after 1m0s
[08:47:47.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:45:0)
[08:47:47.523] Timer tick, asking Buildbucket for the build status
[08:47:48.094] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:48.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:46:0) after 1m0s
[08:48:48.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:46:0)
[08:48:48.131] Timer tick, asking Buildbucket for the build status
[08:48:48.208] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:48.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:47:0) after 1m0s
[08:49:48.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:47:0)
[08:49:48.309] Timer tick, asking Buildbucket for the build status
[08:49:48.487] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:48.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:48:0) after 1m0s
[08:50:48.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:48:0)
[08:50:48.647] Timer tick, asking Buildbucket for the build status
[08:50:48.726] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:48.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:49:0) after 1m0s
[08:51:48.842] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:49:0)
[08:51:48.842] Timer tick, asking Buildbucket for the build status
[08:51:49.183] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:49.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:50:0) after 1m0s
[08:52:49.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:50:0)
[08:52:49.206] Timer tick, asking Buildbucket for the build status
[08:52:49.481] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:49.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:51:0) after 1m0s
[08:53:49.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:51:0)
[08:53:49.518] Timer tick, asking Buildbucket for the build status
[08:53:49.623] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:49.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:52:0) after 1m0s
[08:54:49.698] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:52:0)
[08:54:49.698] Timer tick, asking Buildbucket for the build status
[08:54:50.145] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:50.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:53:0) after 1m0s
[08:55:50.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:53:0)
[08:55:50.164] Timer tick, asking Buildbucket for the build status
[08:55:50.276] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:50.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:54:0) after 1m0s
[08:56:50.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:54:0)
[08:56:50.364] Timer tick, asking Buildbucket for the build status
[08:56:50.977] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:50.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:55:0) after 1m0s
[08:57:51.002] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:55:0)
[08:57:51.002] Timer tick, asking Buildbucket for the build status
[08:57:51.072] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:51.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:56:0) after 1m0s
[08:58:51.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:56:0)
[08:58:51.141] Timer tick, asking Buildbucket for the build status
[08:58:51.252] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:51.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:57:0) after 1m0s
[08:59:51.275] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:57:0)
[08:59:51.275] Timer tick, asking Buildbucket for the build status
[08:59:51.777] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:51.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:58:0) after 1m0s
[09:00:51.800] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:58:0)
[09:00:51.800] Timer tick, asking Buildbucket for the build status
[09:00:51.919] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:51.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:59:0) after 1m0s
[09:01:51.941] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:59:0)
[09:01:51.941] Timer tick, asking Buildbucket for the build status
[09:01:52.016] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:52.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:60:0) after 1m0s
[09:02:51.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:60:0)
[09:02:51.997] Timer tick, asking Buildbucket for the build status
[09:02:52.126] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:52.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:61:0) after 1m0s
[09:03:52.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:61:0)
[09:03:52.219] Timer tick, asking Buildbucket for the build status
[09:03:52.313] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:52.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:62:0) after 1m0s
[09:04:52.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:62:0)
[09:04:52.384] Timer tick, asking Buildbucket for the build status
[09:04:52.490] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:52.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:63:0) after 1m0s
[09:05:52.563] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:63:0)
[09:05:52.563] Timer tick, asking Buildbucket for the build status
[09:05:52.634] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:52.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:64:0) after 1m0s
[09:06:52.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:64:0)
[09:06:52.656] Timer tick, asking Buildbucket for the build status
[09:06:52.761] Build 8864849354771555056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:52.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030279416832065152:65:0) after 1m0s
[09:07:15.645] Received PubSub notification, asking Buildbucket for the build status
[09:07:16.083] Build 8864849354771555056: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:07:16.084] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604221634303909",
"created_by": "project:chromium",
"created_ts": "1604217873751060",
"id": "8864849354771555056",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b1b7241ac6057397097f534f2cacaef3e29e4489\"}}",
"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\": \"b1b7241ac6057397097f534f2cacaef3e29e4489\", \"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-6o02\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15391, \"clang_revision\": \"llvmorg-12-init-5627-gf086e85e-2\", \"got_angle_revision\": \"42ad027982404039e6c9a921b2f6354f55b65a2e\", \"got_dawn_revision\": \"0a374dce665328391ab3b68fd9e667b018dd9dcd\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"b1b7241ac6057397097f534f2cacaef3e29e4489\", \"got_revision_cp\": \"refs/heads/master@{#823007}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"e0de282e678b72dac25b81f8d25305231aec01f8\", \"got_v8_revision\": \"69c856dc816ff1dd65fcc536095b44399a7434b9\", \"got_v8_revision_cp\": \"refs/heads/8.8.214@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b1b7241ac6057397097f534f2cacaef3e29e4489\", \"swarm_hashes_refs/heads/master(at){#823007}_without_patch\": {\"absl_hardening_tests\": \"d5a86944f2a750fb455a9a2699620e68bdd97190\", \"accessibility_unittests\": \"c914243a7a436bba418dac52efbdfbd997fb2583\", \"angle_unittests\": \"e8fa427f69f736b8ce80534beaa5faa692ef6213\", \"app_shell_unittests\": \"04ab2fbe9d1c62c29bcaab63de37591c05e5f146\", \"aura_unittests\": \"549b64d74d10994eed655d9a34570b6c0a0bccce\", \"base_unittests\": \"dff8dc06b5c39f36d1f7122678558135b60c9fdf\", \"base_util_unittests\": \"5ebd7ae8e2bf642902d1e8348fb5b7c271f5c80e\", \"blink_common_unittests\": \"aaaff7919f370b9a82b48f152e92d789a6c771ce\", \"blink_fuzzer_unittests\": \"8025d34a26931fd580914cc3c841230786bba328\", \"blink_heap_unittests\": \"b38d5eb4988066e138e378f5b946e1b173236a3d\", \"blink_platform_unittests\": \"83d84904ffa4268129d99492fed015dafb890724\", \"blink_unittests\": \"5f63bbb6b5c7281af82b73a2b21d7bebc3c93a5b\", \"boringssl_crypto_tests\": \"88cbfece12d9cb6fc9b8ac4db1e012d8775d31a2\", \"boringssl_ssl_tests\": \"bd1f7c2582a548a6144caa5ea57061db5d3159d5\", \"browser_switcher_bho_unittests\": \"1ed50446122794a2fa7b2c38f63f8972cffd5b8c\", \"browser_tests\": \"ce31271c3de0c55d3a05a8b4137989cf045e1ea8\", \"capture_unittests\": \"06ba3229ba9db9053afaa61a2c5009c6bd722fc9\", \"cast_unittests\": \"779cfc43525e312bbb56f50974df1112f2753a4a\", \"cc_unittests\": \"7fdf1361089ea238e3ba26fa6a464cff3ef10a1f\", \"chrome_app_unittests\": \"915a024b96f736a02f45744e8dc9f23ac884de19\", \"chrome_cleaner_unittests\": \"3f537172fb6446f3f452691d486cc1c973469521\", \"chrome_elf_unittests\": \"6b73f6d945baf16e6efd3c1eeea4a32052f1465b\", \"chromedriver_unittests\": \"dcb6828e3558e6b7cb9f26e90b4cd83577753e60\", \"components_browsertests\": \"caba8f1ff079605510aaa7379fb53ee3c88ebcf2\", \"components_unittests\": \"98bde91d2f33a6c772b733a97ea81c9f8e57ba68\", \"compositor_unittests\": \"2adb4352cad8b7ea08f400ffb18a8d910ff45fed\", \"content_browsertests\": \"15ce1caed9d8510b4d9141701065b68d07c9b642\", \"content_unittests\": \"a4bcc69e374ed726b0718a57b76d01c901b80b70\", \"courgette_unittests\": \"7d13ee8c04571136cc677c437e6d5ca9b3b07f6f\", \"crashpad_tests\": \"7d50ff645dde37d0f12477bec3b0ebc2ff9c6556\", \"cronet_tests\": \"9a2ce8a697496ad3fcd0c7357d08f4796320c41c\", \"cronet_unittests\": \"4a148f87d1453cc757af61d98b3cc30c5d6cc41c\", \"crypto_unittests\": \"6719b0c5b489d31b48daea1b5cdbee159cda404f\", \"delayloads_unittests\": \"98e0989606c6d851bee6e59f94dfce9370622905\", \"device_unittests\": \"e763fff607a07d87cdcbe20384e6c5cfac4af1fd\", \"display_unittests\": \"48e36a521c81286942c8f880fef43a49e6ba4777\", \"elevation_service_unittests\": \"0d86101eb2ee811103de037bc92a3e232513aec4\", \"events_unittests\": \"1abe96418519655afe89aea68c9310741af0847f\", \"extensions_browsertests\": \"5a5a9446d1b299267de5c5ab79152acffeaa75eb\", \"extensions_unittests\": \"cd90733c79d0987f4a37ec01c0d1b7e3c8214dae\", \"filesystem_service_unittests\": \"056340320eca3f9739cad04f49dae9cf335c66b7\", \"gcm_unit_tests\": \"8e28098e895246f8d09dd61723d49786dea497f2\", \"gcp_unittests\": \"1343378e120d32a9debd71db7b3c7eb2554bdccc\", \"gfx_unittests\": \"ea78ac04ab5f4208f55e011e0efbc9b58573c2f3\", \"gin_unittests\": \"423efac58365bece43917c4d0cfdb4ab20899643\", \"google_apis_unittests\": \"4b0f71e27d1ae8ba2e61d134f610ff60c20ad715\", \"gpu_unittests\": \"2c6abea41771a5d0f68bb30d62c1ec7df4ade4ea\", \"gwp_asan_unittests\": \"38a832f8d8d57c9f0f126591410bd172beca474d\", \"headless_browsertests\": \"b3a961b2c95e0efe9c7a9aa1896cc30895372e40\", \"headless_unittests\": \"fa2e3c0efb3c7de4e85a76c4c589329cbc1e248e\", \"install_static_unittests\": \"69d2cffbb392c81bd6141e9d66645a01b93f66bb\", \"installer_util_unittests\": \"0f4d0a7dafcdc3d57d27a6d8078a823ad99da1b3\", \"interactive_ui_tests\": \"bb941a63d652ae104afe2fd2efa0de987321c6cb\", \"ipc_tests\": \"b85f3053e75d959540a1f23ad2fcac415ffdea01\", \"jingle_unittests\": \"2ab56f48a9f1081932b5ceece05ede913230c135\", \"latency_unittests\": \"a52eaa20aa6a4718d7010eebb63f37c0c133cb5a\", \"libjingle_xmpp_unittests\": \"1b08636bc77c9f7b7932280b93f38f72c7560990\", \"liburlpattern_unittests\": \"9be2c250e7759e35a7fb76719aee63f52d108561\", \"media_blink_unittests\": \"372eba986c1e92fe168224e45de22beb6ca8d8bc\", \"media_unittests\": \"90b0f8a300f2969e2b4efc03aef89fe204fce978\", \"message_center_unittests\": \"e8073a3ececa69808782f40f8c70f840a55011c8\", \"midi_unittests\": \"a3a4f1a223c28d6734ed71a02aa0d935ec9aa952\", \"mojo_core_unittests\": \"e48c911080a6f5f560be8c1afea807a97a9275b0\", \"mojo_unittests\": \"741090c4442e7b4822e8740886f1d0f0437d6f50\", \"nacl_loader_unittests\": \"ada68fcfb6f758f154f2e623268d462ea986adf7\", \"native_theme_unittests\": \"5733d8be35da31eb4d371c5520cb706e8e0bb722\", \"net_unittests\": \"f25a0ab68735471605bc807c379e10ade0cf2b3b\", \"notification_helper_unittests\": \"f762944367da6eea2f2d527499a60484ab87f81c\", \"pdf_unittests\": \"9ebad4121cb4cb1bb7567e3d92d15feb94f61282\", \"perfetto_unittests\": \"c95d5f0a9c3aa8a18b77d8ad5835e9a6b6f25c35\", \"ppapi_unittests\": \"9c6223a5320c2e7081245691bb4371ad6fcfc8ff\", \"printing_unittests\": \"cee69303f453bca9a4afe08d5483b7c6bef2da01\", \"remoting_unittests\": \"af374cfadc7ed0844e26b0ba3c6e6bf2ae24ecc9\", \"sbox_integration_tests\": \"dbceea529608fd7babb570c35b15b1cbfb279d3c\", \"sbox_unittests\": \"b80b87758729672cffa9968eeb2c811496263cea\", \"sbox_validation_tests\": \"6769eb943f975f2910eab84bf63d18e826b46922\", \"service_manager_unittests\": \"22dd36c766fe5919023ebe9bfc921c86201b310c\", \"services_unittests\": \"d738db599650c0af744302e15f33d225c84a8879\", \"setup_unittests\": \"ad9276c5f80cd9268c307f26c9b3d96c111085d4\", \"shell_dialogs_unittests\": \"1bc2a2c128e8ea919a1cc98daabc50ed5e994b94\", \"skia_unittests\": \"d9e0b36aeb1a6d5f1866db2fd0fa7a74e78343b0\", \"snapshot_unittests\": \"6164bad42d121a690f948122ef6d24cfc70972ae\", \"sql_unittests\": \"37c52cd19638482955dd59cf4266d5d6cdb033e9\", \"storage_unittests\": \"6542adf656c1fc222cd0e23a8f006690f0409e3a\", \"sync_integration_tests\": \"4ef194916d794d0700c5cc871a8253d4be6b7ddb\", \"traffic_annotation_auditor_unittests\": \"ebd284495324c2bff70aaa79502b39da6769d3c0\", \"ui_base_unittests\": \"2aeb41112e6fa59583099b5a3cf57e3e4f51fc84\", \"ui_touch_selection_unittests\": \"80e36c7c704e1f6bde0b2d658b6b75b991a597a7\", \"unit_tests\": \"233f144386632ae62e2eb0376d8cacafc998eb1f\", \"updater_tests\": \"6abe1b5f7d182251100f23de1a8464355b2ae48e\", \"url_unittests\": \"acb7805b970e463382afbcbc7aa13c8b644b97cf\", \"views_unittests\": \"358e2a0f1e6bf5c330366f9eee87754486cb52ae\", \"viz_unittests\": \"1b61ffa3f6ac99580951e93a6ce6bc88b6b57473\", \"vr_common_unittests\": \"f5740039d783c2b916a5ca4d85da072f37265f90\", \"vr_pixeltests\": \"6b5e9ec4125f510cb23308f3b86234cc9ee8bc58\", \"weblayer_browsertests\": \"19f87c656d5bcd007e2b799f3ad7fe8cc18c4d97\", \"weblayer_unittests\": \"97cd413aee2149c117fc99ed5530321e3c57e51d\", \"wm_unittests\": \"6989446cd2167063ec9a3022e8f05f6393df388a\", \"wtf_unittests\": \"f20e44c4ec1a33742d476b7dcb1f317dc7133a42\", \"zlib_unittests\": \"68dcde7ea2dc3f534e393188e9561c32ecd644c8\", \"zucchini_unittests\": \"944d862ac9b593887a7d63a508f6d5ea9e14c8e6\"}}, \"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-6o02\"], \"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\": [\"5614-d862eaa\"], \"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": "1604217887334252",
"status": "COMPLETED",
"status_changed_ts": "1604221634689676",
"tags": [
"build_address:luci.chromium.ci/win-asan/15391",
"builder:win-asan",
"buildset:commit/git/b1b7241ac6057397097f534f2cacaef3e29e4489",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b1b7241ac6057397097f534f2cacaef3e29e4489",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030279416832065152",
"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/8864849354771555056/+/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:4f9ba7d1cbc0b610",
"user_agent:luci-scheduler"
],
"updated_ts": "1604221634707688",
"url": "https://ci.chromium.org/b/8864849354771555056",
"utcnow_ts": "1604221636057794"
}
}
[09:07:16.084] Invocation finished in 1h2m43.680472306s with status SUCCEEDED