[08:33:09.028] New invocation is queued and will start shortly
[08:33:13.824] Starting the invocation (attempt 1)
[08:33:13.862] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:33:13.862] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:33:13.862] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9077025651503048128",
"parameters_json": "{\"builder_name\":\"ToTWinASanLibfuzzer\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"48327f50b4777504f55c6ce336686e3ce4e3ca04\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTWinASanLibfuzzer",
"scheduler_invocation_id:9077025651503048128",
"scheduler_job_id:chromium/ToTWinASanLibfuzzer",
"user_agent:luci-scheduler",
"buildset:commit/git/48327f50b4777504f55c6ce336686e3ce4e3ca04",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/48327f50b4777504f55c6ce336686e3ce4e3ca04",
"gitiles_ref:refs/heads/master"
]
}
[08:33:23.438] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1559637196415468",
"id": "8911595583090097648",
"parameters_json": "{\"builder_name\": \"ToTWinASanLibfuzzer\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"48327f50b4777504f55c6ce336686e3ce4e3ca04\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1559637202045514",
"tags": [
"build_address:luci.chromium.ci/ToTWinASanLibfuzzer/3052",
"builder:ToTWinASanLibfuzzer",
"buildset:commit/git/48327f50b4777504f55c6ce336686e3ce4e3ca04",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/48327f50b4777504f55c6ce336686e3ce4e3ca04",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077025651503048128",
"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/8911595583090097648/+/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": "1559637202045646",
"url": "https://ci.chromium.org/b/8911595583090097648",
"utcnow_ts": "1559637203385312"
}
}
[08:33:23.438] Task URL: https://ci.chromium.org/b/8911595583090097648
[08:33:23.438] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:2:0) after 1m0s
[08:34:23.459] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:2:0)
[08:34:23.459] Timer tick, asking Buildbucket for the build status
[08:34:23.690] Build 8911595583090097648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:34:23.690] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:3:0) after 1m0s
[08:35:23.712] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:3:0)
[08:35:23.712] Timer tick, asking Buildbucket for the build status
[08:35:26.267] Build 8911595583090097648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:35:26.267] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:4:0) after 1m0s
[08:36:26.290] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:4:0)
[08:36:26.290] Timer tick, asking Buildbucket for the build status
[08:36:27.170] Build 8911595583090097648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:36:27.170] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:5:0) after 1m0s
[08:37:27.294] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:5:0)
[08:37:27.294] Timer tick, asking Buildbucket for the build status
[08:37:28.543] Build 8911595583090097648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:37:28.543] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:6:0) after 1m0s
[08:38:28.565] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:6:0)
[08:38:28.565] Timer tick, asking Buildbucket for the build status
[08:38:29.916] Build 8911595583090097648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:38:29.916] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:7:0) after 1m0s
[08:39:29.969] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:7:0)
[08:39:29.969] Timer tick, asking Buildbucket for the build status
[08:39:30.458] Build 8911595583090097648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:39:30.458] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:8:0) after 1m0s
[08:40:30.646] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:8:0)
[08:40:30.646] Timer tick, asking Buildbucket for the build status
[08:40:30.917] Build 8911595583090097648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:40:30.917] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:9:0) after 1m0s
[08:41:30.901] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:9:0)
[08:41:30.901] Timer tick, asking Buildbucket for the build status
[08:41:32.372] Build 8911595583090097648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:41:32.372] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:10:0) after 1m0s
[08:41:44.025] Received PubSub notification, asking Buildbucket for the build status
[08:41:45.776] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:32.390] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:10:0)
[08:42:32.390] Timer tick, asking Buildbucket for the build status
[08:42:39.917] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:39.917] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:12:0) after 1m0s
[08:43:40.160] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:12:0)
[08:43:40.160] Timer tick, asking Buildbucket for the build status
[08:43:40.548] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:40.548] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:13:0) after 1m0s
[08:44:40.647] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:13:0)
[08:44:40.647] Timer tick, asking Buildbucket for the build status
[08:44:43.468] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:43.468] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:14:0) after 1m0s
[08:45:43.489] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:14:0)
[08:45:43.489] Timer tick, asking Buildbucket for the build status
[08:45:45.524] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:45.524] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:15:0) after 1m0s
[08:46:45.506] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:15:0)
[08:46:45.506] Timer tick, asking Buildbucket for the build status
[08:46:46.666] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:46.666] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:16:0) after 1m0s
[08:47:46.732] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:16:0)
[08:47:46.732] Timer tick, asking Buildbucket for the build status
[08:47:47.931] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:47.931] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:17:0) after 1m0s
[08:48:48.408] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:17:0)
[08:48:48.408] Timer tick, asking Buildbucket for the build status
[08:48:49.069] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:49.069] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:18:0) after 1m0s
[08:49:49.444] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:18:0)
[08:49:49.444] Timer tick, asking Buildbucket for the build status
[08:49:49.615] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:49.615] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:19:0) after 1m0s
[08:50:49.683] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:19:0)
[08:50:49.683] Timer tick, asking Buildbucket for the build status
[08:50:51.517] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:51.518] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:20:0) after 1m0s
[08:51:51.706] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:20:0)
[08:51:51.706] Timer tick, asking Buildbucket for the build status
[08:51:54.615] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:54.615] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:21:0) after 1m0s
[08:52:54.762] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:21:0)
[08:52:54.762] Timer tick, asking Buildbucket for the build status
[08:52:57.082] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:57.082] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:22:0) after 1m0s
[08:53:57.202] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:22:0)
[08:53:57.202] Timer tick, asking Buildbucket for the build status
[08:53:57.633] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:57.633] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:23:0) after 1m0s
[08:54:57.672] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:23:0)
[08:54:57.672] Timer tick, asking Buildbucket for the build status
[08:54:57.840] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:57.840] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:24:0) after 1m0s
[08:55:57.954] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:24:0)
[08:55:57.954] Timer tick, asking Buildbucket for the build status
[08:55:58.388] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:58.388] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:25:0) after 1m0s
[08:56:58.369] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:25:0)
[08:56:58.369] Timer tick, asking Buildbucket for the build status
[08:57:00.296] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:00.296] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:26:0) after 1m0s
[08:58:00.299] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:26:0)
[08:58:00.299] Timer tick, asking Buildbucket for the build status
[08:58:01.958] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:01.958] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:27:0) after 1m0s
[08:59:01.970] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:27:0)
[08:59:01.970] Timer tick, asking Buildbucket for the build status
[08:59:03.008] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:03.008] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:28:0) after 1m0s
[09:00:03.035] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:28:0)
[09:00:03.035] Timer tick, asking Buildbucket for the build status
[09:00:06.608] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:06.608] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:29:0) after 1m0s
[09:01:06.649] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:29:0)
[09:01:06.649] Timer tick, asking Buildbucket for the build status
[09:01:09.879] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:09.879] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:30:0) after 1m0s
[09:02:09.905] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:30:0)
[09:02:09.905] Timer tick, asking Buildbucket for the build status
[09:02:10.433] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:10.433] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:31:0) after 1m0s
[09:03:10.488] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:31:0)
[09:03:10.488] Timer tick, asking Buildbucket for the build status
[09:03:10.692] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:10.692] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:32:0) after 1m0s
[09:04:10.737] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:32:0)
[09:04:10.737] Timer tick, asking Buildbucket for the build status
[09:04:12.863] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:12.863] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:33:0) after 1m0s
[09:05:12.877] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:33:0)
[09:05:12.877] Timer tick, asking Buildbucket for the build status
[09:05:13.179] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:13.179] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:34:0) after 1m0s
[09:06:13.267] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:34:0)
[09:06:13.267] Timer tick, asking Buildbucket for the build status
[09:06:15.042] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:15.042] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:35:0) after 1m0s
[09:07:15.031] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:35:0)
[09:07:15.031] Timer tick, asking Buildbucket for the build status
[09:07:16.677] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:16.677] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:36:0) after 1m0s
[09:08:16.779] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:36:0)
[09:08:16.779] Timer tick, asking Buildbucket for the build status
[09:08:17.065] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:17.065] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:37:0) after 1m0s
[09:09:17.088] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:37:0)
[09:09:17.088] Timer tick, asking Buildbucket for the build status
[09:09:17.398] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:17.398] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:38:0) after 1m0s
[09:10:17.454] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:38:0)
[09:10:17.454] Timer tick, asking Buildbucket for the build status
[09:10:20.403] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:20.403] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:39:0) after 1m0s
[09:11:20.394] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:39:0)
[09:11:20.394] Timer tick, asking Buildbucket for the build status
[09:11:21.376] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:21.376] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:40:0) after 1m0s
[09:12:21.412] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:40:0)
[09:12:21.412] Timer tick, asking Buildbucket for the build status
[09:12:21.580] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:21.580] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:41:0) after 1m0s
[09:13:21.677] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:41:0)
[09:13:21.677] Timer tick, asking Buildbucket for the build status
[09:13:24.561] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:24.561] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:42:0) after 1m0s
[09:14:24.563] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:42:0)
[09:14:24.563] Timer tick, asking Buildbucket for the build status
[09:14:26.455] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:26.455] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:43:0) after 1m0s
[09:15:26.508] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:43:0)
[09:15:26.508] Timer tick, asking Buildbucket for the build status
[09:15:27.863] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:27.863] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:44:0) after 1m0s
[09:16:27.947] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:44:0)
[09:16:27.947] Timer tick, asking Buildbucket for the build status
[09:16:30.579] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:30.579] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:45:0) after 1m0s
[09:17:30.637] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:45:0)
[09:17:30.637] Timer tick, asking Buildbucket for the build status
[09:17:31.197] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:31.198] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:46:0) after 1m0s
[09:18:31.230] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:46:0)
[09:18:31.230] Timer tick, asking Buildbucket for the build status
[09:18:31.346] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:31.346] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:47:0) after 1m0s
[09:19:31.438] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:47:0)
[09:19:31.438] Timer tick, asking Buildbucket for the build status
[09:19:34.072] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:34.073] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:48:0) after 1m0s
[09:20:34.129] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:48:0)
[09:20:34.129] Timer tick, asking Buildbucket for the build status
[09:20:34.235] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:34.235] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:49:0) after 1m0s
[09:21:34.305] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:49:0)
[09:21:34.305] Timer tick, asking Buildbucket for the build status
[09:21:34.449] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:34.449] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:50:0) after 1m0s
[09:22:34.518] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:50:0)
[09:22:34.518] Timer tick, asking Buildbucket for the build status
[09:22:34.617] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:34.617] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:51:0) after 1m0s
[09:23:34.666] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:51:0)
[09:23:34.666] Timer tick, asking Buildbucket for the build status
[09:23:34.794] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:34.794] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:52:0) after 1m0s
[09:24:34.922] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:52:0)
[09:24:34.922] Timer tick, asking Buildbucket for the build status
[09:24:35.039] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:35.040] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:53:0) after 1m0s
[09:25:35.071] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:53:0)
[09:25:35.071] Timer tick, asking Buildbucket for the build status
[09:25:37.496] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:37.496] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:54:0) after 1m0s
[09:26:37.606] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:54:0)
[09:26:37.606] Timer tick, asking Buildbucket for the build status
[09:26:38.568] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:38.568] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:55:0) after 1m0s
[09:27:38.672] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:55:0)
[09:27:38.672] Timer tick, asking Buildbucket for the build status
[09:27:41.070] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:41.070] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:56:0) after 1m0s
[09:28:41.095] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:56:0)
[09:28:41.095] Timer tick, asking Buildbucket for the build status
[09:28:41.560] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:41.560] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:57:0) after 1m0s
[09:29:41.705] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:57:0)
[09:29:41.705] Timer tick, asking Buildbucket for the build status
[09:29:43.933] Build 8911595583090097648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:43.934] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9077025651503048128:58:0) after 1m0s
[09:29:45.720] Received PubSub notification, asking Buildbucket for the build status
[09:29:46.155] Build 8911595583090097648: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[09:29:46.155] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1559640581752399",
"created_by": "project:chromium",
"created_ts": "1559637196415468",
"failure_reason": "BUILD_FAILURE",
"id": "8911595583090097648",
"parameters_json": "{\"builder_name\": \"ToTWinASanLibfuzzer\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"48327f50b4777504f55c6ce336686e3ce4e3ca04\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('compile') (retcode: 1)\"}, \"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-10-cst3\", \"branch\": \"refs/heads/master\", \"buildername\": \"ToTWinASanLibfuzzer\", \"buildnumber\": 3052, \"got_angle_revision\": \"4a75741655d330e257b2f20d7404cce1e51ef72e\", \"got_clang_revision\": \"362472-11de0e71-0\", \"got_dawn_revision\": \"b632bc58ed373eb2020af65e0fdeaf07d46d1a9c\", \"got_nacl_revision\": \"0ddc033406886a709b901e0c312872529f9705e8\", \"got_revision\": \"48327f50b4777504f55c6ce336686e3ce4e3ca04\", \"got_revision_cp\": \"refs/heads/master@{#665844}\", \"got_swarming_client_revision\": \"779c4f0f8488c64587b75dbb001d18c3c0c4cda9\", \"got_v8_revision\": \"cd6e781b96615b3d35153cb6e4768d8d12a17929\", \"got_v8_revision_cp\": \"refs/heads/7.7.12@{#1}\", \"got_webrtc_revision\": \"bd002715a8983c52282c77c5cfae8bc59a58528a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28147}\", \"mastername\": \"chromium.clang\", \"path_config\": \"generic\", \"perf_dashboard_machine_group\": \"ChromiumClang\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"48327f50b4777504f55c6ce336686e3ce4e3ca04\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_v2\", \"builder_32bcab4cf484e052115811812b759d41ea58513be642cb87501843a8f7df98a3_v2\", \"builder_a4ee364c4b5996d1649f16c77d7186222b75eb5b75762fbbe3baeec97aac12d9_v2\", \"builder_a820300c9e65cc745d70affd2be7bdb9e0da8337bfe61b74452f33419656c42a_v2\", \"git\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-10-cst3\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"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\": [\"4316-effbcfc\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"Step('compile') (retcode: 1)\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1559637700211716",
"status": "COMPLETED",
"status_changed_ts": "1559637701667977",
"tags": [
"build_address:luci.chromium.ci/ToTWinASanLibfuzzer/3052",
"builder:ToTWinASanLibfuzzer",
"buildset:commit/git/48327f50b4777504f55c6ce336686e3ce4e3ca04",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/48327f50b4777504f55c6ce336686e3ce4e3ca04",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077025651503048128",
"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/8911595583090097648/+/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:453a709f8ba21310",
"user_agent:luci-scheduler"
],
"updated_ts": "1559640582180232",
"url": "https://ci.chromium.org/b/8911595583090097648",
"utcnow_ts": "1559640586135122"
}
}
[09:29:46.155] Invocation finished in 56m37.147627792s with status FAILED