[19:47:28.531] New invocation is queued and will start shortly
[19:47:29.635] Starting the invocation (attempt 1)
[19:47:29.707] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:47:29.707] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:47:29.707] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024165195224108608",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7cb155718dd387e594c359ceed38f72d45670eb7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9024165195224108608",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/7cb155718dd387e594c359ceed38f72d45670eb7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7cb155718dd387e594c359ceed38f72d45670eb7",
"gitiles_ref:refs/heads/master"
]
}
[19:47:31.601] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610048849846519",
"id": "8858735133182033008",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7cb155718dd387e594c359ceed38f72d45670eb7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610048851252305",
"tags": [
"build_address:luci.chromium.ci/win-asan/16679",
"builder:win-asan",
"buildset:commit/git/7cb155718dd387e594c359ceed38f72d45670eb7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7cb155718dd387e594c359ceed38f72d45670eb7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024165195224108608",
"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/8858735133182033008/+/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": "1610048851252401",
"url": "https://ci.chromium.org/b/8858735133182033008",
"utcnow_ts": "1610048851552910"
}
}
[19:47:31.601] Task URL: https://ci.chromium.org/b/8858735133182033008
[19:47:31.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:2:0) after 1m0s
[19:47:37.703] Received PubSub notification, asking Buildbucket for the build status
[19:47:37.815] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:31.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:2:0)
[19:48:31.670] Timer tick, asking Buildbucket for the build status
[19:48:31.769] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:31.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:4:0) after 1m0s
[19:49:31.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:4:0)
[19:49:31.792] Timer tick, asking Buildbucket for the build status
[19:49:32.227] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:32.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:5:0) after 1m0s
[19:50:32.306] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:5:0)
[19:50:32.306] Timer tick, asking Buildbucket for the build status
[19:50:32.607] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:32.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:6:0) after 1m0s
[19:51:32.637] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:6:0)
[19:51:32.637] Timer tick, asking Buildbucket for the build status
[19:51:32.802] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:32.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:7:0) after 1m0s
[19:52:32.815] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:7:0)
[19:52:32.815] Timer tick, asking Buildbucket for the build status
[19:52:32.997] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:32.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:8:0) after 1m0s
[19:53:33.020] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:8:0)
[19:53:33.020] Timer tick, asking Buildbucket for the build status
[19:53:33.272] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:33.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:9:0) after 1m0s
[19:54:33.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:9:0)
[19:54:33.296] Timer tick, asking Buildbucket for the build status
[19:54:33.579] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:33.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:10:0) after 1m0s
[19:55:33.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:10:0)
[19:55:33.609] Timer tick, asking Buildbucket for the build status
[19:55:33.704] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:33.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:11:0) after 1m0s
[19:56:33.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:11:0)
[19:56:33.778] Timer tick, asking Buildbucket for the build status
[19:56:33.853] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:33.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:12:0) after 1m0s
[19:57:33.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:12:0)
[19:57:33.873] Timer tick, asking Buildbucket for the build status
[19:57:33.978] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:33.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:13:0) after 1m0s
[19:58:33.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:13:0)
[19:58:33.972] Timer tick, asking Buildbucket for the build status
[19:58:34.092] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:34.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:14:0) after 1m0s
[19:59:34.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:14:0)
[19:59:34.212] Timer tick, asking Buildbucket for the build status
[19:59:34.339] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:34.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:15:0) after 1m0s
[20:00:34.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:15:0)
[20:00:34.383] Timer tick, asking Buildbucket for the build status
[20:00:34.479] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:34.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:16:0) after 1m0s
[20:01:34.567] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:16:0)
[20:01:34.567] Timer tick, asking Buildbucket for the build status
[20:01:34.905] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:34.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:17:0) after 1m0s
[20:02:34.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:17:0)
[20:02:34.937] Timer tick, asking Buildbucket for the build status
[20:02:35.216] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:35.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:18:0) after 1m0s
[20:03:35.230] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:18:0)
[20:03:35.230] Timer tick, asking Buildbucket for the build status
[20:03:35.314] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:35.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:19:0) after 1m0s
[20:04:35.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:19:0)
[20:04:35.414] Timer tick, asking Buildbucket for the build status
[20:04:35.549] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:35.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:20:0) after 1m0s
[20:05:35.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:20:0)
[20:05:35.592] Timer tick, asking Buildbucket for the build status
[20:05:35.842] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:35.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:21:0) after 1m0s
[20:06:35.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:21:0)
[20:06:35.901] Timer tick, asking Buildbucket for the build status
[20:06:36.328] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:36.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:22:0) after 1m0s
[20:07:36.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:22:0)
[20:07:36.358] Timer tick, asking Buildbucket for the build status
[20:07:36.475] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:36.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:23:0) after 1m0s
[20:08:36.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:23:0)
[20:08:36.551] Timer tick, asking Buildbucket for the build status
[20:08:36.702] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:36.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:24:0) after 1m0s
[20:09:36.819] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:24:0)
[20:09:36.819] Timer tick, asking Buildbucket for the build status
[20:09:36.940] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:36.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:25:0) after 1m0s
[20:10:37.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:25:0)
[20:10:37.084] Timer tick, asking Buildbucket for the build status
[20:10:37.232] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:37.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:26:0) after 1m0s
[20:11:37.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:26:0)
[20:11:37.294] Timer tick, asking Buildbucket for the build status
[20:11:37.539] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:37.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:27:0) after 1m0s
[20:12:37.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:27:0)
[20:12:37.675] Timer tick, asking Buildbucket for the build status
[20:12:38.009] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:38.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:28:0) after 1m0s
[20:13:38.275] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:28:0)
[20:13:38.275] Timer tick, asking Buildbucket for the build status
[20:13:38.460] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:38.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:29:0) after 1m0s
[20:14:38.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:29:0)
[20:14:38.608] Timer tick, asking Buildbucket for the build status
[20:14:39.545] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:39.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:30:0) after 1m0s
[20:15:39.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:30:0)
[20:15:39.624] Timer tick, asking Buildbucket for the build status
[20:15:39.761] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:39.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:31:0) after 1m0s
[20:16:39.773] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:31:0)
[20:16:39.773] Timer tick, asking Buildbucket for the build status
[20:16:39.862] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:39.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:32:0) after 1m0s
[20:17:39.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:32:0)
[20:17:39.936] Timer tick, asking Buildbucket for the build status
[20:17:40.053] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:40.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:33:0) after 1m0s
[20:18:40.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:33:0)
[20:18:40.065] Timer tick, asking Buildbucket for the build status
[20:18:40.488] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:40.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:34:0) after 1m0s
[20:19:40.570] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:34:0)
[20:19:40.570] Timer tick, asking Buildbucket for the build status
[20:19:40.781] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:40.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:35:0) after 1m0s
[20:20:40.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:35:0)
[20:20:40.907] Timer tick, asking Buildbucket for the build status
[20:20:41.108] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:41.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:36:0) after 1m0s
[20:21:41.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:36:0)
[20:21:41.113] Timer tick, asking Buildbucket for the build status
[20:21:41.211] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:41.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:37:0) after 1m0s
[20:22:41.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:37:0)
[20:22:41.368] Timer tick, asking Buildbucket for the build status
[20:22:41.504] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:41.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:38:0) after 1m0s
[20:23:41.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:38:0)
[20:23:41.573] Timer tick, asking Buildbucket for the build status
[20:23:42.034] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:42.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:39:0) after 1m0s
[20:24:42.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:39:0)
[20:24:42.206] Timer tick, asking Buildbucket for the build status
[20:24:42.282] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:42.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:40:0) after 1m0s
[20:25:42.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:40:0)
[20:25:42.465] Timer tick, asking Buildbucket for the build status
[20:25:43.060] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:43.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:41:0) after 1m0s
[20:26:43.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:41:0)
[20:26:43.144] Timer tick, asking Buildbucket for the build status
[20:26:43.227] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:43.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:42:0) after 1m0s
[20:27:43.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:42:0)
[20:27:43.289] Timer tick, asking Buildbucket for the build status
[20:27:43.475] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:43.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:43:0) after 1m0s
[20:28:43.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:43:0)
[20:28:43.499] Timer tick, asking Buildbucket for the build status
[20:28:43.613] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:43.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:44:0) after 1m0s
[20:29:43.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:44:0)
[20:29:43.688] Timer tick, asking Buildbucket for the build status
[20:29:43.813] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:43.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:45:0) after 1m0s
[20:30:43.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:45:0)
[20:30:43.972] Timer tick, asking Buildbucket for the build status
[20:30:44.584] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:44.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:46:0) after 1m0s
[20:31:44.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:46:0)
[20:31:44.644] Timer tick, asking Buildbucket for the build status
[20:31:44.739] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:44.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:47:0) after 1m0s
[20:32:44.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:47:0)
[20:32:44.861] Timer tick, asking Buildbucket for the build status
[20:32:45.068] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:45.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:48:0) after 1m0s
[20:33:45.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:48:0)
[20:33:45.193] Timer tick, asking Buildbucket for the build status
[20:33:45.535] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:45.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:49:0) after 1m0s
[20:34:45.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:49:0)
[20:34:45.749] Timer tick, asking Buildbucket for the build status
[20:34:45.960] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:45.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:50:0) after 1m0s
[20:35:46.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:50:0)
[20:35:46.051] Timer tick, asking Buildbucket for the build status
[20:35:46.127] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:46.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:51:0) after 1m0s
[20:36:46.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:51:0)
[20:36:46.223] Timer tick, asking Buildbucket for the build status
[20:36:46.762] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:46.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:52:0) after 1m0s
[20:37:46.858] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:52:0)
[20:37:46.858] Timer tick, asking Buildbucket for the build status
[20:37:46.989] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:46.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:53:0) after 1m0s
[20:38:47.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:53:0)
[20:38:47.031] Timer tick, asking Buildbucket for the build status
[20:38:47.193] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:47.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:54:0) after 1m0s
[20:39:47.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:54:0)
[20:39:47.289] Timer tick, asking Buildbucket for the build status
[20:39:47.358] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:47.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:55:0) after 1m0s
[20:40:47.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:55:0)
[20:40:47.386] Timer tick, asking Buildbucket for the build status
[20:40:47.689] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:47.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:56:0) after 1m0s
[20:41:47.679] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:56:0)
[20:41:47.679] Timer tick, asking Buildbucket for the build status
[20:41:48.049] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:48.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:57:0) after 1m0s
[20:42:48.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:57:0)
[20:42:48.078] Timer tick, asking Buildbucket for the build status
[20:42:48.222] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:48.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:58:0) after 1m0s
[20:43:48.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:58:0)
[20:43:48.364] Timer tick, asking Buildbucket for the build status
[20:43:48.452] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:48.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:59:0) after 1m0s
[20:44:48.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:59:0)
[20:44:48.573] Timer tick, asking Buildbucket for the build status
[20:44:48.891] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:48.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:60:0) after 1m0s
[20:45:48.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:60:0)
[20:45:48.945] Timer tick, asking Buildbucket for the build status
[20:45:49.014] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:49.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:61:0) after 1m0s
[20:46:49.213] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:61:0)
[20:46:49.213] Timer tick, asking Buildbucket for the build status
[20:46:49.658] Build 8858735133182033008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:49.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024165195224108608:62:0) after 1m0s
[20:46:51.646] Received PubSub notification, asking Buildbucket for the build status
[20:46:51.831] Build 8858735133182033008: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:46:51.831] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610052409562191",
"created_by": "project:chromium",
"created_ts": "1610048849846519",
"id": "8858735133182033008",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7cb155718dd387e594c359ceed38f72d45670eb7\"}}",
"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\": \"7cb155718dd387e594c359ceed38f72d45670eb7\", \"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-7-w73f\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16679, \"clang_revision\": \"llvmorg-12-init-15676-g5c951623-1\", \"got_angle_revision\": \"d0a38010a14cf01b1a7b32143315108bd3334f46\", \"got_dawn_revision\": \"06aa1af28a72833fd59531763a0bee0338811f5b\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"7cb155718dd387e594c359ceed38f72d45670eb7\", \"got_revision_cp\": \"refs/heads/master@{#841149}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"7b2f938954180e8945f776d3e202f6a2bda1aaf9\", \"got_v8_revision\": \"d1da011fb05beda92d918cf7b378ddf7b7386c9b\", \"got_v8_revision_cp\": \"refs/heads/8.9.214@{#1}\", \"got_webrtc_revision\": \"fc6b87192db6d5e4fd6997814f00b6792cf16180\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32904}\", \"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\": \"7cb155718dd387e594c359ceed38f72d45670eb7\", \"swarm_hashes_refs/heads/master(at){#841149}_without_patch\": {\"absl_hardening_tests\": \"e4209e29fc535a6f0701c3c8b9ed1f27d360c80a\", \"accessibility_unittests\": \"0b57279a0f4470f7c37f9b2bdf2660bf4da56e48\", \"angle_unittests\": \"30af18f3f72b2ff587ea79fa05b59c3487989666\", \"app_shell_unittests\": \"7cdd1de4a82b4edfaeb81bc60492f6f5c3e85346\", \"aura_unittests\": \"2c1fd0c63806b87a26e14721db5695654e3eb6f2\", \"base_unittests\": \"5a8c3d3aa212c9ad45c346290b36f75ec974f0f1\", \"base_util_unittests\": \"7679555d2d84ba1f130a6ef1d2662df5426bfd28\", \"blink_common_unittests\": \"03ea84037062e96f109ede4f8c835ec0482df4c9\", \"blink_fuzzer_unittests\": \"2b554894a2849bf62f4220b8666927bc9c6d5b8f\", \"blink_heap_unittests\": \"2d2805bc1587a665a067ab8605829444491b9b9b\", \"blink_platform_unittests\": \"9c7069ea82a125b20b08bd8bf1d99a155e69db35\", \"blink_unittests\": \"e3490bcabf1a3f74d0c503ab9c2581ff5df3d156\", \"boringssl_crypto_tests\": \"4e468000f93d804aeb20ac1e2ffc1379afcf5e3e\", \"boringssl_ssl_tests\": \"f7bc466bdb8eacd5835b475e23efb1d6ff5cb7f9\", \"browser_switcher_bho_unittests\": \"0dcd01372a29dc2c9866928dd95f06230b63ad96\", \"browser_tests\": \"228d88f75dbbde6ddb275f16a036014e15f2c8c8\", \"capture_unittests\": \"61f49cf4582b752f4f3e5fba1b18e718303bc327\", \"cast_unittests\": \"427096d6cb9ee51da1c984355510a143f092ac0f\", \"cc_unittests\": \"f6f66d2832ce4f23d006cfa25e90e34384893229\", \"chrome_app_unittests\": \"b079e2ed6f7a15f512ffb4a78b5bc5cc3d3c7cc8\", \"chrome_cleaner_unittests\": \"18490502a4313c260bce4dc77f2331afd759c7aa\", \"chrome_elf_unittests\": \"fc211c5f15a53fecb998ad82b2ac4f04948d0933\", \"chromedriver_unittests\": \"5aef46910614e498c6bf9d79300f9ad77a89610e\", \"components_browsertests\": \"033f65ed04375b9fd7b052ef23fc741f8ee7bdb7\", \"components_unittests\": \"13cb74ecc04575b035f8ed59293ceb8d1fd54e7b\", \"compositor_unittests\": \"1a64bd85bb7bbd047db510f6d682ab5d93461cb2\", \"content_browsertests\": \"77d11e4ed42a011053e3ce63d35b0b192a3f0c12\", \"content_unittests\": \"eabc9f183e5073a2b4dc91e50aeaf2c245d3cf49\", \"courgette_unittests\": \"7c1d7ec2308e31c8d2e94b7a79056cb2400951ce\", \"crashpad_tests\": \"8f44802155bdd57a64e287b922356cedaba52fb6\", \"cronet_tests\": \"01b551b5907dddd5be65fc92bbdef6c603c23736\", \"cronet_unittests\": \"08c3ab36de08da5f6b16f2c47a5111a0f5a68c59\", \"crypto_unittests\": \"828e7a94bf19e0f03ee19495b5db1c5ad47c974d\", \"delayloads_unittests\": \"9fcf4666cafa90ef57fe33cb3f424b246759f540\", \"device_unittests\": \"285a761574a9776c78fe1c37ec9c8c02c4bc678d\", \"display_unittests\": \"f077702cd3639b51642d8ff7c8cdcddb8b58380b\", \"elevation_service_unittests\": \"1165e5d9e4180262fdbb57f0d2df269da84e685a\", \"events_unittests\": \"3add1f6db65e31091fcb31d2944e694c54361eff\", \"extensions_browsertests\": \"348f086dde33938b620bfb36f28446484aa0234e\", \"extensions_unittests\": \"fbe5c554731721391512f8072c1a562610905f11\", \"filesystem_service_unittests\": \"8d54f651002651fcebc06a6b2cbb905fd51be976\", \"gcm_unit_tests\": \"197bf37b922f48e90065722cd3a17fa3549c0e7d\", \"gcp_unittests\": \"6b2e67c207618746c9c72e057d37bab62ccd624d\", \"gfx_unittests\": \"3cfaca26ce1b5ebc8fc0ed384162d48d2037b525\", \"gin_unittests\": \"b2da80cafecfb9a916f3fe714d375285d8f98e75\", \"google_apis_unittests\": \"ab1c654982faa4bfa890a9ccbca42b16c2805778\", \"gpu_unittests\": \"2cf0e27554d98c122d028fe67862fada1141ba32\", \"gwp_asan_unittests\": \"1858a5dadb07e679878926dbfa87d612ba356100\", \"headless_browsertests\": \"78354f191d458c91a5fa684e9208b7dcc2a1e93c\", \"headless_unittests\": \"4fb730d1938e8ba78bc0791555ec70d4f3cbd771\", \"install_static_unittests\": \"010d4b75b0c43ef6ee5fd7eefa3fff8d925a5178\", \"installer_util_unittests\": \"72a6792ca7d75f9f5a6a2bb5fdc711d64132eb40\", \"interactive_ui_tests\": \"6906ebe9e3ab1d0b68a35fc581ba98b1cb56e6dc\", \"ipc_tests\": \"749f322a4ce0835e5ac86e53d203a7b9b7b72388\", \"jingle_unittests\": \"8d7b6dba75cf72f56d22cb725b0b206a94dcbb86\", \"latency_unittests\": \"62da165812d42e00e657284062596e8e6994c1bc\", \"libjingle_xmpp_unittests\": \"494141fbbdbd33e4b5d61a91f58bc5abb9d7fab0\", \"liburlpattern_unittests\": \"23e511b5f040bb7d22db30a9782b061454ff155d\", \"media_blink_unittests\": \"2c8b079bda5220307fe20d8527617fd20bea02f1\", \"media_unittests\": \"bd8dfea4250e3caaed39eac012b1b1611b538229\", \"message_center_unittests\": \"80587f6fcb4f5ad467c2193256a2886d6af4bcff\", \"midi_unittests\": \"3d46883ab2573acda8317a0953c66c7f9669fcd1\", \"mojo_core_unittests\": \"8b8abefbdf9e62d48472fc465baa3d305d021440\", \"mojo_unittests\": \"41511a6dccb8102e8cd86904aa764d6610d8029f\", \"nacl_loader_unittests\": \"7e6efce84f6b7eab850a69453ee27bdffa0211c4\", \"native_theme_unittests\": \"8f3fe9570719a044dee95ea6fb1e1ab63070e942\", \"net_unittests\": \"a5c621c161cedf56edfcba2d2033179390af8028\", \"notification_helper_unittests\": \"057b54a9c17b38e0085f237e301521eba7c4bda2\", \"pdf_unittests\": \"dbadb15723bd9f031a104122f742fc1bb524bfca\", \"perfetto_unittests\": \"c37ae5f483bb51310f1e8bfb3bd66cb32bb6c129\", \"ppapi_unittests\": \"7de370760727b7d719cb77b0f5d15d7a1f948f72\", \"printing_unittests\": \"d3287a54179c4146f1c760fcd7f05a0a9d676993\", \"remoting_unittests\": \"8548ee12d19a309be6863aceb60dbf0242138948\", \"sbox_integration_tests\": \"e7c5ba3c8efbed35e858080739c94c013326954a\", \"sbox_unittests\": \"cb6ef041ef15bfda4a36d04d03ad11f6c97fe30c\", \"sbox_validation_tests\": \"8de54038b74f6e3c3b1da2aa14960e97d7faa3ea\", \"service_manager_unittests\": \"69feb405099a49d6472bbd4070320d01f01b24fe\", \"services_unittests\": \"6d1ded2e47573cdab3b90790a8a15498d81bf097\", \"setup_unittests\": \"cc9b53e6a562c0cb61381f543e8d72c4c8415452\", \"shell_dialogs_unittests\": \"0051ff2dcd65b02339a1a3bd97da09aabfb6f9c0\", \"skia_unittests\": \"614e8176aca142fc3b6faaa42a381449640b4303\", \"snapshot_unittests\": \"14b3297701d49625790b4ff6ba12562bd2c83f35\", \"sql_unittests\": \"7449d985c37edfe814d83e1f39116f82a8bf8b0f\", \"storage_unittests\": \"481add98acbcc5f0cff2304a024c649f96839cd8\", \"sync_integration_tests\": \"c43f11cf668c4f2e88a49eadf38a2abbbd8fcb18\", \"traffic_annotation_auditor_unittests\": \"ff63de6ec67090bae06971588669ace5637ca13b\", \"ui_base_unittests\": \"bbc832488ba6bdd9ffcc6c3d5414dc1bc62456af\", \"ui_touch_selection_unittests\": \"8def8a089880fd765cd773d88d655eb894a60afa\", \"unit_tests\": \"d4709d8a7c863bdf2fab85d69fafc618047ba80c\", \"updater_tests\": \"fc220cee3d58a9b378fbfd53b8f6e294365c0468\", \"url_unittests\": \"f80900d0992b0d759c2ca96ac8be20ba13dbd41d\", \"views_unittests\": \"29951fea65d4278c4e65d58f0e6a76d2299760ef\", \"viz_unittests\": \"7858ea9facd3b844d187eb88ef8b0117af790043\", \"vr_common_unittests\": \"28232c3ba30787efd68b3357a004c6a6114d83e0\", \"vr_pixeltests\": \"b2411bfd9eecbaa114401d977ddd8db6b7d6605f\", \"weblayer_browsertests\": \"5125a4d76fdf1e0c1b7946b9c6ca9aaa37fa5bba\", \"weblayer_unittests\": \"49cdf97c9e2c3148a0cfa30c4276c2c0da26f618\", \"wm_unittests\": \"ba87a573e2ab0c0b48aa9a8ac1a5e5f56298d794\", \"wtf_unittests\": \"d249595d285d16217e00b6722f5fbb8f427d5710\", \"zlib_unittests\": \"26fc2e864722f1b888e8ee65659028b641ef7b7b\", \"zucchini_unittests\": \"9df673ea487ba85bb790c0119c698c1e1a049c77\"}}, \"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-7-w73f\"], \"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\": [\"5657-0417a3f\"], \"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": "1610048856442041",
"status": "COMPLETED",
"status_changed_ts": "1610052409949747",
"tags": [
"build_address:luci.chromium.ci/win-asan/16679",
"builder:win-asan",
"buildset:commit/git/7cb155718dd387e594c359ceed38f72d45670eb7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7cb155718dd387e594c359ceed38f72d45670eb7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024165195224108608",
"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/8858735133182033008/+/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:50f7355ed6512610",
"user_agent:luci-scheduler"
],
"updated_ts": "1610052409954007",
"url": "https://ci.chromium.org/b/8858735133182033008",
"utcnow_ts": "1610052411820894"
}
}
[20:46:51.831] Invocation finished in 59m23.316771193s with status SUCCEEDED