[21:55:24.424] New invocation is queued and will start shortly
[21:55:25.497] Starting the invocation (attempt 1)
[21:55:25.527] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:55:25.528] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:55:25.528] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9046081612336065152",
"parameters_json": "{\"builder_name\":\"CrWinAsan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"115d2497e86528b9739dbdf90907794c5357572c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:CrWinAsan",
"scheduler_invocation_id:9046081612336065152",
"scheduler_job_id:chromium/CrWinAsan",
"user_agent:luci-scheduler",
"buildset:commit/git/115d2497e86528b9739dbdf90907794c5357572c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/115d2497e86528b9739dbdf90907794c5357572c",
"gitiles_ref:refs/heads/master"
]
}
[21:55:26.636] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1589147725621510",
"id": "8880651550417135488",
"parameters_json": "{\"builder_name\": \"CrWinAsan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"115d2497e86528b9739dbdf90907794c5357572c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1589147726254339",
"tags": [
"build_address:luci.chromium.ci/CrWinAsan/5263",
"builder:CrWinAsan",
"buildset:commit/git/115d2497e86528b9739dbdf90907794c5357572c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/115d2497e86528b9739dbdf90907794c5357572c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9046081612336065152",
"scheduler_job_id:chromium/CrWinAsan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8880651550417135488/+/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": "1589147726254580",
"url": "https://ci.chromium.org/b/8880651550417135488",
"utcnow_ts": "1589147726581593"
}
}
[21:55:26.636] Task URL: https://ci.chromium.org/b/8880651550417135488
[21:55:26.636] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:2:0) after 1m0s
[21:56:26.698] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:2:0)
[21:56:26.698] Timer tick, asking Buildbucket for the build status
[21:56:28.697] Build 8880651550417135488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:28.697] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:3:0) after 1m0s
[21:57:28.734] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:3:0)
[21:57:28.734] Timer tick, asking Buildbucket for the build status
[21:57:29.014] Build 8880651550417135488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:29.014] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:4:0) after 1m0s
[21:58:29.035] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:4:0)
[21:58:29.035] Timer tick, asking Buildbucket for the build status
[21:58:29.171] Build 8880651550417135488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:29.171] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:5:0) after 1m0s
[21:59:10.657] Received PubSub notification, asking Buildbucket for the build status
[21:59:10.783] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:29.302] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:5:0)
[21:59:29.302] Timer tick, asking Buildbucket for the build status
[21:59:29.727] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:29.727] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:7:0) after 1m0s
[22:00:29.780] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:7:0)
[22:00:29.780] Timer tick, asking Buildbucket for the build status
[22:00:29.866] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:29.867] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:8:0) after 1m0s
[22:01:29.842] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:8:0)
[22:01:29.842] Timer tick, asking Buildbucket for the build status
[22:01:29.988] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:29.988] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:9:0) after 1m0s
[22:02:30.054] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:9:0)
[22:02:30.054] Timer tick, asking Buildbucket for the build status
[22:02:30.186] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:30.187] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:10:0) after 1m0s
[22:03:30.476] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:10:0)
[22:03:30.476] Timer tick, asking Buildbucket for the build status
[22:03:30.651] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:30.651] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:11:0) after 1m0s
[22:04:30.718] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:11:0)
[22:04:30.718] Timer tick, asking Buildbucket for the build status
[22:04:30.840] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:30.840] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:12:0) after 1m0s
[22:05:30.895] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:12:0)
[22:05:30.895] Timer tick, asking Buildbucket for the build status
[22:05:31.072] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:31.072] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:13:0) after 1m0s
[22:06:31.093] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:13:0)
[22:06:31.093] Timer tick, asking Buildbucket for the build status
[22:06:31.431] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:31.431] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:14:0) after 1m0s
[22:07:31.450] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:14:0)
[22:07:31.450] Timer tick, asking Buildbucket for the build status
[22:07:31.543] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:31.543] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:15:0) after 1m0s
[22:08:31.685] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:15:0)
[22:08:31.685] Timer tick, asking Buildbucket for the build status
[22:08:31.815] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:31.816] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:16:0) after 1m0s
[22:09:32.061] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:16:0)
[22:09:32.061] Timer tick, asking Buildbucket for the build status
[22:09:32.228] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:32.228] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:17:0) after 1m0s
[22:10:32.349] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:17:0)
[22:10:32.349] Timer tick, asking Buildbucket for the build status
[22:10:32.528] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:32.528] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:18:0) after 1m0s
[22:11:32.575] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:18:0)
[22:11:32.575] Timer tick, asking Buildbucket for the build status
[22:11:32.805] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:32.805] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:19:0) after 1m0s
[22:12:32.843] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:19:0)
[22:12:32.843] Timer tick, asking Buildbucket for the build status
[22:12:33.338] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:33.338] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:20:0) after 1m0s
[22:13:33.347] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:20:0)
[22:13:33.347] Timer tick, asking Buildbucket for the build status
[22:13:33.590] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:33.590] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:21:0) after 1m0s
[22:14:33.714] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:21:0)
[22:14:33.714] Timer tick, asking Buildbucket for the build status
[22:14:33.874] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:33.874] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:22:0) after 1m0s
[22:15:33.983] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:22:0)
[22:15:33.983] Timer tick, asking Buildbucket for the build status
[22:15:34.351] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:34.351] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:23:0) after 1m0s
[22:16:34.375] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:23:0)
[22:16:34.375] Timer tick, asking Buildbucket for the build status
[22:16:34.545] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:34.545] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:24:0) after 1m0s
[22:17:34.735] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:24:0)
[22:17:34.735] Timer tick, asking Buildbucket for the build status
[22:17:34.855] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:34.855] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:25:0) after 1m0s
[22:18:35.189] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:25:0)
[22:18:35.189] Timer tick, asking Buildbucket for the build status
[22:18:35.559] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:35.559] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:26:0) after 1m0s
[22:19:35.686] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:26:0)
[22:19:35.686] Timer tick, asking Buildbucket for the build status
[22:19:36.231] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:36.231] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:27:0) after 1m0s
[22:20:36.389] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:27:0)
[22:20:36.389] Timer tick, asking Buildbucket for the build status
[22:20:36.537] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:36.537] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:28:0) after 1m0s
[22:21:36.739] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:28:0)
[22:21:36.739] Timer tick, asking Buildbucket for the build status
[22:21:36.911] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:36.911] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:29:0) after 1m0s
[22:22:37.142] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:29:0)
[22:22:37.142] Timer tick, asking Buildbucket for the build status
[22:22:37.273] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:37.273] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:30:0) after 1m0s
[22:23:37.297] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:30:0)
[22:23:37.297] Timer tick, asking Buildbucket for the build status
[22:23:37.489] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:37.489] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:31:0) after 1m0s
[22:24:37.555] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:31:0)
[22:24:37.555] Timer tick, asking Buildbucket for the build status
[22:24:38.080] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:38.080] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:32:0) after 1m0s
[22:25:38.227] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:32:0)
[22:25:38.227] Timer tick, asking Buildbucket for the build status
[22:25:38.518] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:38.518] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:33:0) after 1m0s
[22:26:38.664] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:33:0)
[22:26:38.664] Timer tick, asking Buildbucket for the build status
[22:26:38.863] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:38.863] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:34:0) after 1m0s
[22:27:38.970] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:34:0)
[22:27:38.970] Timer tick, asking Buildbucket for the build status
[22:27:39.175] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:39.175] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:35:0) after 1m0s
[22:28:39.295] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:35:0)
[22:28:39.295] Timer tick, asking Buildbucket for the build status
[22:28:41.643] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:41.643] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:36:0) after 1m0s
[22:29:41.666] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:36:0)
[22:29:41.666] Timer tick, asking Buildbucket for the build status
[22:29:41.924] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:41.924] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:37:0) after 1m0s
[22:30:41.943] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:37:0)
[22:30:41.943] Timer tick, asking Buildbucket for the build status
[22:30:42.121] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:42.121] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:38:0) after 1m0s
[22:31:42.194] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:38:0)
[22:31:42.194] Timer tick, asking Buildbucket for the build status
[22:31:44.161] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:44.162] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:39:0) after 1m0s
[22:32:44.184] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:39:0)
[22:32:44.184] Timer tick, asking Buildbucket for the build status
[22:32:44.660] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:44.660] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:40:0) after 1m0s
[22:33:44.686] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:40:0)
[22:33:44.686] Timer tick, asking Buildbucket for the build status
[22:33:44.832] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:44.832] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:41:0) after 1m0s
[22:34:44.879] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:41:0)
[22:34:44.879] Timer tick, asking Buildbucket for the build status
[22:34:45.004] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:45.004] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:42:0) after 1m0s
[22:35:45.032] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:42:0)
[22:35:45.032] Timer tick, asking Buildbucket for the build status
[22:35:45.446] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:45.446] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:43:0) after 1m0s
[22:36:45.593] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:43:0)
[22:36:45.593] Timer tick, asking Buildbucket for the build status
[22:36:45.936] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:45.936] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:44:0) after 1m0s
[22:37:45.961] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:44:0)
[22:37:45.961] Timer tick, asking Buildbucket for the build status
[22:37:46.555] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:46.555] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:45:0) after 1m0s
[22:38:46.596] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:45:0)
[22:38:46.596] Timer tick, asking Buildbucket for the build status
[22:38:46.761] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:46.761] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:46:0) after 1m0s
[22:39:46.733] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:46:0)
[22:39:46.733] Timer tick, asking Buildbucket for the build status
[22:39:47.202] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:47.202] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:47:0) after 1m0s
[22:40:47.333] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:47:0)
[22:40:47.333] Timer tick, asking Buildbucket for the build status
[22:40:47.452] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:47.452] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:48:0) after 1m0s
[22:41:47.525] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:48:0)
[22:41:47.525] Timer tick, asking Buildbucket for the build status
[22:41:47.646] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:47.646] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:49:0) after 1m0s
[22:42:47.678] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:49:0)
[22:42:47.678] Timer tick, asking Buildbucket for the build status
[22:42:47.778] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:47.778] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:50:0) after 1m0s
[22:43:47.772] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:50:0)
[22:43:47.772] Timer tick, asking Buildbucket for the build status
[22:43:47.899] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:47.899] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:51:0) after 1m0s
[22:44:47.918] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:51:0)
[22:44:47.918] Timer tick, asking Buildbucket for the build status
[22:44:48.040] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:48.040] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:52:0) after 1m0s
[22:45:48.088] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:52:0)
[22:45:48.088] Timer tick, asking Buildbucket for the build status
[22:45:48.251] Build 8880651550417135488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:48.251] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:9046081612336065152:53:0) after 1m0s
[22:46:29.091] Received PubSub notification, asking Buildbucket for the build status
[22:46:29.213] Build 8880651550417135488: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[22:46:29.213] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1589150788298807",
"created_by": "project:chromium",
"created_ts": "1589147725621510",
"failure_reason": "BUILD_FAILURE",
"id": "8880651550417135488",
"parameters_json": "{\"builder_name\": \"CrWinAsan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"115d2497e86528b9739dbdf90907794c5357572c\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[2076/57186] CXX obj/skia/skia_core_and_effects/SkOpts.obj\\nFAILED: obj/skia/skia_core_and_effects/SkOpts.obj\\n..\\\\..\\\\third_party\\\\llvm-build\\\\Release+Asserts\\\\bin\\\\clang-cl.exe /nologo /showIncludes:user -imsvc..\\\\.....(too long)\\nAssertion failed: VT.getSizeInBits() == Operand.getValueSizeInBits() \u0026\u0026 \\\"Cannot BITCAST between types of different sizes!\\\", file C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\\\\builder\\\\src\\\\third_party\\\\llvm\\\\llvm\\\\lib\\\\CodeGen\\\\SelectionDAG\\\\SelectionDAG.cpp, line 4750\\nPLEASE submit a bug report to https://crbug.com and run tools/clang/scripts/process_crashreports.py (only works inside Google) which will upload a report and include the crash backtrace, preprocessed source, and associated run script.\\nStack dump:\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}, \"properties\": {\"$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-0-79ac\", \"branch\": \"refs/heads/master\", \"buildername\": \"CrWinAsan\", \"buildnumber\": 5263, \"got_angle_revision\": \"9f32304208deb75106cd641e8cb09af93f8df860\", \"got_clang_revision\": \"n353890-6d23f298-0\", \"got_dawn_revision\": \"562255a94346d1121c433abfa2abe39aee135804\", \"got_nacl_revision\": \"98e6f0f2dc32172835122736bbc317335d8d20e4\", \"got_revision\": \"115d2497e86528b9739dbdf90907794c5357572c\", \"got_revision_cp\": \"refs/heads/master@{#767154}\", \"got_swarming_client_revision\": \"160b445a44e0daacf6f3f8570ca2707ec451f374\", \"got_swiftshader_revision\": \"7b87548e5a639efd25edb2939198077c033dfb6a\", \"got_v8_revision\": \"797cb87973ec7abb6376738a0455e7d943932c27\", \"got_v8_revision_cp\": \"refs/heads/8.4.331@{#1}\", \"got_webrtc_revision\": \"d7e08c8cf8445f6c2ecf8bf485d48ab73fb3aa96\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31199}\", \"mastername\": \"chromium.clang\", \"path_config\": \"generic\", \"perf_dashboard_machine_group\": \"ChromiumClang\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"115d2497e86528b9739dbdf90907794c5357572c\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_f77c1c08af9d0a4522ad60c9181aa925a8ae87fd7ec8443f284fb40f3b6bc27b_v2\", \"git\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-0-79ac\"], \"image\": [\"chrome-win10-1703-20042500-5e6af220c3f\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5050-4c62a25\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[2076/57186] CXX obj/skia/skia_core_and_effects/SkOpts.obj\\nFAILED: obj/skia/skia_core_and_effects/SkOpts.obj\\n..\\\\..\\\\third_party\\\\llvm-build\\\\Release+Asserts\\\\bin\\\\clang-cl.exe /nologo /showIncludes:user -imsvc..\\\\.....(too long)\\nAssertion failed: VT.getSizeInBits() == Operand.getValueSizeInBits() \u0026\u0026 \\\"Cannot BITCAST between types of different sizes!\\\", file C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\\\\builder\\\\src\\\\third_party\\\\llvm\\\\llvm\\\\lib\\\\CodeGen\\\\SelectionDAG\\\\SelectionDAG.cpp, line 4750\\nPLEASE submit a bug report to https://crbug.com and run tools/clang/scripts/process_crashreports.py (only works inside Google) which will upload a report and include the crash backtrace, preprocessed source, and associated run script.\\nStack dump:\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1589147949447154",
"status": "COMPLETED",
"status_changed_ts": "1589147949902022",
"tags": [
"build_address:luci.chromium.ci/CrWinAsan/5263",
"builder:CrWinAsan",
"buildset:commit/git/115d2497e86528b9739dbdf90907794c5357572c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/115d2497e86528b9739dbdf90907794c5357572c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9046081612336065152",
"scheduler_job_id:chromium/CrWinAsan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8880651550417135488/+/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:4c19677d4c48cc10",
"user_agent:luci-scheduler"
],
"updated_ts": "1589150788356104",
"url": "https://ci.chromium.org/b/8880651550417135488",
"utcnow_ts": "1589150789188439"
}
}
[22:46:29.213] Invocation finished in 51m4.804875987s with status FAILED