[12:53:08.581] New invocation is queued and will start shortly
[12:53:09.677] Starting the invocation (attempt 1)
[12:53:09.722] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:53:09.723] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:53:09.723] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026184396031811920",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"213499555e469b78b880796236fbb4af119f8f28\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026184396031811920",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/213499555e469b78b880796236fbb4af119f8f28",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/213499555e469b78b880796236fbb4af119f8f28",
"gitiles_ref:refs/heads/master"
]
}
[12:53:10.373] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608123189805929",
"id": "8860754334085308480",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"213499555e469b78b880796236fbb4af119f8f28\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608123190261285",
"tags": [
"build_address:luci.chromium.ci/win-asan/16260",
"builder:win-asan",
"buildset:commit/git/213499555e469b78b880796236fbb4af119f8f28",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/213499555e469b78b880796236fbb4af119f8f28",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026184396031811920",
"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/8860754334085308480/+/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": "1608123190261432",
"url": "https://ci.chromium.org/b/8860754334085308480",
"utcnow_ts": "1608123190363190"
}
}
[12:53:10.373] Task URL: https://ci.chromium.org/b/8860754334085308480
[12:53:10.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:2:0) after 1m0s
[12:53:23.620] Received PubSub notification, asking Buildbucket for the build status
[12:53:23.683] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:10.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:2:0)
[12:54:10.409] Timer tick, asking Buildbucket for the build status
[12:54:10.609] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:10.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:4:0) after 1m0s
[12:55:10.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:4:0)
[12:55:10.708] Timer tick, asking Buildbucket for the build status
[12:55:10.898] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:10.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:5:0) after 1m0s
[12:56:10.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:5:0)
[12:56:10.942] Timer tick, asking Buildbucket for the build status
[12:56:11.051] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:11.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:6:0) after 1m0s
[12:57:11.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:6:0)
[12:57:11.094] Timer tick, asking Buildbucket for the build status
[12:57:11.201] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:11.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:7:0) after 1m0s
[12:58:11.225] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:7:0)
[12:58:11.225] Timer tick, asking Buildbucket for the build status
[12:58:11.453] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:11.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:8:0) after 1m0s
[12:59:11.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:8:0)
[12:59:11.520] Timer tick, asking Buildbucket for the build status
[12:59:11.659] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:11.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:9:0) after 1m0s
[13:00:11.698] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:9:0)
[13:00:11.698] Timer tick, asking Buildbucket for the build status
[13:00:11.832] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:11.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:10:0) after 1m0s
[13:01:12.163] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:10:0)
[13:01:12.163] Timer tick, asking Buildbucket for the build status
[13:01:12.225] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:12.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:11:0) after 1m0s
[13:02:12.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:11:0)
[13:02:12.448] Timer tick, asking Buildbucket for the build status
[13:02:12.655] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:12.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:12:0) after 1m0s
[13:03:12.754] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:12:0)
[13:03:12.754] Timer tick, asking Buildbucket for the build status
[13:03:12.869] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:12.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:13:0) after 1m0s
[13:04:14.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:13:0)
[13:04:14.684] Timer tick, asking Buildbucket for the build status
[13:04:14.745] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:14.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:14:0) after 1m0s
[13:05:14.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:14:0)
[13:05:14.828] Timer tick, asking Buildbucket for the build status
[13:05:14.890] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:14.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:15:0) after 1m0s
[13:06:15.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:15:0)
[13:06:15.419] Timer tick, asking Buildbucket for the build status
[13:06:15.695] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:15.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:16:0) after 1m0s
[13:07:15.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:16:0)
[13:07:15.785] Timer tick, asking Buildbucket for the build status
[13:07:15.947] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:15.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:17:0) after 1m0s
[13:08:15.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:17:0)
[13:08:15.975] Timer tick, asking Buildbucket for the build status
[13:08:16.093] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:16.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:18:0) after 1m0s
[13:09:16.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:18:0)
[13:09:16.078] Timer tick, asking Buildbucket for the build status
[13:09:16.193] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:16.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:19:0) after 1m0s
[13:10:16.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:19:0)
[13:10:16.279] Timer tick, asking Buildbucket for the build status
[13:10:16.353] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:16.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:20:0) after 1m0s
[13:11:16.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:20:0)
[13:11:16.374] Timer tick, asking Buildbucket for the build status
[13:11:16.805] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:16.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:21:0) after 1m0s
[13:12:16.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:21:0)
[13:12:16.824] Timer tick, asking Buildbucket for the build status
[13:12:17.016] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:17.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:22:0) after 1m0s
[13:13:17.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:22:0)
[13:13:17.128] Timer tick, asking Buildbucket for the build status
[13:13:17.230] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:17.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:23:0) after 1m0s
[13:14:17.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:23:0)
[13:14:17.502] Timer tick, asking Buildbucket for the build status
[13:14:17.792] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:17.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:24:0) after 1m0s
[13:15:18.669] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:24:0)
[13:15:18.669] Timer tick, asking Buildbucket for the build status
[13:15:18.777] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:18.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:25:0) after 1m0s
[13:16:18.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:25:0)
[13:16:18.786] Timer tick, asking Buildbucket for the build status
[13:16:19.469] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:19.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:26:0) after 1m0s
[13:17:19.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:26:0)
[13:17:19.487] Timer tick, asking Buildbucket for the build status
[13:17:19.680] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:19.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:27:0) after 1m0s
[13:18:19.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:27:0)
[13:18:19.775] Timer tick, asking Buildbucket for the build status
[13:18:20.334] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:20.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:28:0) after 1m0s
[13:19:20.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:28:0)
[13:19:20.427] Timer tick, asking Buildbucket for the build status
[13:19:20.628] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:20.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:29:0) after 1m0s
[13:20:20.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:29:0)
[13:20:20.704] Timer tick, asking Buildbucket for the build status
[13:20:20.886] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:20.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:30:0) after 1m0s
[13:21:20.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:30:0)
[13:21:20.911] Timer tick, asking Buildbucket for the build status
[13:21:21.054] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:21.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:31:0) after 1m0s
[13:22:21.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:31:0)
[13:22:21.196] Timer tick, asking Buildbucket for the build status
[13:22:21.706] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:21.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:32:0) after 1m0s
[13:23:21.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:32:0)
[13:23:21.854] Timer tick, asking Buildbucket for the build status
[13:23:21.970] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:21.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:33:0) after 1m0s
[13:24:21.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:33:0)
[13:24:21.976] Timer tick, asking Buildbucket for the build status
[13:24:22.097] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:22.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:34:0) after 1m0s
[13:25:22.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:34:0)
[13:25:22.265] Timer tick, asking Buildbucket for the build status
[13:25:22.409] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:22.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:35:0) after 1m0s
[13:26:22.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:35:0)
[13:26:22.455] Timer tick, asking Buildbucket for the build status
[13:26:22.542] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:22.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:36:0) after 1m0s
[13:27:22.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:36:0)
[13:27:22.681] Timer tick, asking Buildbucket for the build status
[13:27:22.751] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:22.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:37:0) after 1m0s
[13:28:22.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:37:0)
[13:28:22.822] Timer tick, asking Buildbucket for the build status
[13:28:22.944] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:22.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:38:0) after 1m0s
[13:29:23.163] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:38:0)
[13:29:23.163] Timer tick, asking Buildbucket for the build status
[13:29:23.251] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:23.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:39:0) after 1m0s
[13:30:23.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:39:0)
[13:30:23.329] Timer tick, asking Buildbucket for the build status
[13:30:23.462] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:23.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:40:0) after 1m0s
[13:31:23.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:40:0)
[13:31:23.618] Timer tick, asking Buildbucket for the build status
[13:31:23.692] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:23.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:41:0) after 1m0s
[13:32:23.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:41:0)
[13:32:23.778] Timer tick, asking Buildbucket for the build status
[13:32:23.903] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:23.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:42:0) after 1m0s
[13:33:23.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:42:0)
[13:33:23.946] Timer tick, asking Buildbucket for the build status
[13:33:24.183] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:24.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:43:0) after 1m0s
[13:34:24.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:43:0)
[13:34:24.234] Timer tick, asking Buildbucket for the build status
[13:34:24.332] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:24.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:44:0) after 1m0s
[13:35:24.356] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:44:0)
[13:35:24.356] Timer tick, asking Buildbucket for the build status
[13:35:24.449] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:24.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:45:0) after 1m0s
[13:36:24.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:45:0)
[13:36:24.483] Timer tick, asking Buildbucket for the build status
[13:36:24.589] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:24.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:46:0) after 1m0s
[13:37:24.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:46:0)
[13:37:24.653] Timer tick, asking Buildbucket for the build status
[13:37:24.811] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:24.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:47:0) after 1m0s
[13:38:24.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:47:0)
[13:38:24.928] Timer tick, asking Buildbucket for the build status
[13:38:25.024] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:25.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:48:0) after 1m0s
[13:39:25.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:48:0)
[13:39:25.056] Timer tick, asking Buildbucket for the build status
[13:39:25.525] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:25.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:49:0) after 1m0s
[13:40:25.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:49:0)
[13:40:25.586] Timer tick, asking Buildbucket for the build status
[13:40:25.709] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:25.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:50:0) after 1m0s
[13:41:25.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:50:0)
[13:41:25.785] Timer tick, asking Buildbucket for the build status
[13:41:25.919] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:25.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:51:0) after 1m0s
[13:42:25.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:51:0)
[13:42:25.940] Timer tick, asking Buildbucket for the build status
[13:42:26.053] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:26.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:52:0) after 1m0s
[13:43:26.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:52:0)
[13:43:26.078] Timer tick, asking Buildbucket for the build status
[13:43:26.245] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:26.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:53:0) after 1m0s
[13:44:26.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:53:0)
[13:44:26.319] Timer tick, asking Buildbucket for the build status
[13:44:26.551] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:26.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:54:0) after 1m0s
[13:45:26.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:54:0)
[13:45:26.546] Timer tick, asking Buildbucket for the build status
[13:45:26.682] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:26.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:55:0) after 1m0s
[13:46:26.764] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:55:0)
[13:46:26.764] Timer tick, asking Buildbucket for the build status
[13:46:26.836] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:26.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:56:0) after 1m0s
[13:47:26.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:56:0)
[13:47:26.903] Timer tick, asking Buildbucket for the build status
[13:47:27.085] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:27.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:57:0) after 1m0s
[13:48:27.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:57:0)
[13:48:27.126] Timer tick, asking Buildbucket for the build status
[13:48:27.390] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:27.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:58:0) after 1m0s
[13:49:27.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:58:0)
[13:49:27.400] Timer tick, asking Buildbucket for the build status
[13:49:27.501] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:27.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:59:0) after 1m0s
[13:50:27.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:59:0)
[13:50:27.541] Timer tick, asking Buildbucket for the build status
[13:50:27.634] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:27.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:60:0) after 1m0s
[13:51:28.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:60:0)
[13:51:28.582] Timer tick, asking Buildbucket for the build status
[13:51:28.647] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:28.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:61:0) after 1m0s
[13:52:28.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:61:0)
[13:52:28.668] Timer tick, asking Buildbucket for the build status
[13:52:28.729] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:28.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:62:0) after 1m0s
[13:53:28.799] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:62:0)
[13:53:28.799] Timer tick, asking Buildbucket for the build status
[13:53:28.891] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:28.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:63:0) after 1m0s
[13:54:28.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:63:0)
[13:54:28.914] Timer tick, asking Buildbucket for the build status
[13:54:29.025] Build 8860754334085308480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:29.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026184396031811920:64:0) after 1m0s
[13:55:20.974] Received PubSub notification, asking Buildbucket for the build status
[13:55:21.219] Build 8860754334085308480: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:55:21.219] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608126919057951",
"created_by": "project:chromium",
"created_ts": "1608123189805929",
"id": "8860754334085308480",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"213499555e469b78b880796236fbb4af119f8f28\"}}",
"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\": \"213499555e469b78b880796236fbb4af119f8f28\", \"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-qsbv\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16260, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"328f72ae21cbc371431c3776718b3a2ebfbb79ca\", \"got_dawn_revision\": \"a7bb5a5b74f5703fcde1e6af239a01f4f9043d6e\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"213499555e469b78b880796236fbb4af119f8f28\", \"got_revision_cp\": \"refs/heads/master@{#837539}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1ca6504eb7bb9971d317cf8c8033b38b914036b7\", \"got_v8_revision\": \"24e738a6bc98c6971154c655e17f656297085c45\", \"got_v8_revision_cp\": \"refs/heads/8.9.157@{#1}\", \"got_webrtc_revision\": \"cf8ec1f6867651204d81b14a8c31a86e6eb97572\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32829}\", \"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\": \"213499555e469b78b880796236fbb4af119f8f28\", \"swarm_hashes_refs/heads/master(at){#837539}_without_patch\": {\"absl_hardening_tests\": \"9079251ff8746fad501cd85dc95ac5b66df5e626\", \"accessibility_unittests\": \"5dcedaab460d00f39a512e4af6c4b3597d47d2d9\", \"angle_unittests\": \"a31f2df9cb1258c8fa48a7fe1f4a1198e1849c5b\", \"app_shell_unittests\": \"d3c3939fa6263a3e362bfe1348f15894e8f27947\", \"aura_unittests\": \"3dd3395cf4ee6732a0241c17acc9f846754ea719\", \"base_unittests\": \"0f3e45be3030916253b39328ddf9c4a3212b668f\", \"base_util_unittests\": \"5ffeb1b8f320b926719a64a6180493ac2bf942ba\", \"blink_common_unittests\": \"51d633e1f4256ae520fc87659323a79e2c6bddbb\", \"blink_fuzzer_unittests\": \"a9ae7ef5c0d1aabfd2fe942801d3a0e4731d2366\", \"blink_heap_unittests\": \"9826a53abda7727625d4bbe9ed0601ef598ec5ad\", \"blink_platform_unittests\": \"fe04df3b64d616e9d8d4f96d0eee0d6cb90d2475\", \"blink_unittests\": \"19a0a212822cc45fa7f3658be8d60636f4b668cb\", \"boringssl_crypto_tests\": \"c44e9207a9109045a3d6c137fe735a9edecda310\", \"boringssl_ssl_tests\": \"d5cb9c582c9fe7d767fed19b5bc2ce1d2ddef435\", \"browser_switcher_bho_unittests\": \"7b8442ff482eb27141b63e4e31a746c81a732c04\", \"browser_tests\": \"a2ca0acc04d18a9f6920687a6f9a983575a6a1d3\", \"capture_unittests\": \"aac1f48a416ca86f5e1511895932085b7e7e4444\", \"cast_unittests\": \"7621dffac224f46f8a67572d3995658b0434cf1b\", \"cc_unittests\": \"72c8b8eb581f1ba35bfa4ffda5c467c5f49d2088\", \"chrome_app_unittests\": \"afc19020d15d727b09a3c0cf9bd6d062dbb9997c\", \"chrome_cleaner_unittests\": \"b8817dec27153e9ecde40b3e4757708ccf454a62\", \"chrome_elf_unittests\": \"ec09e61912b2885b802fd06ed2f49dc56c14f0a4\", \"chromedriver_unittests\": \"16c849292093e69d55fed1f922f6513555c2d0de\", \"components_browsertests\": \"3bf53ef7ad11d51908b1546364d0561312cd5d8a\", \"components_unittests\": \"eb21b50875e2098ab74e96b4f774a82d4441b3c1\", \"compositor_unittests\": \"452a5960a75296e74bd8e04d9c7d7b4aab9fd020\", \"content_browsertests\": \"61c231b0d9736427e5a9462370f04b1a46116610\", \"content_unittests\": \"af51e74f873ce6a10de12b2b884d79276c922168\", \"courgette_unittests\": \"22b20b277d3147c660bd35fdaaa923a51147f5a4\", \"crashpad_tests\": \"018a5639af8f7753f65ef10172cc4f96e4b21f5f\", \"cronet_tests\": \"abf2093980ce2a5e74a469303a74223dbd467987\", \"cronet_unittests\": \"07f728e6600891e545d8b7b12c1a2ce9a89cf057\", \"crypto_unittests\": \"6538fe678bdd765c4f281e0b8c4b8882579ba553\", \"delayloads_unittests\": \"69a11b506d0e4764e36a602a832eb9447dad8d7e\", \"device_unittests\": \"56407ce5a1f367d37a938ccfece0c7ed9b37d8eb\", \"display_unittests\": \"d8516d6c34accecc2b4ca5c52a3d2b43a05c105b\", \"elevation_service_unittests\": \"cb1f6295ee8db55892bca03aa99b1ffcc0aa1b32\", \"events_unittests\": \"dcd07f8b7e4a30bd6bb51695d0ea29350b985439\", \"extensions_browsertests\": \"1d14bee47afb1497fcb7c0355768f99dfb1d3eeb\", \"extensions_unittests\": \"a7d2eca4d73ccd1258441a3c59db3fc3bc2dc679\", \"filesystem_service_unittests\": \"cc573b2e61cc6c40cb1c793494e5937ec7d9e2e6\", \"gcm_unit_tests\": \"d5f3bc0bcb9f19a4fdaadb013c355502006a03d7\", \"gcp_unittests\": \"6ae690d6fabea1fea2539ca36fed2c422bfbab46\", \"gfx_unittests\": \"612d8bb5b16e4fe205ca2bace6ca1596af6a9512\", \"gin_unittests\": \"0d251d98fe4c8abdacf82b700def6deef04c404e\", \"google_apis_unittests\": \"a60b9fac68ad4e0c4932926351f582eec7ad87a1\", \"gpu_unittests\": \"65d0551c8f5fafb0b43bd97a3e52dd1669e7ac82\", \"gwp_asan_unittests\": \"c0e8505c1449dba9fd7ed296915bf94a53c81a46\", \"headless_browsertests\": \"5b23c52dfefc4b675da9717226239f7fa8dc1098\", \"headless_unittests\": \"b03910f1c3b32c0868658e8c622606082c4305ec\", \"install_static_unittests\": \"b0954bfd8c2a96a941fe760f4f22b98e5180b09a\", \"installer_util_unittests\": \"4e97561b336f217d73e670a9506814f453006d5c\", \"interactive_ui_tests\": \"5820d4ad06368812c1a6e1e923e37dbed180caa5\", \"ipc_tests\": \"635303ddc396d0f4e4cda1f07d2f71486c175004\", \"jingle_unittests\": \"949bd1417dc41f46bf9cc3561ebb88316ea176ea\", \"latency_unittests\": \"1904efe3337624925379dce5ced04ae81cd2d82b\", \"libjingle_xmpp_unittests\": \"03191495b8579c6b954c1b6430f881e53e728a53\", \"liburlpattern_unittests\": \"e89f7174e49a791db2a5c638860968f62424d89f\", \"media_blink_unittests\": \"f6a956d9b5f7de667aceb256ce63959fec5893c9\", \"media_unittests\": \"75060a671beb3575ba720c5df32f35bb5df2e5b9\", \"message_center_unittests\": \"350e0740010d2a56af53eec5e758073722bb9402\", \"midi_unittests\": \"7ce8daecbc5366b7d992908e4c6773488f4f0572\", \"mojo_core_unittests\": \"d5e5549239150101b08de694d28a1054e3d8179f\", \"mojo_unittests\": \"8c198836009121717633ac2e3a3aeca4ecebe83b\", \"nacl_loader_unittests\": \"bfa5a0d4406fd7b971eaf152885efbae8a1abe9b\", \"native_theme_unittests\": \"73c8a07ad11617b49b222329954815083276b6d4\", \"net_unittests\": \"da1fe1695ec419a02e22c8d086cf876e264a6bd9\", \"notification_helper_unittests\": \"d7df31dcdd1eda5cfa63f0885cba323c9b23123a\", \"pdf_unittests\": \"6b7c58fab05937b9a33eb794939a060f3f4f8244\", \"perfetto_unittests\": \"3ba5a1795dde3ab5a7db33945cb7945d5c4c26b6\", \"ppapi_unittests\": \"abdf446855c2a74c3c9e98778dd24331c1ebb2ad\", \"printing_unittests\": \"7c895244d0b63665014a998546a77935ecace582\", \"remoting_unittests\": \"565f3c0423316c54b16c6e1c40913691fde131a4\", \"sbox_integration_tests\": \"45cc3c9a6a46dee0ee9e2e00424b4dc7764f301b\", \"sbox_unittests\": \"5e9abb082de02c1172baa498437602d5e45fdb7c\", \"sbox_validation_tests\": \"b653e51b3a6f2399d8be6341e1f2598cf590baa2\", \"service_manager_unittests\": \"5156d7cd1c06655ead9ea9627af26ab7393c9c39\", \"services_unittests\": \"ac904316922ec5743d6ff00252ba3bc28c6b51bb\", \"setup_unittests\": \"7063a8fac654de018e226fdaaca59842ecf90cee\", \"shell_dialogs_unittests\": \"de41c889b1b90150542c3856c0cbb6b29bfae043\", \"skia_unittests\": \"fbf3202e6d3bbeeb690e9cb440e57c98f3ff8abf\", \"snapshot_unittests\": \"7d75300e4886bedd2666403e1cd6c4ff5ff1f366\", \"sql_unittests\": \"a846f0853b1a4e8b4d1a4a2d32e7177340577831\", \"storage_unittests\": \"ba568b7c6b7f70074c33f69d51ab2d1ccd592cd6\", \"sync_integration_tests\": \"acf2f2d4a7628c72eed02aeeaca10ec63a949096\", \"traffic_annotation_auditor_unittests\": \"2e34c4b369d54ec85e794b748045e1ed9f9106b6\", \"ui_base_unittests\": \"3e33b3df31d6658135e3a55f0428c1a83007cc88\", \"ui_touch_selection_unittests\": \"8bce86787fcfba264306f352b91de9bac9920d9e\", \"unit_tests\": \"cc8ad1af38db7e4a3340dd425354cfeea089090b\", \"updater_tests\": \"2c4bd95556ccf1fbf19c4ab481fd0f44220e1b37\", \"url_unittests\": \"52fa2f48ffdeb27358d80e888d3328dbc863569c\", \"views_unittests\": \"4b8ec50c1022b317fa80233ad400d73a1d67afa6\", \"viz_unittests\": \"1e99a191603b801839753e319d73fe12cd308b1b\", \"vr_common_unittests\": \"87218ad9f9232ac0bedef0cb8baacdb52f48aed2\", \"vr_pixeltests\": \"150649e60c9119b96a4d3de4872cd1c9dab5cfa8\", \"weblayer_browsertests\": \"30cf189a77c6a350a9986a9c15c10174785d6f75\", \"weblayer_unittests\": \"645bdc9d7f93697784f5872bbffbb2654504dfaf\", \"wm_unittests\": \"a44f87e87c8fc80f695ae0b0c0d64ff2438c7cce\", \"wtf_unittests\": \"04d2f8c862143576970e12cf5c7c07e4d7148c48\", \"zlib_unittests\": \"13bf1fb17b8fa2e0d29714362802ed63c29bc609\", \"zucchini_unittests\": \"ae143252da671d2628d07b3a48bfbd486233994e\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_91c730c3e6ead475130e2ae78bc3f03e09cb9dcb91c3469d4450b29020110401_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-qsbv\"], \"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-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608123192369101",
"status": "COMPLETED",
"status_changed_ts": "1608126919925437",
"tags": [
"build_address:luci.chromium.ci/win-asan/16260",
"builder:win-asan",
"buildset:commit/git/213499555e469b78b880796236fbb4af119f8f28",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/213499555e469b78b880796236fbb4af119f8f28",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026184396031811920",
"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/8860754334085308480/+/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:50846e14fc240810",
"user_agent:luci-scheduler"
],
"updated_ts": "1608126920015550",
"url": "https://ci.chromium.org/b/8860754334085308480",
"utcnow_ts": "1608126921209062"
}
}
[13:55:21.219] Invocation finished in 1h2m12.653696428s with status SUCCEEDED