[17:21:21.648] New invocation is queued and will start shortly
[17:21:24.334] Starting the invocation (attempt 1)
[17:21:24.372] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:21:24.372] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:21:24.372] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026801699995306480",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"bfb5e2c034591b0c8729822b0e8bd07c3e9206b6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026801699995306480",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/bfb5e2c034591b0c8729822b0e8bd07c3e9206b6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bfb5e2c034591b0c8729822b0e8bd07c3e9206b6",
"gitiles_ref:refs/heads/master"
]
}
[17:21:25.641] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607534484507708",
"id": "8861371636331991136",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bfb5e2c034591b0c8729822b0e8bd07c3e9206b6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607534485382447",
"tags": [
"build_address:luci.chromium.ci/win-asan/16122",
"builder:win-asan",
"buildset:commit/git/bfb5e2c034591b0c8729822b0e8bd07c3e9206b6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bfb5e2c034591b0c8729822b0e8bd07c3e9206b6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026801699995306480",
"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/8861371636331991136/+/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": "1607534485382594",
"url": "https://ci.chromium.org/b/8861371636331991136",
"utcnow_ts": "1607534485622280"
}
}
[17:21:25.641] Task URL: https://ci.chromium.org/b/8861371636331991136
[17:21:25.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:2:0) after 1m0s
[17:21:29.839] Received PubSub notification, asking Buildbucket for the build status
[17:21:29.947] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:25.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:2:0)
[17:22:25.705] Timer tick, asking Buildbucket for the build status
[17:22:26.103] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:26.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:4:0) after 1m0s
[17:23:26.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:4:0)
[17:23:26.127] Timer tick, asking Buildbucket for the build status
[17:23:26.269] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:26.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:5:0) after 1m0s
[17:24:26.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:5:0)
[17:24:26.294] Timer tick, asking Buildbucket for the build status
[17:24:26.597] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:26.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:6:0) after 1m0s
[17:25:26.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:6:0)
[17:25:26.715] Timer tick, asking Buildbucket for the build status
[17:25:26.865] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:26.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:7:0) after 1m0s
[17:26:26.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:7:0)
[17:26:26.896] Timer tick, asking Buildbucket for the build status
[17:26:26.988] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:26.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:8:0) after 1m0s
[17:27:27.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:8:0)
[17:27:27.019] Timer tick, asking Buildbucket for the build status
[17:27:27.161] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:27.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:9:0) after 1m0s
[17:28:27.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:9:0)
[17:28:27.243] Timer tick, asking Buildbucket for the build status
[17:28:27.300] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:27.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:10:0) after 1m0s
[17:29:27.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:10:0)
[17:29:27.426] Timer tick, asking Buildbucket for the build status
[17:29:27.537] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:27.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:11:0) after 1m0s
[17:30:27.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:11:0)
[17:30:27.794] Timer tick, asking Buildbucket for the build status
[17:30:30.762] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:30.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:12:0) after 1m0s
[17:31:31.295] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:12:0)
[17:31:31.295] Timer tick, asking Buildbucket for the build status
[17:31:31.413] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:31.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:13:0) after 1m0s
[17:32:31.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:13:0)
[17:32:31.552] Timer tick, asking Buildbucket for the build status
[17:32:32.033] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:32.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:14:0) after 1m0s
[17:33:32.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:14:0)
[17:33:32.093] Timer tick, asking Buildbucket for the build status
[17:33:32.394] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:32.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:15:0) after 1m0s
[17:34:32.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:15:0)
[17:34:32.457] Timer tick, asking Buildbucket for the build status
[17:34:32.563] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:32.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:16:0) after 1m0s
[17:35:32.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:16:0)
[17:35:32.643] Timer tick, asking Buildbucket for the build status
[17:35:32.895] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:32.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:17:0) after 1m0s
[17:36:32.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:17:0)
[17:36:32.917] Timer tick, asking Buildbucket for the build status
[17:36:33.011] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:33.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:18:0) after 1m0s
[17:37:33.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:18:0)
[17:37:33.073] Timer tick, asking Buildbucket for the build status
[17:37:33.264] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:33.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:19:0) after 1m0s
[17:38:33.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:19:0)
[17:38:33.353] Timer tick, asking Buildbucket for the build status
[17:38:33.494] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:33.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:20:0) after 1m0s
[17:39:33.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:20:0)
[17:39:33.569] Timer tick, asking Buildbucket for the build status
[17:39:34.002] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:34.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:21:0) after 1m0s
[17:40:34.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:21:0)
[17:40:34.095] Timer tick, asking Buildbucket for the build status
[17:40:34.285] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:34.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:22:0) after 1m0s
[17:41:34.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:22:0)
[17:41:34.351] Timer tick, asking Buildbucket for the build status
[17:41:34.880] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:34.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:23:0) after 1m0s
[17:42:34.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:23:0)
[17:42:34.913] Timer tick, asking Buildbucket for the build status
[17:42:35.255] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:35.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:24:0) after 1m0s
[17:43:35.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:24:0)
[17:43:35.292] Timer tick, asking Buildbucket for the build status
[17:43:35.409] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:35.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:25:0) after 1m0s
[17:44:35.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:25:0)
[17:44:35.465] Timer tick, asking Buildbucket for the build status
[17:44:35.591] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:35.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:26:0) after 1m0s
[17:45:35.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:26:0)
[17:45:35.618] Timer tick, asking Buildbucket for the build status
[17:45:37.289] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:37.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:27:0) after 1m0s
[17:46:37.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:27:0)
[17:46:37.351] Timer tick, asking Buildbucket for the build status
[17:46:37.650] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:37.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:28:0) after 1m0s
[17:47:37.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:28:0)
[17:47:37.681] Timer tick, asking Buildbucket for the build status
[17:47:37.815] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:37.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:29:0) after 1m0s
[17:48:37.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:29:0)
[17:48:37.860] Timer tick, asking Buildbucket for the build status
[17:48:38.009] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:38.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:30:0) after 1m0s
[17:49:38.083] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:30:0)
[17:49:38.083] Timer tick, asking Buildbucket for the build status
[17:49:38.206] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:38.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:31:0) after 1m0s
[17:50:38.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:31:0)
[17:50:38.236] Timer tick, asking Buildbucket for the build status
[17:50:38.385] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:38.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:32:0) after 1m0s
[17:51:38.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:32:0)
[17:51:38.422] Timer tick, asking Buildbucket for the build status
[17:51:39.105] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:39.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:33:0) after 1m0s
[17:52:39.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:33:0)
[17:52:39.142] Timer tick, asking Buildbucket for the build status
[17:52:39.271] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:39.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:34:0) after 1m0s
[17:53:39.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:34:0)
[17:53:39.282] Timer tick, asking Buildbucket for the build status
[17:53:39.356] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:39.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:35:0) after 1m0s
[17:54:39.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:35:0)
[17:54:39.426] Timer tick, asking Buildbucket for the build status
[17:54:39.514] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:39.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:36:0) after 1m0s
[17:55:39.596] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:36:0)
[17:55:39.596] Timer tick, asking Buildbucket for the build status
[17:55:39.759] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:39.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:37:0) after 1m0s
[17:56:39.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:37:0)
[17:56:39.787] Timer tick, asking Buildbucket for the build status
[17:56:39.930] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:39.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:38:0) after 1m0s
[17:57:40.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:38:0)
[17:57:40.043] Timer tick, asking Buildbucket for the build status
[17:57:40.162] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:40.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:39:0) after 1m0s
[17:58:40.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:39:0)
[17:58:40.210] Timer tick, asking Buildbucket for the build status
[17:58:40.375] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:40.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:40:0) after 1m0s
[17:59:40.415] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:40:0)
[17:59:40.415] Timer tick, asking Buildbucket for the build status
[17:59:40.487] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:40.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:41:0) after 1m0s
[18:00:40.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:41:0)
[18:00:40.502] Timer tick, asking Buildbucket for the build status
[18:00:40.593] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:40.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:42:0) after 1m0s
[18:01:40.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:42:0)
[18:01:40.665] Timer tick, asking Buildbucket for the build status
[18:01:40.846] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:40.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:43:0) after 1m0s
[18:02:41.030] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:43:0)
[18:02:41.030] Timer tick, asking Buildbucket for the build status
[18:02:41.512] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:41.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:44:0) after 1m0s
[18:03:41.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:44:0)
[18:03:41.624] Timer tick, asking Buildbucket for the build status
[18:03:41.731] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:41.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:45:0) after 1m0s
[18:04:41.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:45:0)
[18:04:41.860] Timer tick, asking Buildbucket for the build status
[18:04:41.976] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:41.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:46:0) after 1m0s
[18:05:42.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:46:0)
[18:05:42.037] Timer tick, asking Buildbucket for the build status
[18:05:42.158] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:42.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:47:0) after 1m0s
[18:06:42.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:47:0)
[18:06:42.190] Timer tick, asking Buildbucket for the build status
[18:06:42.326] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:42.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:48:0) after 1m0s
[18:07:42.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:48:0)
[18:07:42.391] Timer tick, asking Buildbucket for the build status
[18:07:42.790] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:42.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:49:0) after 1m0s
[18:08:42.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:49:0)
[18:08:42.791] Timer tick, asking Buildbucket for the build status
[18:08:45.280] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:45.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:50:0) after 1m0s
[18:09:45.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:50:0)
[18:09:45.321] Timer tick, asking Buildbucket for the build status
[18:09:45.528] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:45.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:51:0) after 1m0s
[18:10:45.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:51:0)
[18:10:45.592] Timer tick, asking Buildbucket for the build status
[18:10:45.816] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:45.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:52:0) after 1m0s
[18:11:45.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:52:0)
[18:11:45.885] Timer tick, asking Buildbucket for the build status
[18:11:46.096] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:46.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:53:0) after 1m0s
[18:12:46.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:53:0)
[18:12:46.140] Timer tick, asking Buildbucket for the build status
[18:12:46.207] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:46.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:54:0) after 1m0s
[18:13:46.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:54:0)
[18:13:46.259] Timer tick, asking Buildbucket for the build status
[18:13:46.359] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:46.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:55:0) after 1m0s
[18:14:46.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:55:0)
[18:14:46.385] Timer tick, asking Buildbucket for the build status
[18:14:46.456] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:46.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:56:0) after 1m0s
[18:15:46.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:56:0)
[18:15:46.526] Timer tick, asking Buildbucket for the build status
[18:15:46.641] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:46.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:57:0) after 1m0s
[18:16:46.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:57:0)
[18:16:46.693] Timer tick, asking Buildbucket for the build status
[18:16:46.838] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:46.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:58:0) after 1m0s
[18:17:46.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:58:0)
[18:17:46.872] Timer tick, asking Buildbucket for the build status
[18:17:47.010] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:47.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:59:0) after 1m0s
[18:18:47.047] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:59:0)
[18:18:47.047] Timer tick, asking Buildbucket for the build status
[18:18:47.461] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:47.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:60:0) after 1m0s
[18:19:47.488] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:60:0)
[18:19:47.488] Timer tick, asking Buildbucket for the build status
[18:19:47.599] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:47.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:61:0) after 1m0s
[18:20:47.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:61:0)
[18:20:47.629] Timer tick, asking Buildbucket for the build status
[18:20:47.713] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:47.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:62:0) after 1m0s
[18:21:47.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:62:0)
[18:21:47.743] Timer tick, asking Buildbucket for the build status
[18:21:47.881] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:47.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:63:0) after 1m0s
[18:22:47.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:63:0)
[18:22:47.911] Timer tick, asking Buildbucket for the build status
[18:22:48.044] Build 8861371636331991136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:48.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026801699995306480:64:0) after 1m0s
[18:23:41.324] Received PubSub notification, asking Buildbucket for the build status
[18:23:41.533] Build 8861371636331991136: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:23:41.533] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607538220103895",
"created_by": "project:chromium",
"created_ts": "1607534484507708",
"id": "8861371636331991136",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bfb5e2c034591b0c8729822b0e8bd07c3e9206b6\"}}",
"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}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$build/goma.jobs\", \"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"bfb5e2c034591b0c8729822b0e8bd07c3e9206b6\", \"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-6-3vmi\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16122, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"f0f79e08366208f15f05c909bc7b81a1198e261f\", \"got_dawn_revision\": \"99d6c14a3b34a370760caea4d408e0d6c194ed56\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"bfb5e2c034591b0c8729822b0e8bd07c3e9206b6\", \"got_revision_cp\": \"refs/heads/master@{#835231}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"84b9bb6621c7eae8da034d31aceab780d8b1abdc\", \"got_v8_revision\": \"7e546be6b2f61eb429d51abb78e973f7f623f62f\", \"got_v8_revision_cp\": \"refs/heads/8.9.126@{#1}\", \"got_webrtc_revision\": \"d7808f1c464a07c8f1e2f97ec7ee92fda998d590\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32804}\", \"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\": \"bfb5e2c034591b0c8729822b0e8bd07c3e9206b6\", \"swarm_hashes_refs/heads/master(at){#835231}_without_patch\": {\"absl_hardening_tests\": \"d9913e9c76c4230caab2077685550eb11569d4b2\", \"accessibility_unittests\": \"7ffea504bb43cdd9bd9f838b06a43f049a049152\", \"angle_unittests\": \"290da1679a554ad89afab9922f8e87533a213110\", \"app_shell_unittests\": \"d14a235e7c504390993b0dfd92c2a6426fc09149\", \"aura_unittests\": \"8be731ea1d7a1b974f3bb5e990ed18a9d4e077e0\", \"base_unittests\": \"638f0e605ca76208d3e57767d35302a564c64bbb\", \"base_util_unittests\": \"bad51fb18d956a9b009c9061e7dfe47eda8b0a06\", \"blink_common_unittests\": \"653a7a9d78a7f742c0bce6c07e7584d907d486b6\", \"blink_fuzzer_unittests\": \"3856b74b8a61a65970ccfe826ff62ff573db111e\", \"blink_heap_unittests\": \"b6a96868e3f7e8d442d70e0636a5eff8a8931de2\", \"blink_platform_unittests\": \"c51cf837f7aac00158a5553d271ea1b1c0cdd6d1\", \"blink_unittests\": \"1eefea47e9f54fd81d8dc615bec1a765284da7a1\", \"boringssl_crypto_tests\": \"384c18d378dbc623834294c84b9c29971d3b2a12\", \"boringssl_ssl_tests\": \"ffe8b4d8608410dafe99d88f47c48ab8c8a3177e\", \"browser_switcher_bho_unittests\": \"baeb6e9067722002b0d31d0fdc063bd86f2c0f55\", \"browser_tests\": \"4807edcafe846d563bd3dbf4ee5ec0a6b8d3e3ef\", \"capture_unittests\": \"a13d85fc1e46401640664c870d798914498344aa\", \"cast_unittests\": \"af8fb6ce809ba80e84190f410d2d38b0b0fbd1c8\", \"cc_unittests\": \"0fc2dad5fe2da40c7166f6359cc56b142f0b4b4b\", \"chrome_app_unittests\": \"3bc05e6c965489c92d666711279e0e97654f508f\", \"chrome_cleaner_unittests\": \"0fecf133e1cfa0c895331f50aca47dd5a516b24d\", \"chrome_elf_unittests\": \"467b09c36301e69727f3f80f27238b20d119af29\", \"chromedriver_unittests\": \"ae64996fe2dde8d4e7f688facc52616150b51e61\", \"components_browsertests\": \"6e141354e28b19617b3bf41076760978ea542271\", \"components_unittests\": \"dba79be8832dca903d315eb2899a09c49625ebc6\", \"compositor_unittests\": \"d4551cf48af3bc7756ef5b3dcc65e23ee63edac4\", \"content_browsertests\": \"a9cee5d7c2aa530df135d3fcff60d48a69c464db\", \"content_unittests\": \"e142fc8ed52bdfd0919604f6eaa69f5e4761328a\", \"courgette_unittests\": \"8338b8ac583441ef8b515fa584c7c04b7573d1ff\", \"crashpad_tests\": \"3ea752e85d9f4831a5066b201c8657c88bbdb97b\", \"cronet_tests\": \"fea8b486f05bb2028336c1b8855038aec4b258f8\", \"cronet_unittests\": \"0272f3cb26b4ee9a37d6ec46bb7241091399ebd6\", \"crypto_unittests\": \"30e318d1f5cfce3b4b547ae6e135fb085feacc54\", \"delayloads_unittests\": \"2adf4be2d8ffbc9988f371ed02f80bb6d4702ebf\", \"device_unittests\": \"8c9de36d38b1cdbd446d784cdae7600e2cb5fd67\", \"display_unittests\": \"460d757aadd9e551fb3e3a2d9a13a1341e13f184\", \"elevation_service_unittests\": \"1c4a3d2db2cd0a4d76a771af0ff03770f9de8402\", \"events_unittests\": \"9d9b085772459109b2151e2b27d6522a57602b6a\", \"extensions_browsertests\": \"de3753330ae80f9912e498085d0a5fd8a33a66af\", \"extensions_unittests\": \"e4ba137b244311064d8d1beb344bb8918939195f\", \"filesystem_service_unittests\": \"97b4b2f5f7d6259f6816f31a6f7220dc8b843ceb\", \"gcm_unit_tests\": \"7d5bbd42877158477235ffa6204d2121dca61701\", \"gcp_unittests\": \"301ce1214a6322a969d45df4d61520e42bfcda3f\", \"gfx_unittests\": \"c316eb628bd5c2d3c0213741a146d1dac19cd545\", \"gin_unittests\": \"4930ca4e07bc802dd001ee2a70cfbe39fe425f46\", \"google_apis_unittests\": \"0c1051d6bbc04925cd7d6ecced74f55395e9749c\", \"gpu_unittests\": \"191d02f7a5897dd7eae891d8a717ea97e55a4436\", \"gwp_asan_unittests\": \"e4bc2bf3487de1a013c9052aad0e1c48a2587811\", \"headless_browsertests\": \"0ef5c2fdb333919758ce2ef6bfa773e053279062\", \"headless_unittests\": \"a47c0d14fdb3fffdb086de78cccb5bf7c8e4795b\", \"install_static_unittests\": \"d9fdb7f8be2a81bc5e9854b8278c1b82e80d1385\", \"installer_util_unittests\": \"176dec17809cc58c4f30cd9c454ffad179956c44\", \"interactive_ui_tests\": \"ccbbc3b65378a1f7152a4548e5d41df43158a56b\", \"ipc_tests\": \"91cf0a9b27f0a5b1ced5c9ca8538af5a9fb9c59b\", \"jingle_unittests\": \"5a0c173e9e2cee93ed99f92c47785c1322b94f45\", \"latency_unittests\": \"4ef940c1cae48425e072913d23dcf5aa81897e13\", \"libjingle_xmpp_unittests\": \"c260becb0054de8204a748030f665e9bd3736713\", \"liburlpattern_unittests\": \"38a3a3c0a15f96d3be0cffef4a9d522e557b3276\", \"media_blink_unittests\": \"cb9eb3bbdd2832e0dbe79e1a2245e2c9ae7d4e49\", \"media_unittests\": \"744475f5968c1afb112aab5d8c0ccdea9b6fa45b\", \"message_center_unittests\": \"3cd4b0b673b0b085b873814f803ec4a0aa5bad2e\", \"midi_unittests\": \"bfba09aa2ea90c28ad4369394e63d8fcdc41b73a\", \"mojo_core_unittests\": \"f9fe5a863f16279fe1d44966dbbff76943c2fe65\", \"mojo_unittests\": \"5a8354e460c0ad9e01c7747dd6df7f84990bc101\", \"nacl_loader_unittests\": \"98bb8ea1f96b60b51488c52c4df90f08a8735053\", \"native_theme_unittests\": \"e7b88036001499ecce0efc33a7f29758d122391d\", \"net_unittests\": \"3db72a701e772926cb9e78b01c37de974f249ec9\", \"notification_helper_unittests\": \"30ee636615651def05c025042a9dca3a8c0e7938\", \"pdf_unittests\": \"48fdce08b0736c69a271af4b9a6c9c89162d0d79\", \"perfetto_unittests\": \"16efd5e9019fe6723e0128f7981852b7e5cc5ac8\", \"ppapi_unittests\": \"2379b8ce9078e6774a5b41e048b1a7e078442fa4\", \"printing_unittests\": \"3651f2c3624a9c4cb068f590c5b3ee7887c407e9\", \"remoting_unittests\": \"6c607cc7bb91ba636a67ff6984a6a68e77d1345b\", \"sbox_integration_tests\": \"5460a5d7ebc0e4e933bbc3a47bb2f0dd32b24690\", \"sbox_unittests\": \"f98e0018eaa720f715db7e587a838155e611917d\", \"sbox_validation_tests\": \"602204d05af5c8629995d8e5096f1eba1f172c4c\", \"service_manager_unittests\": \"193f4f15425f12cd3cf17d5473bb68a3c8008a9a\", \"services_unittests\": \"d4f2ee1eb65e1c78f72c9780beb21389ad154f24\", \"setup_unittests\": \"68f362becd809600df417d2977d01c765dbfa7a2\", \"shell_dialogs_unittests\": \"0846c6ffe70284253f59df75f75704c8baeccb7d\", \"skia_unittests\": \"3d0f98deb7b7853d4dda75f7b1094aa736485274\", \"snapshot_unittests\": \"2cfa3bd266bbf28b1b69b5e0d129697d27f25b7c\", \"sql_unittests\": \"038027f9a71160cde494238f67354131328cfeaa\", \"storage_unittests\": \"edeba295b7d16441be65a59cfa85779965d99c46\", \"sync_integration_tests\": \"eff01c5483cf8ee96f5f7b4ffd3549285598e9ab\", \"traffic_annotation_auditor_unittests\": \"1b6effc7c85e1f4d35ee2ad5600708e4697a709b\", \"ui_base_unittests\": \"4c6c7dd36fb318e2f2b31089e53e19a6caf81e39\", \"ui_touch_selection_unittests\": \"1fedcedd580b59b1797f6d92f27a53f335ba1548\", \"unit_tests\": \"f0464be9c3846bffb2f5a78c0026d358f8309843\", \"updater_tests\": \"36a17966b71a301f4fe6c97dcb4088518dd1b282\", \"url_unittests\": \"99b2cf8aece7015cf9e6587a109203ee6d9775d2\", \"views_unittests\": \"6b851a252ac0aaffeb96122f63a4f35b32abde50\", \"viz_unittests\": \"932822b7f86a395044ba7d943a6685161957bdc6\", \"vr_common_unittests\": \"e38088a92766b6bb49a85ca3a630155104d78bd2\", \"vr_pixeltests\": \"5486809cd88c6916cc14395a4d3b62d234edc819\", \"weblayer_browsertests\": \"09e045ee51f69c369cbdc2eba6f2729239ec556b\", \"weblayer_unittests\": \"a7a70f756f9e646376850f72870d48c4d4aa0944\", \"wm_unittests\": \"6363d72c508dcf0ce9eb41ceec200548714510b5\", \"wtf_unittests\": \"5c0f3be05373d1c4ade59af18c0509c731dca872\", \"zlib_unittests\": \"56ac4ee0ad26c826cedea5068c6b8a26d7b94653\", \"zucchini_unittests\": \"97721362739273d6545236f6c633b75aba15026c\"}}, \"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-6-3vmi\"], \"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\": [\"5640-d0eb544\"], \"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": "1607534489192913",
"status": "COMPLETED",
"status_changed_ts": "1607538220758722",
"tags": [
"build_address:luci.chromium.ci/win-asan/16122",
"builder:win-asan",
"buildset:commit/git/bfb5e2c034591b0c8729822b0e8bd07c3e9206b6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bfb5e2c034591b0c8729822b0e8bd07c3e9206b6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026801699995306480",
"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/8861371636331991136/+/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:5061572965da4d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1607538220763287",
"url": "https://ci.chromium.org/b/8861371636331991136",
"utcnow_ts": "1607538221523459"
}
}
[18:23:41.533] Invocation finished in 1h2m19.903376901s with status SUCCEEDED