[07:31:09.799] New invocation is queued and will start shortly
[07:31:11.523] Starting the invocation (attempt 1)
[07:31:11.567] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:31:11.567] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:31:11.567] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029284950104087648",
"parameters_json": "{\"builder_name\":\"win-upload-perfetto\",\"properties\":{}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-upload-perfetto",
"scheduler_invocation_id:9029284950104087648",
"scheduler_job_id:chromium/win-upload-perfetto",
"user_agent:luci-scheduler"
]
}
[07:31:14.055] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605166271673242",
"id": "8863854887472796736",
"parameters_json": "{\"builder_name\": \"win-upload-perfetto\", \"properties\": {}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605166272997668",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/1734",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9029284950104087648",
"scheduler_job_id:chromium/win-upload-perfetto",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8863854887472796736/+/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": "1605166272997962",
"url": "https://ci.chromium.org/b/8863854887472796736",
"utcnow_ts": "1605166273350779"
}
}
[07:31:14.055] Task URL: https://ci.chromium.org/b/8863854887472796736
[07:31:14.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:2:0) after 1m0s
[07:32:14.071] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:2:0)
[07:32:14.071] Timer tick, asking Buildbucket for the build status
[07:32:14.239] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:32:14.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:3:0) after 1m0s
[07:33:14.344] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:3:0)
[07:33:14.344] Timer tick, asking Buildbucket for the build status
[07:33:14.422] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:33:14.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:4:0) after 1m0s
[07:34:14.491] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:4:0)
[07:34:14.491] Timer tick, asking Buildbucket for the build status
[07:34:14.876] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:34:14.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:5:0) after 1m0s
[07:35:14.979] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:5:0)
[07:35:14.979] Timer tick, asking Buildbucket for the build status
[07:35:15.148] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:35:15.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:6:0) after 1m0s
[07:36:15.215] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:6:0)
[07:36:15.215] Timer tick, asking Buildbucket for the build status
[07:36:15.356] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:36:15.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:7:0) after 1m0s
[07:37:15.391] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:7:0)
[07:37:15.391] Timer tick, asking Buildbucket for the build status
[07:37:15.475] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:37:15.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:8:0) after 1m0s
[07:38:15.593] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:8:0)
[07:38:15.593] Timer tick, asking Buildbucket for the build status
[07:38:15.744] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:38:15.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:9:0) after 1m0s
[07:39:15.784] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:9:0)
[07:39:15.784] Timer tick, asking Buildbucket for the build status
[07:39:15.997] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:39:15.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:10:0) after 1m0s
[07:40:16.032] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:10:0)
[07:40:16.032] Timer tick, asking Buildbucket for the build status
[07:40:16.114] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:40:16.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:11:0) after 1m0s
[07:41:16.250] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:11:0)
[07:41:16.250] Timer tick, asking Buildbucket for the build status
[07:41:16.591] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:41:16.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:12:0) after 1m0s
[07:42:16.589] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:12:0)
[07:42:16.589] Timer tick, asking Buildbucket for the build status
[07:42:16.678] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:42:16.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:13:0) after 1m0s
[07:43:16.781] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:13:0)
[07:43:16.781] Timer tick, asking Buildbucket for the build status
[07:43:17.107] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:43:17.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:14:0) after 1m0s
[07:44:17.139] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:14:0)
[07:44:17.139] Timer tick, asking Buildbucket for the build status
[07:44:17.217] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:44:17.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:15:0) after 1m0s
[07:45:17.291] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:15:0)
[07:45:17.291] Timer tick, asking Buildbucket for the build status
[07:45:17.837] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:45:17.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:16:0) after 1m0s
[07:46:17.888] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:16:0)
[07:46:17.888] Timer tick, asking Buildbucket for the build status
[07:46:17.992] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:46:17.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:17:0) after 1m0s
[07:47:18.050] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:17:0)
[07:47:18.050] Timer tick, asking Buildbucket for the build status
[07:47:18.515] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:47:18.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:18:0) after 1m0s
[07:48:18.613] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:18:0)
[07:48:18.613] Timer tick, asking Buildbucket for the build status
[07:48:19.027] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:48:19.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:19:0) after 1m0s
[07:49:19.193] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:19:0)
[07:49:19.193] Timer tick, asking Buildbucket for the build status
[07:49:19.389] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:49:19.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:20:0) after 1m0s
[07:50:19.428] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:20:0)
[07:50:19.428] Timer tick, asking Buildbucket for the build status
[07:50:19.512] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:50:19.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:21:0) after 1m0s
[07:51:19.583] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:21:0)
[07:51:19.583] Timer tick, asking Buildbucket for the build status
[07:51:19.696] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:51:19.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:22:0) after 1m0s
[07:52:19.831] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:22:0)
[07:52:19.831] Timer tick, asking Buildbucket for the build status
[07:52:19.955] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:52:19.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:23:0) after 1m0s
[07:53:19.977] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:23:0)
[07:53:19.977] Timer tick, asking Buildbucket for the build status
[07:53:20.135] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:53:20.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:24:0) after 1m0s
[07:54:20.165] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:24:0)
[07:54:20.165] Timer tick, asking Buildbucket for the build status
[07:54:20.239] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:54:20.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:25:0) after 1m0s
[07:55:20.294] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:25:0)
[07:55:20.294] Timer tick, asking Buildbucket for the build status
[07:55:20.365] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:55:20.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:26:0) after 1m0s
[07:56:20.410] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:26:0)
[07:56:20.410] Timer tick, asking Buildbucket for the build status
[07:56:20.524] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:56:20.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:27:0) after 1m0s
[07:57:20.587] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:27:0)
[07:57:20.587] Timer tick, asking Buildbucket for the build status
[07:57:20.667] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:57:20.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:28:0) after 1m0s
[07:58:21.989] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:28:0)
[07:58:21.989] Timer tick, asking Buildbucket for the build status
[07:58:22.370] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:58:22.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:29:0) after 1m0s
[07:59:22.554] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:29:0)
[07:59:22.554] Timer tick, asking Buildbucket for the build status
[07:59:22.639] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:59:22.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:30:0) after 1m0s
[08:00:22.956] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:30:0)
[08:00:22.956] Timer tick, asking Buildbucket for the build status
[08:00:23.112] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:00:23.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:31:0) after 1m0s
[08:01:24.355] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:31:0)
[08:01:24.355] Timer tick, asking Buildbucket for the build status
[08:01:24.486] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:01:24.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:32:0) after 1m0s
[08:02:24.960] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:32:0)
[08:02:24.960] Timer tick, asking Buildbucket for the build status
[08:02:25.057] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:02:25.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:33:0) after 1m0s
[08:03:25.267] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:33:0)
[08:03:25.267] Timer tick, asking Buildbucket for the build status
[08:03:25.383] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:03:25.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:34:0) after 1m0s
[08:04:25.545] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:34:0)
[08:04:25.545] Timer tick, asking Buildbucket for the build status
[08:04:25.801] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:04:25.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:35:0) after 1m0s
[08:05:25.824] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:35:0)
[08:05:25.824] Timer tick, asking Buildbucket for the build status
[08:05:25.921] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:05:25.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:36:0) after 1m0s
[08:06:25.945] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:36:0)
[08:06:25.945] Timer tick, asking Buildbucket for the build status
[08:06:26.112] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:06:26.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:37:0) after 1m0s
[08:07:26.136] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:37:0)
[08:07:26.136] Timer tick, asking Buildbucket for the build status
[08:07:26.272] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:07:26.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:38:0) after 1m0s
[08:08:26.305] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:38:0)
[08:08:26.305] Timer tick, asking Buildbucket for the build status
[08:08:26.382] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:08:26.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:39:0) after 1m0s
[08:09:26.431] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:39:0)
[08:09:26.431] Timer tick, asking Buildbucket for the build status
[08:09:26.504] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:09:26.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:40:0) after 1m0s
[08:10:26.584] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:40:0)
[08:10:26.584] Timer tick, asking Buildbucket for the build status
[08:10:26.890] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:10:26.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:41:0) after 1m0s
[08:11:26.958] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:41:0)
[08:11:26.958] Timer tick, asking Buildbucket for the build status
[08:11:27.193] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:11:27.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:42:0) after 1m0s
[08:12:27.264] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:42:0)
[08:12:27.264] Timer tick, asking Buildbucket for the build status
[08:12:27.439] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:12:27.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:43:0) after 1m0s
[08:13:27.564] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:43:0)
[08:13:27.564] Timer tick, asking Buildbucket for the build status
[08:13:27.667] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:13:27.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:44:0) after 1m0s
[08:14:27.700] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:44:0)
[08:14:27.700] Timer tick, asking Buildbucket for the build status
[08:14:27.775] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:14:27.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:45:0) after 1m0s
[08:15:27.900] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:45:0)
[08:15:27.900] Timer tick, asking Buildbucket for the build status
[08:15:28.432] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:15:28.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:46:0) after 1m0s
[08:16:28.590] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:46:0)
[08:16:28.590] Timer tick, asking Buildbucket for the build status
[08:16:28.902] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:16:28.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:47:0) after 1m0s
[08:17:28.994] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:47:0)
[08:17:28.994] Timer tick, asking Buildbucket for the build status
[08:17:29.263] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:17:29.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:48:0) after 1m0s
[08:18:29.370] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:48:0)
[08:18:29.370] Timer tick, asking Buildbucket for the build status
[08:18:29.444] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:18:29.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:49:0) after 1m0s
[08:19:29.549] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:49:0)
[08:19:29.549] Timer tick, asking Buildbucket for the build status
[08:19:29.636] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:19:29.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:50:0) after 1m0s
[08:20:29.758] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:50:0)
[08:20:29.758] Timer tick, asking Buildbucket for the build status
[08:20:29.853] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:20:29.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:51:0) after 1m0s
[08:21:29.925] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:51:0)
[08:21:29.925] Timer tick, asking Buildbucket for the build status
[08:21:30.041] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:21:30.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:52:0) after 1m0s
[08:22:30.448] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:52:0)
[08:22:30.448] Timer tick, asking Buildbucket for the build status
[08:22:30.555] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:22:30.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:53:0) after 1m0s
[08:23:30.655] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:53:0)
[08:23:30.655] Timer tick, asking Buildbucket for the build status
[08:23:30.852] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:23:30.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:54:0) after 1m0s
[08:24:30.899] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:54:0)
[08:24:30.899] Timer tick, asking Buildbucket for the build status
[08:24:31.091] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:24:31.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:55:0) after 1m0s
[08:25:31.197] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:55:0)
[08:25:31.197] Timer tick, asking Buildbucket for the build status
[08:25:31.282] Build 8863854887472796736: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:25:31.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:56:0) after 1m0s
[08:26:29.627] Received PubSub notification, asking Buildbucket for the build status
[08:26:29.695] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:31.382] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:56:0)
[08:26:31.382] Timer tick, asking Buildbucket for the build status
[08:26:31.576] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:31.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:58:0) after 1m0s
[08:27:31.607] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:58:0)
[08:27:31.607] Timer tick, asking Buildbucket for the build status
[08:27:33.076] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:33.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:59:0) after 1m0s
[08:28:33.122] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:59:0)
[08:28:33.122] Timer tick, asking Buildbucket for the build status
[08:28:33.233] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:33.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:60:0) after 1m0s
[08:29:33.309] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:60:0)
[08:29:33.309] Timer tick, asking Buildbucket for the build status
[08:29:33.396] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:33.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:61:0) after 1m0s
[08:30:33.496] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:61:0)
[08:30:33.496] Timer tick, asking Buildbucket for the build status
[08:30:33.679] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:33.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:62:0) after 1m0s
[08:31:34.688] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:62:0)
[08:31:34.689] Timer tick, asking Buildbucket for the build status
[08:31:34.807] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:34.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:63:0) after 1m0s
[08:32:35.083] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:63:0)
[08:32:35.083] Timer tick, asking Buildbucket for the build status
[08:32:35.391] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:35.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:64:0) after 1m0s
[08:33:35.623] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:64:0)
[08:33:35.623] Timer tick, asking Buildbucket for the build status
[08:33:35.729] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:35.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:65:0) after 1m0s
[08:34:35.861] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:65:0)
[08:34:35.861] Timer tick, asking Buildbucket for the build status
[08:34:36.744] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:36.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:66:0) after 1m0s
[08:35:37.004] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:66:0)
[08:35:37.004] Timer tick, asking Buildbucket for the build status
[08:35:37.141] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:37.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:67:0) after 1m0s
[08:36:37.355] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:67:0)
[08:36:37.355] Timer tick, asking Buildbucket for the build status
[08:36:37.514] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:37.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:68:0) after 1m0s
[08:37:37.773] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:68:0)
[08:37:37.773] Timer tick, asking Buildbucket for the build status
[08:37:37.934] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:37.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:69:0) after 1m0s
[08:38:38.045] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:69:0)
[08:38:38.045] Timer tick, asking Buildbucket for the build status
[08:38:38.244] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:38.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:70:0) after 1m0s
[08:39:40.327] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:70:0)
[08:39:40.327] Timer tick, asking Buildbucket for the build status
[08:39:40.426] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:40.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:71:0) after 1m0s
[08:40:40.558] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:71:0)
[08:40:40.558] Timer tick, asking Buildbucket for the build status
[08:40:40.658] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:40.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:72:0) after 1m0s
[08:41:40.722] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:72:0)
[08:41:40.722] Timer tick, asking Buildbucket for the build status
[08:41:41.314] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:41.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:73:0) after 1m0s
[08:42:41.512] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:73:0)
[08:42:41.512] Timer tick, asking Buildbucket for the build status
[08:42:41.945] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:41.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:74:0) after 1m0s
[08:43:42.089] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:74:0)
[08:43:42.090] Timer tick, asking Buildbucket for the build status
[08:43:42.148] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:42.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:75:0) after 1m0s
[08:44:42.284] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:75:0)
[08:44:42.284] Timer tick, asking Buildbucket for the build status
[08:44:42.372] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:42.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:76:0) after 1m0s
[08:45:42.465] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:76:0)
[08:45:42.465] Timer tick, asking Buildbucket for the build status
[08:45:42.545] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:42.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:77:0) after 1m0s
[08:46:42.579] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:77:0)
[08:46:42.579] Timer tick, asking Buildbucket for the build status
[08:46:42.773] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:42.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:78:0) after 1m0s
[08:47:42.875] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:78:0)
[08:47:42.875] Timer tick, asking Buildbucket for the build status
[08:47:42.954] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:42.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:79:0) after 1m0s
[08:48:43.097] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:79:0)
[08:48:43.097] Timer tick, asking Buildbucket for the build status
[08:48:43.175] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:43.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:80:0) after 1m0s
[08:49:43.237] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:80:0)
[08:49:43.237] Timer tick, asking Buildbucket for the build status
[08:49:43.302] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:43.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:81:0) after 1m0s
[08:50:43.425] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:81:0)
[08:50:43.425] Timer tick, asking Buildbucket for the build status
[08:50:43.571] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:43.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:82:0) after 1m0s
[08:51:43.630] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:82:0)
[08:51:43.630] Timer tick, asking Buildbucket for the build status
[08:51:43.771] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:43.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:83:0) after 1m0s
[08:52:43.799] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:83:0)
[08:52:43.799] Timer tick, asking Buildbucket for the build status
[08:52:43.855] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:43.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:84:0) after 1m0s
[08:53:43.832] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:84:0)
[08:53:43.832] Timer tick, asking Buildbucket for the build status
[08:53:43.926] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:43.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:85:0) after 1m0s
[08:54:43.973] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:85:0)
[08:54:43.973] Timer tick, asking Buildbucket for the build status
[08:54:44.105] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:44.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:86:0) after 1m0s
[08:55:44.098] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:86:0)
[08:55:44.098] Timer tick, asking Buildbucket for the build status
[08:55:44.165] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:44.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:87:0) after 1m0s
[08:56:44.265] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:87:0)
[08:56:44.265] Timer tick, asking Buildbucket for the build status
[08:56:44.386] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:44.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:88:0) after 1m0s
[08:57:44.416] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:88:0)
[08:57:44.416] Timer tick, asking Buildbucket for the build status
[08:57:44.577] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:44.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:89:0) after 1m0s
[08:58:44.771] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:89:0)
[08:58:44.771] Timer tick, asking Buildbucket for the build status
[08:58:44.886] Build 8863854887472796736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:44.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029284950104087648:90:0) after 1m0s
[08:59:09.589] Received PubSub notification, asking Buildbucket for the build status
[08:59:09.679] Build 8863854887472796736: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[08:59:09.679] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605171548731519",
"created_by": "project:chromium",
"created_ts": "1605166271673242",
"failure_reason": "BUILD_FAILURE",
"id": "8863854887472796736",
"parameters_json": "{\"builder_name\": \"win-upload-perfetto\", \"properties\": {}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**upload_trace_processor** failed.\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"b9bcda0634d7253101273997b92a88a077cb2bfd\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-8-1-tx7f\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"win-upload-perfetto\", \"buildnumber\": 1734, \"got_angle_revision\": \"6067e9ce8bb3bde028dfa613af4d7b1ac41391e3\", \"got_dawn_revision\": \"b70a5b02e9712693ffb796c0b0d8cfa24ca5eba5\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"b9bcda0634d7253101273997b92a88a077cb2bfd\", \"got_revision_cp\": \"refs/heads/master@{#826692}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"b638dfe160a17f288c774fd98f725f212f843c2b\", \"got_v8_revision\": \"f3414dbc4f3adca0d966820894857e5255b3fc20\", \"got_v8_revision_cp\": \"refs/heads/8.8.293@{#1}\", \"got_webrtc_revision\": \"06bbeb3398afa8651ca76ea7eb56b7d046edfca8\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32588}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"swarm_hashes_refs/heads/master(at){#826692}_without_patch\": {\"upload_trace_processor\": \"177bed083a6efd6036b02d2fb0e6ebe9ff72a4e2\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-8-1-tx7f\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5626-39642e9\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**upload_trace_processor** failed.\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605169588856979",
"status": "COMPLETED",
"status_changed_ts": "1605169589351254",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/1734",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9029284950104087648",
"scheduler_job_id:chromium/win-upload-perfetto",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8863854887472796736/+/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:4fd42f1ba91d7a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1605171549304206",
"url": "https://ci.chromium.org/b/8863854887472796736",
"utcnow_ts": "1605171549665902"
}
}
[08:59:09.679] Invocation finished in 1h27m59.898870924s with status FAILED