[03:43:51.701] New invocation is queued and will start shortly
[03:43:52.809] Starting the invocation (attempt 1)
[03:43:52.842] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:43:52.842] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:43:52.842] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021960896405174624",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ff13372ca453b6eb5f81a49c37f5a311383cbb0d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021960896405174624",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/ff13372ca453b6eb5f81a49c37f5a311383cbb0d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ff13372ca453b6eb5f81a49c37f5a311383cbb0d",
"gitiles_ref:refs/heads/master"
]
}
[03:43:54.427] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612151032948916",
"id": "8856530834433881680",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ff13372ca453b6eb5f81a49c37f5a311383cbb0d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612151034097188",
"tags": [
"build_address:luci.chromium.ci/win-asan/17105",
"builder:win-asan",
"buildset:commit/git/ff13372ca453b6eb5f81a49c37f5a311383cbb0d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ff13372ca453b6eb5f81a49c37f5a311383cbb0d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021960896405174624",
"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/8856530834433881680/+/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": "1612151034097824",
"url": "https://ci.chromium.org/b/8856530834433881680",
"utcnow_ts": "1612151034419802"
}
}
[03:43:54.427] Task URL: https://ci.chromium.org/b/8856530834433881680
[03:43:54.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:2:0) after 1m0s
[03:43:59.183] Received PubSub notification, asking Buildbucket for the build status
[03:43:59.263] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:54.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:2:0)
[03:44:54.496] Timer tick, asking Buildbucket for the build status
[03:44:54.639] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:54.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:4:0) after 1m0s
[03:45:54.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:4:0)
[03:45:54.765] Timer tick, asking Buildbucket for the build status
[03:45:55.201] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:55.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:5:0) after 1m0s
[03:46:55.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:5:0)
[03:46:55.228] Timer tick, asking Buildbucket for the build status
[03:46:55.340] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:55.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:6:0) after 1m0s
[03:47:55.570] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:6:0)
[03:47:55.570] Timer tick, asking Buildbucket for the build status
[03:47:55.695] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:55.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:7:0) after 1m0s
[03:48:55.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:7:0)
[03:48:55.839] Timer tick, asking Buildbucket for the build status
[03:48:55.999] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:55.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:8:0) after 1m0s
[03:49:56.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:8:0)
[03:49:56.233] Timer tick, asking Buildbucket for the build status
[03:49:56.362] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:56.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:9:0) after 1m0s
[03:50:56.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:9:0)
[03:50:56.465] Timer tick, asking Buildbucket for the build status
[03:50:57.490] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:57.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:10:0) after 1m0s
[03:51:57.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:10:0)
[03:51:57.555] Timer tick, asking Buildbucket for the build status
[03:51:57.914] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:57.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:11:0) after 1m0s
[03:52:57.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:11:0)
[03:52:57.886] Timer tick, asking Buildbucket for the build status
[03:52:58.012] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:58.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:12:0) after 1m0s
[03:53:58.011] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:12:0)
[03:53:58.011] Timer tick, asking Buildbucket for the build status
[03:53:58.285] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:58.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:13:0) after 1m0s
[03:54:58.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:13:0)
[03:54:58.381] Timer tick, asking Buildbucket for the build status
[03:54:58.441] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:58.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:14:0) after 1m0s
[03:55:58.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:14:0)
[03:55:58.491] Timer tick, asking Buildbucket for the build status
[03:55:58.560] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:58.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:15:0) after 1m0s
[03:56:58.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:15:0)
[03:56:58.581] Timer tick, asking Buildbucket for the build status
[03:56:58.647] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:58.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:16:0) after 1m0s
[03:57:58.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:16:0)
[03:57:58.668] Timer tick, asking Buildbucket for the build status
[03:57:58.745] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:58.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:17:0) after 1m0s
[03:58:58.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:17:0)
[03:58:58.849] Timer tick, asking Buildbucket for the build status
[03:58:59.035] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:59.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:18:0) after 1m0s
[03:59:59.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:18:0)
[03:59:59.063] Timer tick, asking Buildbucket for the build status
[03:59:59.406] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:59.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:19:0) after 1m0s
[04:00:59.466] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:19:0)
[04:00:59.466] Timer tick, asking Buildbucket for the build status
[04:00:59.596] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:59.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:20:0) after 1m0s
[04:01:59.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:20:0)
[04:01:59.616] Timer tick, asking Buildbucket for the build status
[04:01:59.754] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:59.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:21:0) after 1m0s
[04:02:59.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:21:0)
[04:02:59.790] Timer tick, asking Buildbucket for the build status
[04:03:00.114] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:00.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:22:0) after 1m0s
[04:04:00.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:22:0)
[04:04:00.143] Timer tick, asking Buildbucket for the build status
[04:04:00.291] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:00.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:23:0) after 1m0s
[04:05:00.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:23:0)
[04:05:00.334] Timer tick, asking Buildbucket for the build status
[04:05:00.453] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:00.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:24:0) after 1m0s
[04:06:00.466] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:24:0)
[04:06:00.466] Timer tick, asking Buildbucket for the build status
[04:06:00.590] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:00.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:25:0) after 1m0s
[04:07:00.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:25:0)
[04:07:00.714] Timer tick, asking Buildbucket for the build status
[04:07:00.840] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:00.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:26:0) after 1m0s
[04:08:01.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:26:0)
[04:08:01.124] Timer tick, asking Buildbucket for the build status
[04:08:01.237] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:01.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:27:0) after 1m0s
[04:09:01.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:27:0)
[04:09:01.332] Timer tick, asking Buildbucket for the build status
[04:09:01.454] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:01.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:28:0) after 1m0s
[04:10:01.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:28:0)
[04:10:01.499] Timer tick, asking Buildbucket for the build status
[04:10:01.757] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:01.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:29:0) after 1m0s
[04:11:01.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:29:0)
[04:11:01.838] Timer tick, asking Buildbucket for the build status
[04:11:01.983] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:01.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:30:0) after 1m0s
[04:12:02.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:30:0)
[04:12:02.022] Timer tick, asking Buildbucket for the build status
[04:12:02.104] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:02.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:31:0) after 1m0s
[04:13:02.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:31:0)
[04:13:02.148] Timer tick, asking Buildbucket for the build status
[04:13:02.259] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:02.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:32:0) after 1m0s
[04:14:02.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:32:0)
[04:14:02.287] Timer tick, asking Buildbucket for the build status
[04:14:02.726] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:02.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:33:0) after 1m0s
[04:15:02.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:33:0)
[04:15:02.745] Timer tick, asking Buildbucket for the build status
[04:15:02.880] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:02.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:34:0) after 1m0s
[04:16:02.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:34:0)
[04:16:02.907] Timer tick, asking Buildbucket for the build status
[04:16:03.004] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:03.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:35:0) after 1m0s
[04:17:03.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:35:0)
[04:17:03.052] Timer tick, asking Buildbucket for the build status
[04:17:03.136] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:03.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:36:0) after 1m0s
[04:18:03.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:36:0)
[04:18:03.127] Timer tick, asking Buildbucket for the build status
[04:18:03.236] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:03.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:37:0) after 1m0s
[04:19:03.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:37:0)
[04:19:03.317] Timer tick, asking Buildbucket for the build status
[04:19:03.637] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:03.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:38:0) after 1m0s
[04:20:03.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:38:0)
[04:20:03.656] Timer tick, asking Buildbucket for the build status
[04:20:04.279] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:04.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:39:0) after 1m0s
[04:21:04.376] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:39:0)
[04:21:04.376] Timer tick, asking Buildbucket for the build status
[04:21:04.812] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:04.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:40:0) after 1m0s
[04:22:04.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:40:0)
[04:22:04.826] Timer tick, asking Buildbucket for the build status
[04:22:05.202] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:05.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:41:0) after 1m0s
[04:23:05.349] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:41:0)
[04:23:05.349] Timer tick, asking Buildbucket for the build status
[04:23:05.430] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:05.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:42:0) after 1m0s
[04:24:05.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:42:0)
[04:24:05.495] Timer tick, asking Buildbucket for the build status
[04:24:06.111] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:06.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:43:0) after 1m0s
[04:25:06.158] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:43:0)
[04:25:06.158] Timer tick, asking Buildbucket for the build status
[04:25:06.272] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:06.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:44:0) after 1m0s
[04:26:06.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:44:0)
[04:26:06.414] Timer tick, asking Buildbucket for the build status
[04:26:06.516] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:06.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:45:0) after 1m0s
[04:27:06.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:45:0)
[04:27:06.533] Timer tick, asking Buildbucket for the build status
[04:27:06.786] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:06.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:46:0) after 1m0s
[04:28:06.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:46:0)
[04:28:06.896] Timer tick, asking Buildbucket for the build status
[04:28:06.960] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:06.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:47:0) after 1m0s
[04:29:06.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:47:0)
[04:29:06.979] Timer tick, asking Buildbucket for the build status
[04:29:07.102] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:07.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:48:0) after 1m0s
[04:30:07.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:48:0)
[04:30:07.126] Timer tick, asking Buildbucket for the build status
[04:30:07.269] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:07.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:49:0) after 1m0s
[04:31:07.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:49:0)
[04:31:07.867] Timer tick, asking Buildbucket for the build status
[04:31:07.949] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:07.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:50:0) after 1m0s
[04:32:07.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:50:0)
[04:32:07.999] Timer tick, asking Buildbucket for the build status
[04:32:08.074] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:08.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:51:0) after 1m0s
[04:33:08.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:51:0)
[04:33:08.101] Timer tick, asking Buildbucket for the build status
[04:33:08.221] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:08.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:52:0) after 1m0s
[04:34:08.250] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:52:0)
[04:34:08.250] Timer tick, asking Buildbucket for the build status
[04:34:09.046] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:09.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:53:0) after 1m0s
[04:35:09.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:53:0)
[04:35:09.076] Timer tick, asking Buildbucket for the build status
[04:35:09.702] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:09.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:54:0) after 1m0s
[04:36:09.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:54:0)
[04:36:09.734] Timer tick, asking Buildbucket for the build status
[04:36:09.852] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:09.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:55:0) after 1m0s
[04:37:09.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:55:0)
[04:37:09.881] Timer tick, asking Buildbucket for the build status
[04:37:09.963] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:09.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:56:0) after 1m0s
[04:38:10.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:56:0)
[04:38:10.003] Timer tick, asking Buildbucket for the build status
[04:38:10.229] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:10.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:57:0) after 1m0s
[04:39:10.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:57:0)
[04:39:10.263] Timer tick, asking Buildbucket for the build status
[04:39:10.546] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:10.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:58:0) after 1m0s
[04:40:10.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:58:0)
[04:40:10.608] Timer tick, asking Buildbucket for the build status
[04:40:10.742] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:10.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:59:0) after 1m0s
[04:41:10.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:59:0)
[04:41:10.818] Timer tick, asking Buildbucket for the build status
[04:41:10.909] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:10.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:60:0) after 1m0s
[04:42:10.935] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:60:0)
[04:42:10.935] Timer tick, asking Buildbucket for the build status
[04:42:11.000] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:11.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:61:0) after 1m0s
[04:43:11.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:61:0)
[04:43:11.033] Timer tick, asking Buildbucket for the build status
[04:43:11.269] Build 8856530834433881680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:11.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021960896405174624:62:0) after 1m0s
[04:43:56.587] Received PubSub notification, asking Buildbucket for the build status
[04:43:56.788] Build 8856530834433881680: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:43:56.788] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612154635404271",
"created_by": "project:chromium",
"created_ts": "1612151032948916",
"id": "8856530834433881680",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ff13372ca453b6eb5f81a49c37f5a311383cbb0d\"}}",
"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\": \"ff13372ca453b6eb5f81a49c37f5a311383cbb0d\", \"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-us-central1-b-32-1-y2bx\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17105, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"0be050a44a4308ace01229b89bd4560457356007\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"ff13372ca453b6eb5f81a49c37f5a311383cbb0d\", \"got_revision_cp\": \"refs/heads/master@{#848989}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"8b4cf1c2491d56cf9eda153b5455e4b295732b7c\", \"got_v8_revision\": \"6069442cab5b767e9722d20cabda6d4864fb7210\", \"got_v8_revision_cp\": \"refs/heads/9.0.102@{#1}\", \"got_webrtc_revision\": \"41bfcf4a63611409220fcd458a03deaa2cd23619\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33116}\", \"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\": \"ff13372ca453b6eb5f81a49c37f5a311383cbb0d\", \"swarm_hashes_refs/heads/master(at){#848989}_without_patch\": {\"absl_hardening_tests\": \"54efe653eb29ffd0408a4310ba6170cd3f723d26\", \"accessibility_unittests\": \"7b09de4016baf5728a4c613e4cd45208fa5d1da1\", \"angle_unittests\": \"4cd792690eeb1b221fbda31e6b5762998eae92a3\", \"app_shell_unittests\": \"892cb38ebe155c7ee613b8503c45024931247553\", \"aura_unittests\": \"908e5801f262242173ffcaa3eb253ded55c260f0\", \"base_unittests\": \"59ca6465fde0e52df071d0976cc81c47fa039154\", \"base_util_unittests\": \"b8a938daa05583c3356c2b6658659f4a4496dc76\", \"blink_common_unittests\": \"9557cd8bf9cf0a8b5901000c4d811d6bc8f8c823\", \"blink_fuzzer_unittests\": \"2d3da46c016e4c7cbbbdb38ef8a53ecaf1eaa320\", \"blink_heap_unittests\": \"25abe332824e17d21cb99c14babde75230421085\", \"blink_platform_unittests\": \"55aeb504a9441b3f89c63646afaafc02582e2d4e\", \"blink_unittests\": \"227e9103f02f25aba7cd16da7006fe163d16ed65\", \"boringssl_crypto_tests\": \"3c8d2559da392fae7f9aeef8f796d574e470b898\", \"boringssl_ssl_tests\": \"537a3ff703d15263942349feea13c0091e9cebd5\", \"browser_switcher_bho_unittests\": \"5aa750a9772e4e130f025c48361cb62cba0e5597\", \"browser_tests\": \"4c74cdb94675ffc3b33818ec5d8543eec9f66ebe\", \"capture_unittests\": \"df47d2f118533d0b046f9e00b0c96e301c650346\", \"cast_unittests\": \"15cab57b5547670e2eeb5fb4e2f1d02d3fefbb10\", \"cc_unittests\": \"c2f1ae2e6172a46c74e4da8d3a53147fe4d3ee34\", \"chrome_app_unittests\": \"3f6be79989622887ad21acd271e727d97d0ceabc\", \"chrome_cleaner_unittests\": \"6972b673cb403d105861b1178e2697ce14fa15aa\", \"chrome_elf_unittests\": \"62bbe7e1ac69127deb24ed8427e751c33f45df56\", \"chromedriver_unittests\": \"3fde265722a0d511d2112a8b7f101cc3f289b21f\", \"components_browsertests\": \"e0e52edfed122813014ed32b6ec0514bc6082d47\", \"components_unittests\": \"8d419161836c99bced3e5d738be4c8458bea3b60\", \"compositor_unittests\": \"c64c7e4e0f206038435be4a37274b131f8dbf865\", \"content_browsertests\": \"ce0844adf4768fc8ddc6bfb4d8355145ae2b1085\", \"content_unittests\": \"3d3b99f28c089ab05df23e237b053ff6ddeda99a\", \"courgette_unittests\": \"01836ce5c6dd616175cd1913a7397fdb7b914055\", \"crashpad_tests\": \"1bc71d38c563b9953a09ced2dfb7195ee06cb3a2\", \"cronet_tests\": \"73f0fabeda4c9846ee859151c39ee2c344423fbd\", \"cronet_unittests\": \"46e3fcf02c4c5415e64dc3f12c7a987b416e03c7\", \"crypto_unittests\": \"9ba1caa01ef2664436cec9cc0327c9eaeae91602\", \"delayloads_unittests\": \"57a6dd79294352a2542b3093bcd9cdcb54489b79\", \"device_unittests\": \"0993f7ff4273ca7db15aa9c3ec40507ebc158324\", \"display_unittests\": \"33ace8f1410d3f6349d8bb23ad9723ba50bcdeaf\", \"elevation_service_unittests\": \"25ae4c436c9008a53538a4ceb78a51e85fa107fb\", \"events_unittests\": \"e775887ba2ca2903d52e6c2d491efe11b55d8cb5\", \"extensions_browsertests\": \"496ce64d3af19d7e9dd02bad166117cc2330b259\", \"extensions_unittests\": \"a8d315efb6423072554313d70de883f9970985d4\", \"filesystem_service_unittests\": \"0b7c53b96121ba682338d67bd8759716655adc23\", \"gcm_unit_tests\": \"a91d9924e16451ce6c329a12439907c4c8e3dc73\", \"gcp_unittests\": \"1bb92005d88de1098217f61b7729da3d2490eca4\", \"gfx_unittests\": \"8e3ef9d8434edcc742bf09d37fb257204bd3e9c3\", \"gin_unittests\": \"8ddbc145212921d35b38f37ce653958dfc826925\", \"google_apis_unittests\": \"979ecdec49e5763134b5799756aaf63dfa968e90\", \"gpu_unittests\": \"95250902c081571bb16bd97f4f5b99b0f11bc1e5\", \"gwp_asan_unittests\": \"d922626f4d63943e2b30c1dee963555c20d07e61\", \"headless_browsertests\": \"60ac16691381fff4387bf427d0aa3e6a797d04b9\", \"headless_unittests\": \"2270663396caa91031a643f630125adee5fd296a\", \"install_static_unittests\": \"c7cf8633cf329daefb3d7093c646612b8487ef0d\", \"installer_util_unittests\": \"64448b76935748d23c96c6c7bfa902e99dc9e018\", \"interactive_ui_tests\": \"01e95ee87dbbe9cbe3544b1d530c75d6e5fa0a6f\", \"ipc_tests\": \"981e28a98a0c6f9b882f687ff7e4e9ba113a9173\", \"jingle_unittests\": \"0733c551b68cab5934fbc7b843acdb3a651a956b\", \"latency_unittests\": \"bad79be6996af3b0b26dd0e79769c12905ab7d26\", \"libjingle_xmpp_unittests\": \"1327d25f5573d93a4c711cfc01541512a5a724c5\", \"liburlpattern_unittests\": \"9d8a436360cafe877a842ac86e172549ed43b66e\", \"media_blink_unittests\": \"505e4accabd0076972cf0d44c7b7bf83fbb0be50\", \"media_unittests\": \"a20b6ee0bcccf0743e16c7d7d9f6f222041f6e37\", \"message_center_unittests\": \"a3af172ce96a3a2ff89290b635593fb3524c785b\", \"midi_unittests\": \"dc4bb666d6c61f615fd61dbc61d09eb0c2bcce7c\", \"mojo_core_unittests\": \"5e99d6e01d751cfa0fc244a36c25fcf7bf6954db\", \"mojo_unittests\": \"f893fcd9170974d7decc795c4890130c7c7ccb2a\", \"nacl_loader_unittests\": \"99d936705ecab4a2c8fc3192f3863dcf978ac903\", \"native_theme_unittests\": \"e89203da97e05274d997cecc37457a7c6691d95d\", \"net_unittests\": \"a0e7352c4ce4612013d432b30e9253a554632145\", \"notification_helper_unittests\": \"9dde974d40a45a67ac3b1eb5bbf57f8caa452f9c\", \"pdf_unittests\": \"d576e520ae5425381243a069f0e06daf81ee5ea6\", \"perfetto_unittests\": \"250b8a7a7bdb2c8185a5d9ec0f70562d44005242\", \"ppapi_unittests\": \"f17fc03d86e581675ca6914748150843e0beeae0\", \"printing_unittests\": \"5c013c790d79fef9e1114bfd5198ad81b1692208\", \"remoting_unittests\": \"756dd7d0d72ff540c3f06fd0085981272befeec9\", \"sbox_integration_tests\": \"b8e746a2f9de2048eed46435e17f422ecd6e14d0\", \"sbox_unittests\": \"e9728144e8bd0110aedb71bf34553d0b3c45f525\", \"sbox_validation_tests\": \"80fbe13df3a07fe477a7450b65ddc4aa83b228d2\", \"service_manager_unittests\": \"0d31728ee44eb17bad3bfff468c1e8e26b25c5fd\", \"services_unittests\": \"3d81ce4f1c52b55d420cc5d471ace786d6757eb2\", \"setup_unittests\": \"1a8051bae7db17a94c8658d0d7fcfdb666173994\", \"shell_dialogs_unittests\": \"c4baea57210c41e3529cf07e6ef7f81329a9560c\", \"skia_unittests\": \"4836d03443606b0cd0ca7efee7718cb5952ff45b\", \"snapshot_unittests\": \"7474338b8f3d2d6d8def49ae06d310018e47842b\", \"sql_unittests\": \"267040b731bfc2161f3dfa7b233b4d5d604cb3bf\", \"storage_unittests\": \"fd349436e9d74e7384d8bf09dc11e601656467c2\", \"sync_integration_tests\": \"15953167254ebd22a933780c34fd95ada1a82e21\", \"traffic_annotation_auditor_unittests\": \"ec5d35c2e34616b972c0e51487143429415f061a\", \"ui_base_unittests\": \"e715a05c36280005c83f69f57eb1997e0b77e7d5\", \"ui_touch_selection_unittests\": \"f5f3b592fc8f322b23da4131dd2756e7d076a160\", \"unit_tests\": \"9b78b372d977653e268eb60d68d948160b124635\", \"updater_tests\": \"3d89e40b09102a7fa1367fc0bc9d5f9c093ae40b\", \"url_unittests\": \"07bb83330d8d20976982bf9e382019f2e8f41efe\", \"views_unittests\": \"14a7293e650ea1ec6d5b91b6022a3c75fab11d05\", \"viz_unittests\": \"bcb5a1d031e6263286cfe5d24b791c628a05e1b6\", \"vr_common_unittests\": \"29490e5cb3f8502fe67122b077b14e7e5539a96b\", \"vr_pixeltests\": \"225ac6c74be6ae8f5de5b58e0410210a7d75b537\", \"weblayer_browsertests\": \"fd39af00433cf00d304b7910fd016176a77b61c2\", \"weblayer_unittests\": \"d52018c7f29b79bb4c7b3afc2561cf0e75a491b6\", \"wm_unittests\": \"6e6a608b2dfaead931684151049dc7e3d469d400\", \"wtf_unittests\": \"1b34fe72f3bd8f17820e53521105443b9eb3c120\", \"zlib_unittests\": \"e5553eff791df2decc8f7026c12654aaaa409792\", \"zucchini_unittests\": \"84d957c3537bb5c8a9b4834f7e2c8e032ca6792d\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-y2bx\"], \"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\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612151038292201",
"status": "COMPLETED",
"status_changed_ts": "1612154635834357",
"tags": [
"build_address:luci.chromium.ci/win-asan/17105",
"builder:win-asan",
"buildset:commit/git/ff13372ca453b6eb5f81a49c37f5a311383cbb0d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ff13372ca453b6eb5f81a49c37f5a311383cbb0d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021960896405174624",
"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/8856530834433881680/+/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:5174821b22e47910",
"user_agent:luci-scheduler"
],
"updated_ts": "1612154635840296",
"url": "https://ci.chromium.org/b/8856530834433881680",
"utcnow_ts": "1612154636767101"
}
}
[04:43:56.788] Invocation finished in 1h0m5.104450765s with status SUCCEEDED