[20:20:32.179] New invocation is queued and will start shortly
[20:20:33.409] Starting the invocation (attempt 1)
[20:20:33.463] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:20:33.463] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:20:33.463] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9070367568087196096",
"parameters_json": "{\"builder_name\":\"ToTWinASanLibfuzzer\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"4ecf8decc0584c334d30c0206abc759e3e90b50d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTWinASanLibfuzzer",
"scheduler_invocation_id:9070367568087196096",
"scheduler_job_id:chromium/ToTWinASanLibfuzzer",
"user_agent:luci-scheduler",
"buildset:commit/git/4ecf8decc0584c334d30c0206abc759e3e90b50d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4ecf8decc0584c334d30c0206abc759e3e90b50d",
"gitiles_ref:refs/heads/master"
]
}
[20:20:34.176] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1565986833617449",
"id": "8904937505911829824",
"parameters_json": "{\"builder_name\": \"ToTWinASanLibfuzzer\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"4ecf8decc0584c334d30c0206abc759e3e90b50d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1565986834010157",
"tags": [
"build_address:luci.chromium.ci/ToTWinASanLibfuzzer/3506",
"builder:ToTWinASanLibfuzzer",
"buildset:commit/git/4ecf8decc0584c334d30c0206abc759e3e90b50d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4ecf8decc0584c334d30c0206abc759e3e90b50d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9070367568087196096",
"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/8904937505911829824/+/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": "1565986834010276",
"url": "https://ci.chromium.org/b/8904937505911829824",
"utcnow_ts": "1565986834167359"
}
}
[20:20:34.176] Task URL: https://ci.chromium.org/b/8904937505911829824
[20:20:34.176] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:2:0) after 1m0s
[20:20:56.862] Received PubSub notification, asking Buildbucket for the build status
[20:20:57.020] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:34.348] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:2:0)
[20:21:34.348] Timer tick, asking Buildbucket for the build status
[20:21:34.486] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:34.486] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:4:0) after 1m0s
[20:22:34.506] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:4:0)
[20:22:34.506] Timer tick, asking Buildbucket for the build status
[20:22:34.698] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:34.698] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:5:0) after 1m0s
[20:23:34.773] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:5:0)
[20:23:34.773] Timer tick, asking Buildbucket for the build status
[20:23:35.193] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:35.194] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:6:0) after 1m0s
[20:24:35.313] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:6:0)
[20:24:35.313] Timer tick, asking Buildbucket for the build status
[20:24:36.231] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:36.231] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:7:0) after 1m0s
[20:25:36.243] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:7:0)
[20:25:36.243] Timer tick, asking Buildbucket for the build status
[20:25:36.532] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:36.532] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:8:0) after 1m0s
[20:26:36.554] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:8:0)
[20:26:36.554] Timer tick, asking Buildbucket for the build status
[20:26:37.476] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:37.476] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:9:0) after 1m0s
[20:27:37.569] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:9:0)
[20:27:37.569] Timer tick, asking Buildbucket for the build status
[20:27:37.805] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:37.805] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:10:0) after 1m0s
[20:28:37.841] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:10:0)
[20:28:37.841] Timer tick, asking Buildbucket for the build status
[20:28:38.458] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:38.458] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:11:0) after 1m0s
[20:29:38.485] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:11:0)
[20:29:38.485] Timer tick, asking Buildbucket for the build status
[20:29:38.673] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:38.673] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:12:0) after 1m0s
[20:30:38.742] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:12:0)
[20:30:38.742] Timer tick, asking Buildbucket for the build status
[20:30:38.974] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:38.974] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:13:0) after 1m0s
[20:31:38.994] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:13:0)
[20:31:38.994] Timer tick, asking Buildbucket for the build status
[20:31:40.207] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:40.207] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:14:0) after 1m0s
[20:32:40.264] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:14:0)
[20:32:40.264] Timer tick, asking Buildbucket for the build status
[20:32:40.441] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:40.441] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:15:0) after 1m0s
[20:33:40.463] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:15:0)
[20:33:40.463] Timer tick, asking Buildbucket for the build status
[20:33:40.705] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:40.705] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:16:0) after 1m0s
[20:34:40.777] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:16:0)
[20:34:40.777] Timer tick, asking Buildbucket for the build status
[20:34:41.125] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:41.125] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:17:0) after 1m0s
[20:35:41.228] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:17:0)
[20:35:41.228] Timer tick, asking Buildbucket for the build status
[20:35:41.850] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:41.850] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:18:0) after 1m0s
[20:36:41.966] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:18:0)
[20:36:41.966] Timer tick, asking Buildbucket for the build status
[20:36:42.239] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:42.239] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:19:0) after 1m0s
[20:37:42.300] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:19:0)
[20:37:42.300] Timer tick, asking Buildbucket for the build status
[20:37:42.499] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:42.499] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:20:0) after 1m0s
[20:38:42.785] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:20:0)
[20:38:42.785] Timer tick, asking Buildbucket for the build status
[20:38:43.411] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:43.411] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:21:0) after 1m0s
[20:39:43.497] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:21:0)
[20:39:43.497] Timer tick, asking Buildbucket for the build status
[20:39:43.660] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:43.660] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:22:0) after 1m0s
[20:40:43.836] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:22:0)
[20:40:43.836] Timer tick, asking Buildbucket for the build status
[20:40:44.823] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:44.823] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:23:0) after 1m0s
[20:41:44.875] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:23:0)
[20:41:44.875] Timer tick, asking Buildbucket for the build status
[20:41:45.172] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:45.172] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:24:0) after 1m0s
[20:42:45.202] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:24:0)
[20:42:45.202] Timer tick, asking Buildbucket for the build status
[20:42:45.356] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:45.356] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:25:0) after 1m0s
[20:43:45.465] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:25:0)
[20:43:45.465] Timer tick, asking Buildbucket for the build status
[20:43:46.064] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:46.064] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:26:0) after 1m0s
[20:44:46.113] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:26:0)
[20:44:46.113] Timer tick, asking Buildbucket for the build status
[20:44:46.662] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:46.662] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:27:0) after 1m0s
[20:45:46.767] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:27:0)
[20:45:46.767] Timer tick, asking Buildbucket for the build status
[20:45:46.961] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:46.961] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:28:0) after 1m0s
[20:46:47.007] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:28:0)
[20:46:47.007] Timer tick, asking Buildbucket for the build status
[20:46:47.265] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:47.265] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:29:0) after 1m0s
[20:47:47.321] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:29:0)
[20:47:47.321] Timer tick, asking Buildbucket for the build status
[20:47:48.237] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:48.237] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:30:0) after 1m0s
[20:48:48.244] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:30:0)
[20:48:48.244] Timer tick, asking Buildbucket for the build status
[20:48:48.636] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:48.636] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:31:0) after 1m0s
[20:49:48.689] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:31:0)
[20:49:48.689] Timer tick, asking Buildbucket for the build status
[20:49:48.836] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:48.836] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:32:0) after 1m0s
[20:50:48.994] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:32:0)
[20:50:48.994] Timer tick, asking Buildbucket for the build status
[20:50:49.971] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:49.971] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:33:0) after 1m0s
[20:51:50.116] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:33:0)
[20:51:50.116] Timer tick, asking Buildbucket for the build status
[20:51:50.346] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:50.346] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:34:0) after 1m0s
[20:52:50.542] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:34:0)
[20:52:50.542] Timer tick, asking Buildbucket for the build status
[20:52:50.918] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:50.918] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:35:0) after 1m0s
[20:53:51.046] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:35:0)
[20:53:51.046] Timer tick, asking Buildbucket for the build status
[20:53:51.261] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:51.261] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:36:0) after 1m0s
[20:54:51.313] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:36:0)
[20:54:51.313] Timer tick, asking Buildbucket for the build status
[20:54:51.466] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:51.466] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:37:0) after 1m0s
[20:55:51.598] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:37:0)
[20:55:51.598] Timer tick, asking Buildbucket for the build status
[20:55:51.774] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:51.774] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:38:0) after 1m0s
[20:56:51.901] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:38:0)
[20:56:51.901] Timer tick, asking Buildbucket for the build status
[20:56:52.366] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:52.366] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:39:0) after 1m0s
[20:57:52.367] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:39:0)
[20:57:52.367] Timer tick, asking Buildbucket for the build status
[20:57:52.697] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:52.697] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:40:0) after 1m0s
[20:58:52.797] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:40:0)
[20:58:52.797] Timer tick, asking Buildbucket for the build status
[20:58:53.023] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:53.023] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:41:0) after 1m0s
[20:59:53.041] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:41:0)
[20:59:53.041] Timer tick, asking Buildbucket for the build status
[20:59:53.249] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:53.249] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:42:0) after 1m0s
[21:00:53.443] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:42:0)
[21:00:53.443] Timer tick, asking Buildbucket for the build status
[21:00:53.670] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:53.670] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:43:0) after 1m0s
[21:01:53.962] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:43:0)
[21:01:53.962] Timer tick, asking Buildbucket for the build status
[21:01:54.118] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:54.118] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:44:0) after 1m0s
[21:02:54.172] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:44:0)
[21:02:54.172] Timer tick, asking Buildbucket for the build status
[21:02:54.749] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:54.749] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:45:0) after 1m0s
[21:03:54.884] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:45:0)
[21:03:54.884] Timer tick, asking Buildbucket for the build status
[21:03:55.424] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:55.424] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:46:0) after 1m0s
[21:04:55.439] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:46:0)
[21:04:55.439] Timer tick, asking Buildbucket for the build status
[21:04:56.543] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:56.543] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:47:0) after 1m0s
[21:05:56.589] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:47:0)
[21:05:56.589] Timer tick, asking Buildbucket for the build status
[21:05:56.766] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:56.766] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:48:0) after 1m0s
[21:06:56.831] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:48:0)
[21:06:56.831] Timer tick, asking Buildbucket for the build status
[21:06:57.456] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:57.456] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:49:0) after 1m0s
[21:07:57.510] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:49:0)
[21:07:57.510] Timer tick, asking Buildbucket for the build status
[21:07:57.655] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:57.655] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:50:0) after 1m0s
[21:08:57.725] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:50:0)
[21:08:57.725] Timer tick, asking Buildbucket for the build status
[21:08:58.000] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:58.000] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:51:0) after 1m0s
[21:09:58.036] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:51:0)
[21:09:58.036] Timer tick, asking Buildbucket for the build status
[21:09:58.337] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:58.337] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:52:0) after 1m0s
[21:10:58.514] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:52:0)
[21:10:58.514] Timer tick, asking Buildbucket for the build status
[21:10:58.935] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:58.935] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:53:0) after 1m0s
[21:11:58.966] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:53:0)
[21:11:58.966] Timer tick, asking Buildbucket for the build status
[21:11:59.647] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:59.647] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:54:0) after 1m0s
[21:12:59.754] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:54:0)
[21:12:59.754] Timer tick, asking Buildbucket for the build status
[21:13:00.118] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:00.118] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:55:0) after 1m0s
[21:14:00.380] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:55:0)
[21:14:00.380] Timer tick, asking Buildbucket for the build status
[21:14:03.072] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:03.072] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:56:0) after 1m0s
[21:15:03.107] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:56:0)
[21:15:03.107] Timer tick, asking Buildbucket for the build status
[21:15:04.932] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:04.932] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:57:0) after 1m0s
[21:16:04.960] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:57:0)
[21:16:04.960] Timer tick, asking Buildbucket for the build status
[21:16:05.260] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:05.260] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:58:0) after 1m0s
[21:17:05.297] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:58:0)
[21:17:05.297] Timer tick, asking Buildbucket for the build status
[21:17:05.549] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:05.549] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:59:0) after 1m0s
[21:18:05.569] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:59:0)
[21:18:05.569] Timer tick, asking Buildbucket for the build status
[21:18:06.826] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:06.826] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:60:0) after 1m0s
[21:19:06.862] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:60:0)
[21:19:06.862] Timer tick, asking Buildbucket for the build status
[21:19:07.203] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:07.203] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:61:0) after 1m0s
[21:20:07.233] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:61:0)
[21:20:07.233] Timer tick, asking Buildbucket for the build status
[21:20:07.575] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:07.575] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:62:0) after 1m0s
[21:21:07.604] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:62:0)
[21:21:07.604] Timer tick, asking Buildbucket for the build status
[21:21:08.491] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:08.491] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:63:0) after 1m0s
[21:22:08.516] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:63:0)
[21:22:08.516] Timer tick, asking Buildbucket for the build status
[21:22:09.224] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:09.224] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:64:0) after 1m0s
[21:23:09.272] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:64:0)
[21:23:09.272] Timer tick, asking Buildbucket for the build status
[21:23:09.851] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:09.851] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:65:0) after 1m0s
[21:24:09.948] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:65:0)
[21:24:09.948] Timer tick, asking Buildbucket for the build status
[21:24:10.201] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:10.201] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:66:0) after 1m0s
[21:25:10.277] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:66:0)
[21:25:10.277] Timer tick, asking Buildbucket for the build status
[21:25:10.569] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:10.569] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:67:0) after 1m0s
[21:26:10.692] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:67:0)
[21:26:10.692] Timer tick, asking Buildbucket for the build status
[21:26:11.903] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:11.903] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:68:0) after 1m0s
[21:27:12.095] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:68:0)
[21:27:12.095] Timer tick, asking Buildbucket for the build status
[21:27:12.290] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:12.290] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:69:0) after 1m0s
[21:28:12.408] Handling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:69:0)
[21:28:12.408] Timer tick, asking Buildbucket for the build status
[21:28:13.129] Build 8904937505911829824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:13.129] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWinASanLibfuzzer:9070367568087196096:70:0) after 1m0s
[21:28:17.806] Received PubSub notification, asking Buildbucket for the build status
[21:28:18.024] Build 8904937505911829824: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[21:28:18.024] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1565990897249657",
"created_by": "project:chromium",
"created_ts": "1565986833617449",
"failure_reason": "BUILD_FAILURE",
"id": "8904937505911829824",
"parameters_json": "{\"builder_name\": \"ToTWinASanLibfuzzer\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"4ecf8decc0584c334d30c0206abc759e3e90b50d\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('gclient runhooks') (retcode: 2)\"}, \"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-4-1hmy\", \"branch\": \"refs/heads/master\", \"buildername\": \"ToTWinASanLibfuzzer\", \"buildnumber\": 3506, \"got_angle_revision\": \"cc25d9fbc4bbd919bf9a9d6244639742ddb99fee\", \"got_dawn_revision\": \"786f76574a7face893b369e0dcca60b7bc9fd1a8\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"4ecf8decc0584c334d30c0206abc759e3e90b50d\", \"got_revision_cp\": \"refs/heads/master@{#687794}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"7b861aca66ea8080a32eba6217f8833cdcc32a09\", \"got_v8_revision_cp\": \"refs/heads/7.8.113@{#1}\", \"got_webrtc_revision\": \"f5e5d250bc85616e74487f8330ed8d99cc9cf84f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28880}\", \"mastername\": \"chromium.clang\", \"path_config\": \"generic\", \"perf_dashboard_machine_group\": \"ChromiumClang\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"4ecf8decc0584c334d30c0206abc759e3e90b50d\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4f77261552e8d7e3833e5abc9c27108a2b925a0ef53cb3b8532fd8ec4484401e_v2\", \"git\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-4-1hmy\"], \"image\": [\"chrome-win10-1703-19072400-fedda89f1f1\"], \"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\": [\"4397-8786906\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"Step('gclient runhooks') (retcode: 2)\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1565986854280145",
"status": "COMPLETED",
"status_changed_ts": "1565986855875371",
"tags": [
"build_address:luci.chromium.ci/ToTWinASanLibfuzzer/3506",
"builder:ToTWinASanLibfuzzer",
"buildset:commit/git/4ecf8decc0584c334d30c0206abc759e3e90b50d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4ecf8decc0584c334d30c0206abc759e3e90b50d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9070367568087196096",
"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/8904937505911829824/+/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:46b4e8510ab7ff10",
"user_agent:luci-scheduler"
],
"updated_ts": "1565990897331931",
"url": "https://ci.chromium.org/b/8904937505911829824",
"utcnow_ts": "1565990898008101"
}
}
[21:28:18.024] Invocation finished in 1h7m45.865095232s with status FAILED