[13:17:09.080] New invocation is queued and will start shortly
[13:17:10.322] Starting the invocation (attempt 1)
[13:17:10.412] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:17:10.412] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:17:10.412] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9043215115243246064",
"parameters_json": "{\"builder_name\":\"ToTWinASanLibfuzzer\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"538ce8d5848cb51c21637bc7afb56443a2ff7100\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTWinASanLibfuzzer",
"scheduler_invocation_id:9043215115243246064",
"scheduler_job_id:chromium/ToTWinASanLibfuzzer",
"user_agent:luci-scheduler",
"buildset:commit/git/538ce8d5848cb51c21637bc7afb56443a2ff7100",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/538ce8d5848cb51c21637bc7afb56443a2ff7100",
"gitiles_ref:refs/heads/master"
]
}
[13:17:11.760] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1591881430534176",
"id": "8877785053054195552",
"parameters_json": "{\"builder_name\": \"ToTWinASanLibfuzzer\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"538ce8d5848cb51c21637bc7afb56443a2ff7100\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1591881431322951",
"tags": [
"build_address:luci.chromium.ci/ToTWinASanLibfuzzer/5810",
"builder:ToTWinASanLibfuzzer",
"buildset:commit/git/538ce8d5848cb51c21637bc7afb56443a2ff7100",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/538ce8d5848cb51c21637bc7afb56443a2ff7100",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9043215115243246064",
"scheduler_job_id:chromium/ToTWinASanLibfuzzer",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8877785053054195552/+/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": "1591881431323121",
"url": "https://ci.chromium.org/b/8877785053054195552",
"utcnow_ts": "1591881431732755"
}
}
[13:17:11.760] Task URL: https://ci.chromium.org/b/8877785053054195552
[13:17:11.760] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:2:0) after 1m0s
[13:18:11.867] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:2:0)
[13:18:11.867] Timer tick, asking Buildbucket for the build status
[13:18:12.067] Build 8877785053054195552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:18:12.067] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:3:0) after 1m0s
[13:19:12.103] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:3:0)
[13:19:12.103] Timer tick, asking Buildbucket for the build status
[13:19:12.464] Build 8877785053054195552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:19:12.464] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:4:0) after 1m0s
[13:20:12.483] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:4:0)
[13:20:12.483] Timer tick, asking Buildbucket for the build status
[13:20:12.852] Build 8877785053054195552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:20:12.852] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:5:0) after 1m0s
[13:20:59.143] Received PubSub notification, asking Buildbucket for the build status
[13:20:59.642] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:12.869] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:5:0)
[13:21:12.869] Timer tick, asking Buildbucket for the build status
[13:21:12.987] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:12.987] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:7:0) after 1m0s
[13:22:13.064] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:7:0)
[13:22:13.064] Timer tick, asking Buildbucket for the build status
[13:22:13.253] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:13.253] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:8:0) after 1m0s
[13:23:13.288] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:8:0)
[13:23:13.288] Timer tick, asking Buildbucket for the build status
[13:23:13.509] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:13.509] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:9:0) after 1m0s
[13:24:13.564] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:9:0)
[13:24:13.564] Timer tick, asking Buildbucket for the build status
[13:24:13.664] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:13.664] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:10:0) after 1m0s
[13:25:13.661] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:10:0)
[13:25:13.661] Timer tick, asking Buildbucket for the build status
[13:25:14.126] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:14.126] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:11:0) after 1m0s
[13:26:14.179] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:11:0)
[13:26:14.179] Timer tick, asking Buildbucket for the build status
[13:26:14.316] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:14.316] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:12:0) after 1m0s
[13:27:14.375] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:12:0)
[13:27:14.375] Timer tick, asking Buildbucket for the build status
[13:27:14.589] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:14.589] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:13:0) after 1m0s
[13:28:14.645] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:13:0)
[13:28:14.645] Timer tick, asking Buildbucket for the build status
[13:28:14.810] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:14.810] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:14:0) after 1m0s
[13:29:14.863] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:14:0)
[13:29:14.863] Timer tick, asking Buildbucket for the build status
[13:29:15.113] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:15.113] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:15:0) after 1m0s
[13:30:15.249] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:15:0)
[13:30:15.249] Timer tick, asking Buildbucket for the build status
[13:30:15.454] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:15.454] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:16:0) after 1m0s
[13:31:15.513] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:16:0)
[13:31:15.513] Timer tick, asking Buildbucket for the build status
[13:31:15.700] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:15.700] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:17:0) after 1m0s
[13:32:15.827] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:17:0)
[13:32:15.827] Timer tick, asking Buildbucket for the build status
[13:32:15.999] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:15.999] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:18:0) after 1m0s
[13:33:16.043] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:18:0)
[13:33:16.043] Timer tick, asking Buildbucket for the build status
[13:33:16.360] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:16.360] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:19:0) after 1m0s
[13:34:16.395] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:19:0)
[13:34:16.395] Timer tick, asking Buildbucket for the build status
[13:34:16.838] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:16.838] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:20:0) after 1m0s
[13:35:16.891] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:20:0)
[13:35:16.891] Timer tick, asking Buildbucket for the build status
[13:35:17.051] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:17.051] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:21:0) after 1m0s
[13:36:17.123] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:21:0)
[13:36:17.123] Timer tick, asking Buildbucket for the build status
[13:36:17.300] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:17.300] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:22:0) after 1m0s
[13:37:17.554] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:22:0)
[13:37:17.554] Timer tick, asking Buildbucket for the build status
[13:37:17.703] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:17.703] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:23:0) after 1m0s
[13:38:17.830] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:23:0)
[13:38:17.830] Timer tick, asking Buildbucket for the build status
[13:38:18.818] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:18.818] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:24:0) after 1m0s
[13:39:18.866] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:24:0)
[13:39:18.866] Timer tick, asking Buildbucket for the build status
[13:39:19.007] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:19.007] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:25:0) after 1m0s
[13:40:19.092] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:25:0)
[13:40:19.092] Timer tick, asking Buildbucket for the build status
[13:40:19.241] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:19.241] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:26:0) after 1m0s
[13:41:19.293] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:26:0)
[13:41:19.293] Timer tick, asking Buildbucket for the build status
[13:41:19.521] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:19.521] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:27:0) after 1m0s
[13:42:19.573] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:27:0)
[13:42:19.573] Timer tick, asking Buildbucket for the build status
[13:42:19.781] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:19.781] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:28:0) after 1m0s
[13:43:19.796] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:28:0)
[13:43:19.796] Timer tick, asking Buildbucket for the build status
[13:43:19.920] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:19.920] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:29:0) after 1m0s
[13:44:20.025] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:29:0)
[13:44:20.025] Timer tick, asking Buildbucket for the build status
[13:44:20.154] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:20.154] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:30:0) after 1m0s
[13:45:20.896] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:30:0)
[13:45:20.896] Timer tick, asking Buildbucket for the build status
[13:45:21.288] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:21.288] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:31:0) after 1m0s
[13:46:21.455] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:31:0)
[13:46:21.455] Timer tick, asking Buildbucket for the build status
[13:46:21.788] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:21.788] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:32:0) after 1m0s
[13:47:21.861] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:32:0)
[13:47:21.861] Timer tick, asking Buildbucket for the build status
[13:47:22.025] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:22.025] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:33:0) after 1m0s
[13:48:22.046] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:33:0)
[13:48:22.046] Timer tick, asking Buildbucket for the build status
[13:48:22.333] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:22.333] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:34:0) after 1m0s
[13:49:22.368] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:34:0)
[13:49:22.369] Timer tick, asking Buildbucket for the build status
[13:49:22.540] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:22.540] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:35:0) after 1m0s
[13:50:22.617] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:35:0)
[13:50:22.617] Timer tick, asking Buildbucket for the build status
[13:50:23.166] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:23.166] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:36:0) after 1m0s
[13:51:23.210] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:36:0)
[13:51:23.210] Timer tick, asking Buildbucket for the build status
[13:51:23.571] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:23.571] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:37:0) after 1m0s
[13:52:23.592] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:37:0)
[13:52:23.592] Timer tick, asking Buildbucket for the build status
[13:52:24.119] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:24.119] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:38:0) after 1m0s
[13:53:24.179] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:38:0)
[13:53:24.179] Timer tick, asking Buildbucket for the build status
[13:53:24.337] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:24.337] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:39:0) after 1m0s
[13:54:24.381] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:39:0)
[13:54:24.381] Timer tick, asking Buildbucket for the build status
[13:54:24.641] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:24.641] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:40:0) after 1m0s
[13:55:24.632] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:40:0)
[13:55:24.632] Timer tick, asking Buildbucket for the build status
[13:55:24.749] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:24.749] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:41:0) after 1m0s
[13:56:24.833] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:41:0)
[13:56:24.833] Timer tick, asking Buildbucket for the build status
[13:56:24.985] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:24.985] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:42:0) after 1m0s
[13:57:25.121] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:42:0)
[13:57:25.121] Timer tick, asking Buildbucket for the build status
[13:57:25.491] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:25.491] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:43:0) after 1m0s
[13:58:25.619] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:43:0)
[13:58:25.619] Timer tick, asking Buildbucket for the build status
[13:58:25.815] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:25.815] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:44:0) after 1m0s
[13:59:25.860] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:44:0)
[13:59:25.860] Timer tick, asking Buildbucket for the build status
[13:59:26.046] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:26.046] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:45:0) after 1m0s
[14:00:26.287] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:45:0)
[14:00:26.287] Timer tick, asking Buildbucket for the build status
[14:00:26.616] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:26.616] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:46:0) after 1m0s
[14:01:26.720] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:46:0)
[14:01:26.720] Timer tick, asking Buildbucket for the build status
[14:01:26.994] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:26.994] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:47:0) after 1m0s
[14:02:27.168] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:47:0)
[14:02:27.168] Timer tick, asking Buildbucket for the build status
[14:02:27.731] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:27.731] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:48:0) after 1m0s
[14:03:27.851] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:48:0)
[14:03:27.851] Timer tick, asking Buildbucket for the build status
[14:03:28.291] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:28.291] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:49:0) after 1m0s
[14:04:28.385] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:49:0)
[14:04:28.385] Timer tick, asking Buildbucket for the build status
[14:04:28.572] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:28.572] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:50:0) after 1m0s
[14:05:28.628] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:50:0)
[14:05:28.628] Timer tick, asking Buildbucket for the build status
[14:05:28.875] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:28.875] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:51:0) after 1m0s
[14:06:28.867] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:51:0)
[14:06:28.867] Timer tick, asking Buildbucket for the build status
[14:06:29.077] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:29.077] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:52:0) after 1m0s
[14:07:29.125] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:52:0)
[14:07:29.125] Timer tick, asking Buildbucket for the build status
[14:07:29.275] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:29.275] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:53:0) after 1m0s
[14:08:29.348] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:53:0)
[14:08:29.348] Timer tick, asking Buildbucket for the build status
[14:08:29.537] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:29.537] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:54:0) after 1m0s
[14:09:29.566] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:54:0)
[14:09:29.566] Timer tick, asking Buildbucket for the build status
[14:09:29.711] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:29.711] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:55:0) after 1m0s
[14:10:29.809] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:55:0)
[14:10:29.809] Timer tick, asking Buildbucket for the build status
[14:10:29.960] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:29.960] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:56:0) after 1m0s
[14:11:30.052] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:56:0)
[14:11:30.052] Timer tick, asking Buildbucket for the build status
[14:11:30.280] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:30.280] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:57:0) after 1m0s
[14:12:30.453] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:57:0)
[14:12:30.453] Timer tick, asking Buildbucket for the build status
[14:12:30.693] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:30.693] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:58:0) after 1m0s
[14:13:30.728] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:58:0)
[14:13:30.728] Timer tick, asking Buildbucket for the build status
[14:13:30.833] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:30.833] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:59:0) after 1m0s
[14:14:30.956] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:59:0)
[14:14:30.956] Timer tick, asking Buildbucket for the build status
[14:14:31.348] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:31.348] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:60:0) after 1m0s
[14:15:31.386] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:60:0)
[14:15:31.386] Timer tick, asking Buildbucket for the build status
[14:15:31.879] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:31.879] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:61:0) after 1m0s
[14:16:31.948] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:61:0)
[14:16:31.948] Timer tick, asking Buildbucket for the build status
[14:16:32.217] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:32.217] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:62:0) after 1m0s
[14:17:32.259] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:62:0)
[14:17:32.259] Timer tick, asking Buildbucket for the build status
[14:17:32.399] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:32.399] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:63:0) after 1m0s
[14:18:32.490] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:63:0)
[14:18:32.490] Timer tick, asking Buildbucket for the build status
[14:18:32.735] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:32.735] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:64:0) after 1m0s
[14:19:32.760] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:64:0)
[14:19:32.760] Timer tick, asking Buildbucket for the build status
[14:19:33.043] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:33.043] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:65:0) after 1m0s
[14:20:33.137] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:65:0)
[14:20:33.137] Timer tick, asking Buildbucket for the build status
[14:20:33.506] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:33.506] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:66:0) after 1m0s
[14:21:33.520] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:66:0)
[14:21:33.520] Timer tick, asking Buildbucket for the build status
[14:21:33.740] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:33.740] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:67:0) after 1m0s
[14:22:33.780] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:67:0)
[14:22:33.780] Timer tick, asking Buildbucket for the build status
[14:22:33.904] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:33.904] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:68:0) after 1m0s
[14:23:33.924] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:68:0)
[14:23:33.924] Timer tick, asking Buildbucket for the build status
[14:23:34.082] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:34.082] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:69:0) after 1m0s
[14:24:34.195] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:69:0)
[14:24:34.195] Timer tick, asking Buildbucket for the build status
[14:24:34.380] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:34.380] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:70:0) after 1m0s
[14:25:34.441] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:70:0)
[14:25:34.441] Timer tick, asking Buildbucket for the build status
[14:25:34.755] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:34.755] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:71:0) after 1m0s
[14:26:34.937] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:71:0)
[14:26:34.937] Timer tick, asking Buildbucket for the build status
[14:26:35.172] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:35.172] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:72:0) after 1m0s
[14:27:35.219] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:72:0)
[14:27:35.219] Timer tick, asking Buildbucket for the build status
[14:27:35.348] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:35.348] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:73:0) after 1m0s
[14:28:35.425] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:73:0)
[14:28:35.425] Timer tick, asking Buildbucket for the build status
[14:28:36.139] Build 8877785053054195552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:36.139] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9043215115243246064:74:0) after 1m0s
[14:29:07.576] Received PubSub notification, asking Buildbucket for the build status
[14:29:07.690] Build 8877785053054195552: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[14:29:07.690] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1591885747087403",
"created_by": "project:chromium",
"created_ts": "1591881430534176",
"failure_reason": "BUILD_FAILURE",
"id": "8877785053054195552",
"parameters_json": "{\"builder_name\": \"ToTWinASanLibfuzzer\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"538ce8d5848cb51c21637bc7afb56443a2ff7100\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[9080/68580] ACTION //components/policy:proto_library_cloud_policy_full_runtime_proto_gen(//build/toolchain/win:win_clang_x64)\\nFAILED: gen/components/policy/proto/fuzzer/cloud_policy.pb.h gen/components/policy/proto/fuzzer/cloud_policy.pb.cc\\nC:\\\\b\\\\s\\\\w\\\\ir\\\\cipd_bin_packages\\\\cpython\\\\bin\\\\python.exe ../../tools/protoc_wrapper/protoc_wrapper.py cloud_policy.proto --protoc ./protoc.exe --proto-in-dir gen/components/policy/proto --plugin override_lite_runtime_plugin.exe --plugin-out-dir gen/components/policy/proto/fuzzer --import-dir=../../components/policy/proto\\ngen/components/policy/proto/fuzzer/policy_common_definitions.pb.h: Invalid argumentProtoc has returned non-zero status: 1\\n```\\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-32-7-r6lt\", \"branch\": \"refs/heads/master\", \"buildername\": \"ToTWinASanLibfuzzer\", \"buildnumber\": 5810, \"clang_revision\": \"n356976-f45c65aa-0\", \"got_angle_revision\": \"cd4bf5e7340e028ae556d309974fd344d222543f\", \"got_dawn_revision\": \"18992f7be95aadcb428fc33cf7092d36755bd8f4\", \"got_nacl_revision\": \"87616f7ce2ea8383efd3731960c15f4ba5852d93\", \"got_revision\": \"538ce8d5848cb51c21637bc7afb56443a2ff7100\", \"got_revision_cp\": \"refs/heads/master@{#777330}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"45510ad8a77862c1ce2e33f0efed41544f5f048b\", \"got_v8_revision\": \"bfc0d2f43a4fa0634b7aaa5d94fb141a64e55978\", \"got_v8_revision_cp\": \"refs/heads/8.5.137@{#1}\", \"got_webrtc_revision\": \"1e79c9ba9c6f1444d8ffee24a882d5ebfdb68595\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31494}\", \"mastername\": \"chromium.clang\", \"path_config\": \"generic\", \"perf_dashboard_machine_group\": \"ChromiumClang\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"538ce8d5848cb51c21637bc7afb56443a2ff7100\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_32bcab4cf484e052115811812b759d41ea58513be642cb87501843a8f7df98a3_v2\", \"builder_91c730c3e6ead475130e2ae78bc3f03e09cb9dcb91c3469d4450b29020110401_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-7-r6lt\"], \"image\": [\"chrome-win10-1703-20052900-58250aa4257\"], \"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\": [\"5199-07c11c0\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[9080/68580] ACTION //components/policy:proto_library_cloud_policy_full_runtime_proto_gen(//build/toolchain/win:win_clang_x64)\\nFAILED: gen/components/policy/proto/fuzzer/cloud_policy.pb.h gen/components/policy/proto/fuzzer/cloud_policy.pb.cc\\nC:\\\\b\\\\s\\\\w\\\\ir\\\\cipd_bin_packages\\\\cpython\\\\bin\\\\python.exe ../../tools/protoc_wrapper/protoc_wrapper.py cloud_policy.proto --protoc ./protoc.exe --proto-in-dir gen/components/policy/proto --plugin override_lite_runtime_plugin.exe --plugin-out-dir gen/components/policy/proto/fuzzer --import-dir=../../components/policy/proto\\ngen/components/policy/proto/fuzzer/policy_common_definitions.pb.h: Invalid argumentProtoc has returned non-zero status: 1\\n```\\n#### More information in raw_io.output[failure_summary]\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1591881658269359",
"status": "COMPLETED",
"status_changed_ts": "1591881658839729",
"tags": [
"build_address:luci.chromium.ci/ToTWinASanLibfuzzer/5810",
"builder:ToTWinASanLibfuzzer",
"buildset:commit/git/538ce8d5848cb51c21637bc7afb56443a2ff7100",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/538ce8d5848cb51c21637bc7afb56443a2ff7100",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9043215115243246064",
"scheduler_job_id:chromium/ToTWinASanLibfuzzer",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8877785053054195552/+/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:4cbc5882f51aae10",
"user_agent:luci-scheduler"
],
"updated_ts": "1591885747203671",
"url": "https://ci.chromium.org/b/8877785053054195552",
"utcnow_ts": "1591885747669792"
}
}
[14:29:07.690] Invocation finished in 1h11m58.627868823s with status FAILED