[03:27:03.461] New invocation is queued and will start shortly
[03:27:04.570] Starting the invocation (attempt 1)
[03:27:04.602] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:27:04.602] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:27:04.602] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019787626425662080",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"cc87834842eae57d8c9f8e55ebd94f74707a00ff\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019787626425662080",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/cc87834842eae57d8c9f8e55ebd94f74707a00ff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc87834842eae57d8c9f8e55ebd94f74707a00ff",
"gitiles_ref:refs/heads/master"
]
}
[03:27:05.244] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614223624681826",
"id": "8854357564485006464",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cc87834842eae57d8c9f8e55ebd94f74707a00ff\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614223625119457",
"tags": [
"build_address:luci.chromium.ci/win-asan/17518",
"builder:win-asan",
"buildset:commit/git/cc87834842eae57d8c9f8e55ebd94f74707a00ff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc87834842eae57d8c9f8e55ebd94f74707a00ff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019787626425662080",
"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/8854357564485006464/+/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": "1614223625119600",
"url": "https://ci.chromium.org/b/8854357564485006464",
"utcnow_ts": "1614223625238438"
}
}
[03:27:05.244] Task URL: https://ci.chromium.org/b/8854357564485006464
[03:27:05.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:2:0) after 1m0s
[03:27:12.195] Received PubSub notification, asking Buildbucket for the build status
[03:27:12.266] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:05.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:2:0)
[03:28:05.283] Timer tick, asking Buildbucket for the build status
[03:28:05.382] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:05.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:4:0) after 1m0s
[03:29:05.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:4:0)
[03:29:05.385] Timer tick, asking Buildbucket for the build status
[03:29:05.705] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:05.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:5:0) after 1m0s
[03:30:06.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:5:0)
[03:30:06.064] Timer tick, asking Buildbucket for the build status
[03:30:06.175] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:06.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:6:0) after 1m0s
[03:31:06.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:6:0)
[03:31:06.972] Timer tick, asking Buildbucket for the build status
[03:31:07.161] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:07.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:7:0) after 1m0s
[03:32:07.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:7:0)
[03:32:07.273] Timer tick, asking Buildbucket for the build status
[03:32:07.383] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:07.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:8:0) after 1m0s
[03:33:07.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:8:0)
[03:33:07.411] Timer tick, asking Buildbucket for the build status
[03:33:07.662] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:07.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:9:0) after 1m0s
[03:34:07.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:9:0)
[03:34:07.676] Timer tick, asking Buildbucket for the build status
[03:34:07.755] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:07.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:10:0) after 1m0s
[03:35:07.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:10:0)
[03:35:07.769] Timer tick, asking Buildbucket for the build status
[03:35:07.908] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:07.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:11:0) after 1m0s
[03:36:08.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:11:0)
[03:36:08.094] Timer tick, asking Buildbucket for the build status
[03:36:08.176] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:08.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:12:0) after 1m0s
[03:37:08.179] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:12:0)
[03:37:08.179] Timer tick, asking Buildbucket for the build status
[03:37:08.292] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:08.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:13:0) after 1m0s
[03:38:08.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:13:0)
[03:38:08.298] Timer tick, asking Buildbucket for the build status
[03:38:08.433] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:08.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:14:0) after 1m0s
[03:39:08.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:14:0)
[03:39:08.449] Timer tick, asking Buildbucket for the build status
[03:39:08.610] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:08.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:15:0) after 1m0s
[03:40:08.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:15:0)
[03:40:08.626] Timer tick, asking Buildbucket for the build status
[03:40:08.877] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:08.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:16:0) after 1m0s
[03:41:08.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:16:0)
[03:41:08.895] Timer tick, asking Buildbucket for the build status
[03:41:08.974] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:08.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:17:0) after 1m0s
[03:42:09.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:17:0)
[03:42:09.027] Timer tick, asking Buildbucket for the build status
[03:42:09.118] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:09.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:18:0) after 1m0s
[03:43:09.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:18:0)
[03:43:09.136] Timer tick, asking Buildbucket for the build status
[03:43:09.260] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:09.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:19:0) after 1m0s
[03:44:09.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:19:0)
[03:44:09.299] Timer tick, asking Buildbucket for the build status
[03:44:09.441] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:09.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:20:0) after 1m0s
[03:45:09.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:20:0)
[03:45:09.457] Timer tick, asking Buildbucket for the build status
[03:45:09.758] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:09.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:21:0) after 1m0s
[03:46:09.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:21:0)
[03:46:09.755] Timer tick, asking Buildbucket for the build status
[03:46:10.083] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:10.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:22:0) after 1m0s
[03:47:10.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:22:0)
[03:47:10.101] Timer tick, asking Buildbucket for the build status
[03:47:10.198] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:10.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:23:0) after 1m0s
[03:48:10.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:23:0)
[03:48:10.215] Timer tick, asking Buildbucket for the build status
[03:48:10.300] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:10.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:24:0) after 1m0s
[03:49:10.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:24:0)
[03:49:10.365] Timer tick, asking Buildbucket for the build status
[03:49:10.451] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:10.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:25:0) after 1m0s
[03:50:10.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:25:0)
[03:50:10.512] Timer tick, asking Buildbucket for the build status
[03:50:10.601] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:10.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:26:0) after 1m0s
[03:51:10.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:26:0)
[03:51:10.683] Timer tick, asking Buildbucket for the build status
[03:51:10.842] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:10.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:27:0) after 1m0s
[03:52:10.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:27:0)
[03:52:10.870] Timer tick, asking Buildbucket for the build status
[03:52:10.958] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:10.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:28:0) after 1m0s
[03:53:10.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:28:0)
[03:53:10.971] Timer tick, asking Buildbucket for the build status
[03:53:11.051] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:11.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:29:0) after 1m0s
[03:54:11.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:29:0)
[03:54:11.079] Timer tick, asking Buildbucket for the build status
[03:54:11.168] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:11.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:30:0) after 1m0s
[03:55:11.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:30:0)
[03:55:11.232] Timer tick, asking Buildbucket for the build status
[03:55:11.350] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:11.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:31:0) after 1m0s
[03:56:11.399] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:31:0)
[03:56:11.399] Timer tick, asking Buildbucket for the build status
[03:56:11.479] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:11.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:32:0) after 1m0s
[03:57:11.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:32:0)
[03:57:11.550] Timer tick, asking Buildbucket for the build status
[03:57:11.636] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:11.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:33:0) after 1m0s
[03:58:11.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:33:0)
[03:58:11.777] Timer tick, asking Buildbucket for the build status
[03:58:11.922] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:11.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:34:0) after 1m0s
[03:59:11.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:34:0)
[03:59:11.964] Timer tick, asking Buildbucket for the build status
[03:59:12.048] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:12.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:35:0) after 1m0s
[04:00:12.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:35:0)
[04:00:12.121] Timer tick, asking Buildbucket for the build status
[04:00:14.600] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:14.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:36:0) after 1m0s
[04:01:14.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:36:0)
[04:01:14.689] Timer tick, asking Buildbucket for the build status
[04:01:14.770] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:14.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:37:0) after 1m0s
[04:02:14.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:37:0)
[04:02:14.856] Timer tick, asking Buildbucket for the build status
[04:02:14.957] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:14.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:38:0) after 1m0s
[04:03:14.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:38:0)
[04:03:14.976] Timer tick, asking Buildbucket for the build status
[04:03:15.061] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:15.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:39:0) after 1m0s
[04:04:15.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:39:0)
[04:04:15.149] Timer tick, asking Buildbucket for the build status
[04:04:15.528] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:15.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:40:0) after 1m0s
[04:05:15.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:40:0)
[04:05:15.498] Timer tick, asking Buildbucket for the build status
[04:05:15.806] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:15.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:41:0) after 1m0s
[04:06:15.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:41:0)
[04:06:15.811] Timer tick, asking Buildbucket for the build status
[04:06:16.223] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:16.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:42:0) after 1m0s
[04:07:16.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:42:0)
[04:07:16.381] Timer tick, asking Buildbucket for the build status
[04:07:16.516] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:16.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:43:0) after 1m0s
[04:08:16.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:43:0)
[04:08:16.556] Timer tick, asking Buildbucket for the build status
[04:08:16.951] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:16.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:44:0) after 1m0s
[04:09:16.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:44:0)
[04:09:16.999] Timer tick, asking Buildbucket for the build status
[04:09:17.272] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:17.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:45:0) after 1m0s
[04:10:17.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:45:0)
[04:10:17.255] Timer tick, asking Buildbucket for the build status
[04:10:17.345] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:17.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:46:0) after 1m0s
[04:11:17.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:46:0)
[04:11:17.434] Timer tick, asking Buildbucket for the build status
[04:11:17.766] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:17.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:47:0) after 1m0s
[04:12:17.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:47:0)
[04:12:17.896] Timer tick, asking Buildbucket for the build status
[04:12:18.010] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:18.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:48:0) after 1m0s
[04:13:18.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:48:0)
[04:13:18.052] Timer tick, asking Buildbucket for the build status
[04:13:18.137] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:18.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:49:0) after 1m0s
[04:14:18.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:49:0)
[04:14:18.186] Timer tick, asking Buildbucket for the build status
[04:14:18.798] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:18.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:50:0) after 1m0s
[04:15:19.179] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:50:0)
[04:15:19.179] Timer tick, asking Buildbucket for the build status
[04:15:19.253] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:19.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:51:0) after 1m0s
[04:16:19.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:51:0)
[04:16:19.395] Timer tick, asking Buildbucket for the build status
[04:16:19.657] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:19.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:52:0) after 1m0s
[04:17:19.731] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:52:0)
[04:17:19.731] Timer tick, asking Buildbucket for the build status
[04:17:19.979] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:19.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:53:0) after 1m0s
[04:18:20.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:53:0)
[04:18:20.202] Timer tick, asking Buildbucket for the build status
[04:18:20.323] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:20.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:54:0) after 1m0s
[04:19:20.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:54:0)
[04:19:20.424] Timer tick, asking Buildbucket for the build status
[04:19:20.505] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:20.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:55:0) after 1m0s
[04:20:20.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:55:0)
[04:20:20.623] Timer tick, asking Buildbucket for the build status
[04:20:20.718] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:20.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:56:0) after 1m0s
[04:21:20.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:56:0)
[04:21:20.874] Timer tick, asking Buildbucket for the build status
[04:21:20.981] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:20.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:57:0) after 1m0s
[04:22:22.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:57:0)
[04:22:22.262] Timer tick, asking Buildbucket for the build status
[04:22:22.576] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:22.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:58:0) after 1m0s
[04:23:22.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:58:0)
[04:23:22.616] Timer tick, asking Buildbucket for the build status
[04:23:22.701] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:22.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:59:0) after 1m0s
[04:24:22.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:59:0)
[04:24:22.752] Timer tick, asking Buildbucket for the build status
[04:24:22.833] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:22.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:60:0) after 1m0s
[04:25:22.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:60:0)
[04:25:22.888] Timer tick, asking Buildbucket for the build status
[04:25:22.979] Build 8854357564485006464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:22.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019787626425662080:61:0) after 1m0s
[04:25:53.973] Received PubSub notification, asking Buildbucket for the build status
[04:25:54.048] Build 8854357564485006464: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:25:54.048] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614227150789047",
"created_by": "project:chromium",
"created_ts": "1614223624681826",
"id": "8854357564485006464",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cc87834842eae57d8c9f8e55ebd94f74707a00ff\"}}",
"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\": \"cc87834842eae57d8c9f8e55ebd94f74707a00ff\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-3-xpf4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17518, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"9b1c569b14e90765cdd7c07e449400e88f1d6c45\", \"got_dawn_revision\": \"5eb496b863da5b40dc5c9a713c8ca1e413811f35\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"cc87834842eae57d8c9f8e55ebd94f74707a00ff\", \"got_revision_cp\": \"refs/heads/master@{#857514}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"349abccec8f7ebd1259de11965d58790a76b0ba4\", \"got_v8_revision\": \"bd0108b4c88e0d6f2350cb79b5f363fbd02f3eb7\", \"got_v8_revision_cp\": \"refs/heads/9.0.257@{#1}\", \"got_webrtc_revision\": \"cd0373f013b5c9672c05e79a48bb1e9fb5f50fa4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33333}\", \"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\": \"cc87834842eae57d8c9f8e55ebd94f74707a00ff\", \"swarm_hashes_refs/heads/master(at){#857514}_without_patch\": {\"absl_hardening_tests\": \"9a85e7c3b08e4569fec1f8b11e323bcc17bf58d8\", \"accessibility_unittests\": \"c995fee0d65cb89f11fd92f81cebc9d34fa5e5e7\", \"angle_unittests\": \"19e55840f5496126ff58acb342f972f0beb6951b\", \"app_shell_unittests\": \"4d1b12559bd2703432beeecd62f4a1779a33ea53\", \"aura_unittests\": \"093cff7c595bec0bda6a6466f2a08272e624b30b\", \"base_unittests\": \"bc28fe8310ff6ef9167b4a0f003f88d6fb681af7\", \"base_util_unittests\": \"1e877c18b13190c8c2a342abdf8b40749143f355\", \"blink_common_unittests\": \"82bdc29ddf7c3c4d5cf81760e15c720af972d29d\", \"blink_fuzzer_unittests\": \"e535eeeef5b84aca3388ceac73c7624412054e00\", \"blink_heap_unittests\": \"8e97a58adbc9a566e38ddb8102e73418495b84f5\", \"blink_platform_unittests\": \"a7a659673346e4ec37b39811a0a8c23dd3e57fd1\", \"blink_unittests\": \"ccaf710434fc981d4b263a00e80f172db4a806a8\", \"boringssl_crypto_tests\": \"17c23890eff6f3f861d3322856ef0ec64272dd61\", \"boringssl_ssl_tests\": \"a8a06260158e1d3803527ba275f179661eb48a2e\", \"browser_switcher_bho_unittests\": \"39d51236b5cdd553df7ae1eab8f6ca660fa64033\", \"browser_tests\": \"59b44c66787dce3117937a38578b20eaa5da7362\", \"capture_unittests\": \"76a88cfb648acf1adb81de6cb0e665b0af688f1e\", \"cast_unittests\": \"2a03a4d74f55cf0cc4b23d6ccb9a2418bc7bbb20\", \"cc_unittests\": \"5c35a36d4f2b8d6e4fdd6a32e00b51f8acb8162f\", \"chrome_app_unittests\": \"7143be83bac8d892f533629f3f019b248a55448a\", \"chrome_cleaner_unittests\": \"2b26019cd4c698e6e47a0b9614b65d22189c1638\", \"chrome_elf_unittests\": \"46d336d5f7e066330dd46e91a184941d14448848\", \"chromedriver_unittests\": \"0ecd624028e37a7568125d6bf43ac0be22ddafd3\", \"color_unittests\": \"6dd1b2a93382dfe8951e965d44f795b7dcca4a41\", \"components_browsertests\": \"1970efd1c6b5d77cd8e9a05004be928d3e27aab0\", \"components_unittests\": \"efef1a8a0158870dccd8b402b328d6bbedc92de3\", \"compositor_unittests\": \"c52e98dc83060fe06ed15b5c556b7ca1e0b87e56\", \"content_browsertests\": \"433c05fb211dc47bf757cb49d72c297f32589358\", \"content_unittests\": \"21a47e5b110717412c7f3680522560c4d3cb1715\", \"courgette_unittests\": \"a781a2d2bc533def1318be8a4b5284a6c61f47cb\", \"crashpad_tests\": \"c560f579c584f84fb82d5a0be7735de9fcc9df6d\", \"cronet_tests\": \"8f0f8202d9d01f4966edf3c4f7778fa1ad9b89a5\", \"cronet_unittests\": \"168e17abddcc46e1065ed39828c29ed27e249021\", \"crypto_unittests\": \"c301f0136a93c82821b51d3c76175b929ac42a75\", \"delayloads_unittests\": \"db3ca7ce5241a9cfb6c1318c5efa562bf703630a\", \"device_unittests\": \"961a85b4f7b53c1461d04fd9f53eeebb0d791719\", \"display_unittests\": \"7b8e8bbf5abd5416c4ef5ef319374692311ccc11\", \"elevation_service_unittests\": \"1b39eb94391e52d748d5dd521920477137ff1816\", \"events_unittests\": \"382f2d91a2741d45ec9f2c9c340ec455006fa1d3\", \"extensions_browsertests\": \"7254d7b364cb565edc136424c07c93601e0d670c\", \"extensions_unittests\": \"2c5d2a1ff6abe7efaeba00baa21ef00b381a84c5\", \"filesystem_service_unittests\": \"47fdeb6c6d51a86f18357ca3e3a95eb8218246ad\", \"gcm_unit_tests\": \"b9005eb440c8aeac2e59a83b15b6cb78a39f813a\", \"gcp_unittests\": \"809747ec02d2a26bd9961d8693d7044066d24e79\", \"gfx_unittests\": \"756a06f9743e00c5142df6836a0d88615c131351\", \"gin_unittests\": \"fa862e4d29963285ffadc382e220baba761cd7de\", \"google_apis_unittests\": \"c9dc264b88f9ac82acbcdcb68ca69f30b63f0c64\", \"gpu_unittests\": \"71078211ee6c410e1e4bfc7cf9e218f0ffde0bc1\", \"gwp_asan_unittests\": \"8ba3b1f1ee3a13c7050f75f76046cb5969672c4b\", \"headless_browsertests\": \"6b562004fe171f113971f22b1924b7c09a74001f\", \"headless_unittests\": \"c53ea3d54b08705d50fbb7e092947c34fa30c7d5\", \"install_static_unittests\": \"22daa48f8defa0cd3215e780ba91d492a91b404a\", \"installer_util_unittests\": \"f4ec72980df432c4f38a910932fdc15af1e560fa\", \"interactive_ui_tests\": \"c1599a0118125abcda1cf53c11470fb6b23d5ae0\", \"ipc_tests\": \"a1815410e1c5f659285eb909f0367569eb0ac210\", \"jingle_unittests\": \"71eb9c240d429569a5ab873a65be7bdf4634427d\", \"latency_unittests\": \"20c80d81d2609ae24c338ed8e368ae856a5d6cb4\", \"libjingle_xmpp_unittests\": \"00a500605bbb3868941c7515e2df60d54dc05601\", \"liburlpattern_unittests\": \"01872a637abf860f323a170eb5298c8435398420\", \"media_blink_unittests\": \"2e9dc6ce6bc551ed413c148b5b889623b03fcedd\", \"media_unittests\": \"debbb19324c0b0e7f6d475fe744c40d270aa9f7e\", \"message_center_unittests\": \"30754733ff67dd3d52713afe524e0b585b4518d7\", \"midi_unittests\": \"3cd80ca96438453e4164023da740bd9a20ea1188\", \"mojo_core_unittests\": \"aa8d8fa410cf20edff5763e62ca669f7ac852448\", \"mojo_unittests\": \"5ee7b10f6325516e94e7645a0332e1b97d664a29\", \"nacl_loader_unittests\": \"6b60748893703d9eb13e93e2348f8c74d9b9c4f6\", \"native_theme_unittests\": \"dcf430f4d0cf1d5cbd0e2071e636496364529de4\", \"net_unittests\": \"39db2ee55c576aa9e8b595a2198e6664abdb695d\", \"notification_helper_unittests\": \"09d6164450b06dc8f62cc85d03c9ca8e7a34e6b2\", \"pdf_unittests\": \"a4feb6f6e27cf30c375f7291ee57e3527285f2a1\", \"perfetto_unittests\": \"280db6e0e453a3dd57a357724920f00233691060\", \"ppapi_unittests\": \"ff73b828a7d0ab6d750caf573432f9ba55b82f05\", \"printing_unittests\": \"19843f7ec387eec9caea573c71082d142d28f52a\", \"remoting_unittests\": \"b11c1386b1ae8c8ad4300d24bc46d3176fc694ae\", \"sbox_integration_tests\": \"99ced40a94b33310532dda3b14e3d687af4b3c89\", \"sbox_unittests\": \"96f562460c4d78a524631e1df5448d497a840b7c\", \"sbox_validation_tests\": \"f4d5e8e38aff1218cb865b27d876d949cb6779b4\", \"service_manager_unittests\": \"0571fdaaacd5a5cf6f25d0502820ae478236ac19\", \"services_unittests\": \"89780b12e557937dc2d262b9ec754a199aafe705\", \"setup_unittests\": \"0ce779bc0c543af5dccba1300b49c7ff89541147\", \"shell_dialogs_unittests\": \"a74d61753814ad1d3feefe34d6fd33e88078a471\", \"skia_unittests\": \"619e5aa7111813f3e86618f53a33bdb3ac195754\", \"snapshot_unittests\": \"d4665185cb33b122255ab533d68d071b9ca642fa\", \"sql_unittests\": \"261f6d936405fcaa683950bac860ea6500e42c8e\", \"storage_unittests\": \"8825bab32167030673abcd442fad4ef173e80115\", \"sync_integration_tests\": \"50f53dc03a378d30baa5141b843ff3084486e191\", \"traffic_annotation_auditor_unittests\": \"94c1b61edf8f1926b854ef9a070e9b6474d2abcf\", \"ui_base_unittests\": \"ad93fb2a3f5c4e883a1496224cd3419ed62515e4\", \"ui_touch_selection_unittests\": \"16e851d2af4cf56421cdce16eb431ef15b150f7b\", \"unit_tests\": \"94e72b9a987a48a35021ac36f379e9bd083e68ed\", \"updater_tests\": \"068c72504a6574dade75c747f0e5a28a2f827853\", \"url_unittests\": \"10149cdfa20b309766bbb90a563cbc61eb27017c\", \"views_unittests\": \"fb7f947db12f0ab0b7308a4a6f56ac2dddf1310f\", \"viz_unittests\": \"564f3835524e4ebefa686f1397c6045deb7cc6c6\", \"vr_common_unittests\": \"1d6ba4856798a51b02d5e4034864fa31d4f51595\", \"vr_pixeltests\": \"3d953f445198ffe08932a0c9c4509b47df8e760a\", \"weblayer_browsertests\": \"0463bb64d969ee97a6e01b6ccbfc410bfd4e915a\", \"weblayer_unittests\": \"bbdc0d726bb6400f9bfcb182446cd844627ee729\", \"wm_unittests\": \"07d0ddb1ca622a4f633351fefa642ce5f0bde2cf\", \"wtf_unittests\": \"543831850cc1755635980c4719123a697b16d42e\", \"zlib_unittests\": \"27f3e5742628067b466cec9c32d62a954cee60fb\", \"zucchini_unittests\": \"88c630941c357ebae2f9b9374c0b515d79bca8e5\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-3-xpf4\"], \"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\": [\"5712-339372b\"], \"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": "1614223631155933",
"status": "COMPLETED",
"status_changed_ts": "1614227151044532",
"tags": [
"build_address:luci.chromium.ci/win-asan/17518",
"builder:win-asan",
"buildset:commit/git/cc87834842eae57d8c9f8e55ebd94f74707a00ff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc87834842eae57d8c9f8e55ebd94f74707a00ff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019787626425662080",
"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/8854357564485006464/+/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:51f00b5561f18410",
"user_agent:luci-scheduler"
],
"updated_ts": "1614227151048876",
"url": "https://ci.chromium.org/b/8854357564485006464",
"utcnow_ts": "1614227154040759"
}
}
[04:25:54.048] Invocation finished in 58m50.601497866s with status SUCCEEDED