[05:32:09.234] New invocation is queued and will start shortly
[05:32:10.392] Starting the invocation (attempt 1)
[05:32:10.462] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:32:10.463] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:32:10.463] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029020646638127024",
"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:9029020646638127024",
"scheduler_job_id:chromium/win-upload-perfetto",
"user_agent:luci-scheduler"
]
}
[05:32:11.536] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605418330607080",
"id": "8863590584523423952",
"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": "1605418331325481",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/1749",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9029020646638127024",
"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/8863590584523423952/+/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": "1605418331325620",
"url": "https://ci.chromium.org/b/8863590584523423952",
"utcnow_ts": "1605418331517619"
}
}
[05:32:11.536] Task URL: https://ci.chromium.org/b/8863590584523423952
[05:32:11.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:2:0) after 1m0s
[05:33:11.560] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:2:0)
[05:33:11.560] Timer tick, asking Buildbucket for the build status
[05:33:11.655] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:33:11.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:3:0) after 1m0s
[05:34:11.739] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:3:0)
[05:34:11.739] Timer tick, asking Buildbucket for the build status
[05:34:11.828] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:34:11.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:4:0) after 1m0s
[05:35:11.853] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:4:0)
[05:35:11.853] Timer tick, asking Buildbucket for the build status
[05:35:11.917] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:35:11.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:5:0) after 1m0s
[05:36:12.002] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:5:0)
[05:36:12.002] Timer tick, asking Buildbucket for the build status
[05:36:12.071] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:36:12.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:6:0) after 1m0s
[05:37:12.181] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:6:0)
[05:37:12.183] Timer tick, asking Buildbucket for the build status
[05:37:12.470] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:37:12.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:7:0) after 1m0s
[05:38:12.528] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:7:0)
[05:38:12.528] Timer tick, asking Buildbucket for the build status
[05:38:12.725] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:38:12.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:8:0) after 1m0s
[05:39:12.742] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:8:0)
[05:39:12.742] Timer tick, asking Buildbucket for the build status
[05:39:12.812] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:39:12.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:9:0) after 1m0s
[05:40:12.961] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:9:0)
[05:40:12.961] Timer tick, asking Buildbucket for the build status
[05:40:13.026] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:40:13.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:10:0) after 1m0s
[05:41:13.194] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:10:0)
[05:41:13.194] Timer tick, asking Buildbucket for the build status
[05:41:13.372] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:41:13.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:11:0) after 1m0s
[05:42:13.532] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:11:0)
[05:42:13.532] Timer tick, asking Buildbucket for the build status
[05:42:13.607] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:42:13.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:12:0) after 1m0s
[05:43:13.610] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:12:0)
[05:43:13.610] Timer tick, asking Buildbucket for the build status
[05:43:13.712] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:43:13.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:13:0) after 1m0s
[05:44:14.013] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:13:0)
[05:44:14.013] Timer tick, asking Buildbucket for the build status
[05:44:14.099] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:44:14.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:14:0) after 1m0s
[05:45:14.355] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:14:0)
[05:45:14.355] Timer tick, asking Buildbucket for the build status
[05:45:14.814] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:45:14.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:15:0) after 1m0s
[05:46:15.040] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:15:0)
[05:46:15.040] Timer tick, asking Buildbucket for the build status
[05:46:15.105] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:46:15.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:16:0) after 1m0s
[05:47:15.155] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:16:0)
[05:47:15.155] Timer tick, asking Buildbucket for the build status
[05:47:15.487] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:47:15.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:17:0) after 1m0s
[05:48:15.510] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:17:0)
[05:48:15.510] Timer tick, asking Buildbucket for the build status
[05:48:15.800] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:48:15.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:18:0) after 1m0s
[05:49:15.878] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:18:0)
[05:49:15.878] Timer tick, asking Buildbucket for the build status
[05:49:16.022] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:49:16.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:19:0) after 1m0s
[05:50:16.103] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:19:0)
[05:50:16.103] Timer tick, asking Buildbucket for the build status
[05:50:16.188] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:50:16.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:20:0) after 1m0s
[05:51:16.235] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:20:0)
[05:51:16.235] Timer tick, asking Buildbucket for the build status
[05:51:16.640] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:51:16.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:21:0) after 1m0s
[05:52:16.725] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:21:0)
[05:52:16.725] Timer tick, asking Buildbucket for the build status
[05:52:16.806] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:52:16.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:22:0) after 1m0s
[05:53:16.836] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:22:0)
[05:53:16.836] Timer tick, asking Buildbucket for the build status
[05:53:17.295] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:53:17.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:23:0) after 1m0s
[05:54:17.352] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:23:0)
[05:54:17.352] Timer tick, asking Buildbucket for the build status
[05:54:17.417] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:54:17.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:24:0) after 1m0s
[05:55:17.471] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:24:0)
[05:55:17.471] Timer tick, asking Buildbucket for the build status
[05:55:17.557] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:55:17.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:25:0) after 1m0s
[05:56:17.664] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:25:0)
[05:56:17.664] Timer tick, asking Buildbucket for the build status
[05:56:17.732] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:56:17.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:26:0) after 1m0s
[05:57:17.763] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:26:0)
[05:57:17.763] Timer tick, asking Buildbucket for the build status
[05:57:17.839] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:57:17.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:27:0) after 1m0s
[05:58:17.893] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:27:0)
[05:58:17.893] Timer tick, asking Buildbucket for the build status
[05:58:17.985] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:58:17.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:28:0) after 1m0s
[05:59:18.074] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:28:0)
[05:59:18.074] Timer tick, asking Buildbucket for the build status
[05:59:18.161] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:59:18.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:29:0) after 1m0s
[06:00:18.186] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:29:0)
[06:00:18.186] Timer tick, asking Buildbucket for the build status
[06:00:18.259] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:00:18.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:30:0) after 1m0s
[06:01:26.788] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:30:0)
[06:01:26.788] Timer tick, asking Buildbucket for the build status
[06:01:26.863] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:01:26.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:31:0) after 1m0s
[06:02:26.901] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:31:0)
[06:02:26.901] Timer tick, asking Buildbucket for the build status
[06:02:27.644] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:02:27.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:32:0) after 1m0s
[06:03:27.667] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:32:0)
[06:03:27.667] Timer tick, asking Buildbucket for the build status
[06:03:27.905] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:03:27.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:33:0) after 1m0s
[06:04:27.923] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:33:0)
[06:04:27.923] Timer tick, asking Buildbucket for the build status
[06:04:27.993] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:04:27.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:34:0) after 1m0s
[06:05:28.051] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:34:0)
[06:05:28.051] Timer tick, asking Buildbucket for the build status
[06:05:28.214] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:05:28.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:35:0) after 1m0s
[06:06:28.254] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:35:0)
[06:06:28.254] Timer tick, asking Buildbucket for the build status
[06:06:28.439] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:06:28.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:36:0) after 1m0s
[06:07:28.472] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:36:0)
[06:07:28.472] Timer tick, asking Buildbucket for the build status
[06:07:28.551] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:07:28.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:37:0) after 1m0s
[06:08:28.629] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:37:0)
[06:08:28.629] Timer tick, asking Buildbucket for the build status
[06:08:28.770] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:08:28.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:38:0) after 1m0s
[06:09:28.752] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:38:0)
[06:09:28.752] Timer tick, asking Buildbucket for the build status
[06:09:28.823] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:09:28.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:39:0) after 1m0s
[06:10:28.842] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:39:0)
[06:10:28.842] Timer tick, asking Buildbucket for the build status
[06:10:28.937] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:10:28.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:40:0) after 1m0s
[06:11:28.958] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:40:0)
[06:11:28.958] Timer tick, asking Buildbucket for the build status
[06:11:29.034] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:11:29.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:41:0) after 1m0s
[06:12:29.056] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:41:0)
[06:12:29.056] Timer tick, asking Buildbucket for the build status
[06:12:29.227] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:12:29.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:42:0) after 1m0s
[06:13:29.314] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:42:0)
[06:13:29.314] Timer tick, asking Buildbucket for the build status
[06:13:29.410] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:13:29.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:43:0) after 1m0s
[06:14:29.512] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:43:0)
[06:14:29.512] Timer tick, asking Buildbucket for the build status
[06:14:29.607] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:14:29.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:44:0) after 1m0s
[06:15:29.713] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:44:0)
[06:15:29.713] Timer tick, asking Buildbucket for the build status
[06:15:29.794] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:15:29.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:45:0) after 1m0s
[06:16:29.829] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:45:0)
[06:16:29.829] Timer tick, asking Buildbucket for the build status
[06:16:29.979] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:16:29.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:46:0) after 1m0s
[06:17:30.151] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:46:0)
[06:17:30.151] Timer tick, asking Buildbucket for the build status
[06:17:30.252] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:17:30.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:47:0) after 1m0s
[06:18:30.476] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:47:0)
[06:18:30.476] Timer tick, asking Buildbucket for the build status
[06:18:30.580] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:18:30.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:48:0) after 1m0s
[06:19:30.640] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:48:0)
[06:19:30.640] Timer tick, asking Buildbucket for the build status
[06:19:30.712] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:19:30.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:49:0) after 1m0s
[06:20:30.827] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:49:0)
[06:20:30.827] Timer tick, asking Buildbucket for the build status
[06:20:30.896] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:20:30.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:50:0) after 1m0s
[06:21:30.949] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:50:0)
[06:21:30.949] Timer tick, asking Buildbucket for the build status
[06:21:31.023] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:21:31.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:51:0) after 1m0s
[06:22:31.101] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:51:0)
[06:22:31.101] Timer tick, asking Buildbucket for the build status
[06:22:32.899] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:22:32.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:52:0) after 1m0s
[06:23:32.990] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:52:0)
[06:23:32.990] Timer tick, asking Buildbucket for the build status
[06:23:33.149] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:23:33.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:53:0) after 1m0s
[06:24:33.179] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:53:0)
[06:24:33.179] Timer tick, asking Buildbucket for the build status
[06:24:33.490] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:24:33.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:54:0) after 1m0s
[06:25:33.567] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:54:0)
[06:25:33.567] Timer tick, asking Buildbucket for the build status
[06:25:33.683] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:25:33.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:55:0) after 1m0s
[06:26:33.705] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:55:0)
[06:26:33.705] Timer tick, asking Buildbucket for the build status
[06:26:33.985] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:26:33.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:56:0) after 1m0s
[06:27:34.068] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:56:0)
[06:27:34.068] Timer tick, asking Buildbucket for the build status
[06:27:34.342] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:27:34.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:57:0) after 1m0s
[06:28:34.366] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:57:0)
[06:28:34.366] Timer tick, asking Buildbucket for the build status
[06:28:34.448] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:28:34.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:58:0) after 1m0s
[06:29:34.472] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:58:0)
[06:29:34.472] Timer tick, asking Buildbucket for the build status
[06:29:34.538] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:29:34.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:59:0) after 1m0s
[06:30:34.578] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:59:0)
[06:30:34.578] Timer tick, asking Buildbucket for the build status
[06:30:34.652] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:30:34.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:60:0) after 1m0s
[06:31:34.673] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:60:0)
[06:31:34.673] Timer tick, asking Buildbucket for the build status
[06:31:34.845] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:31:34.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:61:0) after 1m0s
[06:32:34.881] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:61:0)
[06:32:34.881] Timer tick, asking Buildbucket for the build status
[06:32:35.035] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:32:35.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:62:0) after 1m0s
[06:33:35.150] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:62:0)
[06:33:35.150] Timer tick, asking Buildbucket for the build status
[06:33:35.313] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:33:35.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:63:0) after 1m0s
[06:34:35.354] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:63:0)
[06:34:35.354] Timer tick, asking Buildbucket for the build status
[06:34:35.566] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:34:35.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:64:0) after 1m0s
[06:35:35.591] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:64:0)
[06:35:35.591] Timer tick, asking Buildbucket for the build status
[06:35:35.801] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:35:35.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:65:0) after 1m0s
[06:36:35.825] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:65:0)
[06:36:35.825] Timer tick, asking Buildbucket for the build status
[06:36:35.899] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:36:35.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:66:0) after 1m0s
[06:37:35.925] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:66:0)
[06:37:35.925] Timer tick, asking Buildbucket for the build status
[06:37:36.324] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:37:36.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:67:0) after 1m0s
[06:38:36.346] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:67:0)
[06:38:36.346] Timer tick, asking Buildbucket for the build status
[06:38:36.418] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:38:36.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:68:0) after 1m0s
[06:39:36.443] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:68:0)
[06:39:36.443] Timer tick, asking Buildbucket for the build status
[06:39:36.615] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:39:36.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:69:0) after 1m0s
[06:40:36.653] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:69:0)
[06:40:36.653] Timer tick, asking Buildbucket for the build status
[06:40:36.735] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:40:36.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:70:0) after 1m0s
[06:41:36.806] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:70:0)
[06:41:36.806] Timer tick, asking Buildbucket for the build status
[06:41:36.936] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:41:36.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:71:0) after 1m0s
[06:42:36.961] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:71:0)
[06:42:36.961] Timer tick, asking Buildbucket for the build status
[06:42:38.560] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:42:38.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:72:0) after 1m0s
[06:43:38.581] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:72:0)
[06:43:38.581] Timer tick, asking Buildbucket for the build status
[06:43:38.654] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:43:38.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:73:0) after 1m0s
[06:44:38.727] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:73:0)
[06:44:38.727] Timer tick, asking Buildbucket for the build status
[06:44:38.810] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:44:38.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:74:0) after 1m0s
[06:45:38.866] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:74:0)
[06:45:38.866] Timer tick, asking Buildbucket for the build status
[06:45:38.936] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:45:38.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:75:0) after 1m0s
[06:46:38.958] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:75:0)
[06:46:38.958] Timer tick, asking Buildbucket for the build status
[06:46:39.024] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:46:39.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:76:0) after 1m0s
[06:47:39.015] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:76:0)
[06:47:39.015] Timer tick, asking Buildbucket for the build status
[06:47:39.278] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:47:39.278] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:77:0) after 1m0s
[06:48:39.348] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:77:0)
[06:48:39.348] Timer tick, asking Buildbucket for the build status
[06:48:39.421] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:48:39.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:78:0) after 1m0s
[06:49:39.540] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:78:0)
[06:49:39.540] Timer tick, asking Buildbucket for the build status
[06:49:39.610] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:49:39.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:79:0) after 1m0s
[06:50:39.631] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:79:0)
[06:50:39.631] Timer tick, asking Buildbucket for the build status
[06:50:39.703] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:50:39.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:80:0) after 1m0s
[06:51:39.750] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:80:0)
[06:51:39.750] Timer tick, asking Buildbucket for the build status
[06:51:39.832] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:51:39.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:81:0) after 1m0s
[06:52:39.859] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:81:0)
[06:52:39.859] Timer tick, asking Buildbucket for the build status
[06:52:39.955] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:52:39.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:82:0) after 1m0s
[06:53:39.994] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:82:0)
[06:53:39.994] Timer tick, asking Buildbucket for the build status
[06:53:40.067] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:53:40.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:83:0) after 1m0s
[06:54:40.108] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:83:0)
[06:54:40.108] Timer tick, asking Buildbucket for the build status
[06:54:40.181] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:54:40.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:84:0) after 1m0s
[06:55:40.244] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:84:0)
[06:55:40.245] Timer tick, asking Buildbucket for the build status
[06:55:40.321] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:55:40.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:85:0) after 1m0s
[06:56:40.338] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:85:0)
[06:56:40.338] Timer tick, asking Buildbucket for the build status
[06:56:40.437] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:56:40.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:86:0) after 1m0s
[06:57:41.527] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:86:0)
[06:57:41.527] Timer tick, asking Buildbucket for the build status
[06:57:41.703] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:57:41.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:87:0) after 1m0s
[06:58:41.787] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:87:0)
[06:58:41.787] Timer tick, asking Buildbucket for the build status
[06:58:41.858] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:58:41.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:88:0) after 1m0s
[06:59:41.970] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:88:0)
[06:59:41.970] Timer tick, asking Buildbucket for the build status
[06:59:42.054] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:59:42.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:89:0) after 1m0s
[07:00:42.109] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:89:0)
[07:00:42.109] Timer tick, asking Buildbucket for the build status
[07:00:42.285] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:00:42.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:90:0) after 1m0s
[07:01:42.308] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:90:0)
[07:01:42.308] Timer tick, asking Buildbucket for the build status
[07:01:42.384] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:01:42.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:91:0) after 1m0s
[07:02:42.448] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:91:0)
[07:02:42.448] Timer tick, asking Buildbucket for the build status
[07:02:42.824] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:02:42.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:92:0) after 1m0s
[07:03:43.008] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:92:0)
[07:03:43.008] Timer tick, asking Buildbucket for the build status
[07:03:43.084] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:03:43.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:93:0) after 1m0s
[07:04:43.098] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:93:0)
[07:04:43.098] Timer tick, asking Buildbucket for the build status
[07:04:43.173] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:04:43.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:94:0) after 1m0s
[07:05:43.322] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:94:0)
[07:05:43.322] Timer tick, asking Buildbucket for the build status
[07:05:43.511] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:05:43.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:95:0) after 1m0s
[07:06:43.775] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:95:0)
[07:06:43.775] Timer tick, asking Buildbucket for the build status
[07:06:43.853] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:06:43.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:96:0) after 1m0s
[07:07:43.920] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:96:0)
[07:07:43.920] Timer tick, asking Buildbucket for the build status
[07:07:44.028] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:07:44.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:97:0) after 1m0s
[07:08:44.144] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:97:0)
[07:08:44.144] Timer tick, asking Buildbucket for the build status
[07:08:44.250] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:08:44.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:98:0) after 1m0s
[07:09:44.371] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:98:0)
[07:09:44.371] Timer tick, asking Buildbucket for the build status
[07:09:44.438] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:09:44.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:99:0) after 1m0s
[07:10:44.418] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:99:0)
[07:10:44.418] Timer tick, asking Buildbucket for the build status
[07:10:44.483] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:10:44.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:100:0) after 1m0s
[07:11:44.565] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:100:0)
[07:11:44.565] Timer tick, asking Buildbucket for the build status
[07:11:44.906] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:11:44.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:101:0) after 1m0s
[07:12:44.966] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:101:0)
[07:12:44.966] Timer tick, asking Buildbucket for the build status
[07:12:47.600] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:12:47.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:102:0) after 1m0s
[07:13:47.653] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:102:0)
[07:13:47.653] Timer tick, asking Buildbucket for the build status
[07:13:47.717] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:13:47.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:103:0) after 1m0s
[07:14:47.747] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:103:0)
[07:14:47.747] Timer tick, asking Buildbucket for the build status
[07:14:47.928] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:14:47.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:104:0) after 1m0s
[07:15:48.006] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:104:0)
[07:15:48.006] Timer tick, asking Buildbucket for the build status
[07:15:48.116] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:15:48.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:105:0) after 1m0s
[07:16:48.578] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:105:0)
[07:16:48.578] Timer tick, asking Buildbucket for the build status
[07:16:48.691] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:16:48.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:106:0) after 1m0s
[07:17:49.957] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:106:0)
[07:17:49.957] Timer tick, asking Buildbucket for the build status
[07:17:50.179] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:17:50.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:107:0) after 1m0s
[07:18:52.674] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:107:0)
[07:18:52.674] Timer tick, asking Buildbucket for the build status
[07:18:52.744] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:18:52.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:108:0) after 1m0s
[07:19:52.829] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:108:0)
[07:19:52.829] Timer tick, asking Buildbucket for the build status
[07:19:53.004] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:19:53.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:109:0) after 1m0s
[07:20:53.034] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:109:0)
[07:20:53.034] Timer tick, asking Buildbucket for the build status
[07:20:53.308] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:20:53.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:110:0) after 1m0s
[07:21:53.334] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:110:0)
[07:21:53.334] Timer tick, asking Buildbucket for the build status
[07:21:53.401] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:21:53.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:111:0) after 1m0s
[07:22:53.424] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:111:0)
[07:22:53.424] Timer tick, asking Buildbucket for the build status
[07:22:53.527] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:22:53.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:112:0) after 1m0s
[07:23:53.597] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:112:0)
[07:23:53.597] Timer tick, asking Buildbucket for the build status
[07:23:53.693] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:23:53.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:113:0) after 1m0s
[07:24:53.721] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:113:0)
[07:24:53.721] Timer tick, asking Buildbucket for the build status
[07:24:53.796] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:24:53.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:114:0) after 1m0s
[07:25:53.839] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:114:0)
[07:25:53.839] Timer tick, asking Buildbucket for the build status
[07:25:54.123] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:25:54.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:115:0) after 1m0s
[07:26:54.182] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:115:0)
[07:26:54.182] Timer tick, asking Buildbucket for the build status
[07:26:54.256] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:26:54.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:116:0) after 1m0s
[07:27:54.289] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:116:0)
[07:27:54.289] Timer tick, asking Buildbucket for the build status
[07:27:54.385] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:27:54.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:117:0) after 1m0s
[07:28:54.470] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:117:0)
[07:28:54.470] Timer tick, asking Buildbucket for the build status
[07:28:54.607] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:28:54.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:118:0) after 1m0s
[07:29:54.662] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:118:0)
[07:29:54.662] Timer tick, asking Buildbucket for the build status
[07:29:54.875] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:29:54.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:119:0) after 1m0s
[07:30:55.055] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:119:0)
[07:30:55.055] Timer tick, asking Buildbucket for the build status
[07:30:55.124] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:30:55.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:120:0) after 1m0s
[07:31:55.289] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:120:0)
[07:31:55.289] Timer tick, asking Buildbucket for the build status
[07:31:55.400] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:31:55.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:121:0) after 1m0s
[07:32:55.569] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:121:0)
[07:32:55.569] Timer tick, asking Buildbucket for the build status
[07:32:55.666] Build 8863590584523423952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:32:55.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:122:0) after 1m0s
[07:33:09.288] Received PubSub notification, asking Buildbucket for the build status
[07:33:09.660] Build 8863590584523423952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:56.187] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:122:0)
[07:33:56.187] Timer tick, asking Buildbucket for the build status
[07:33:56.419] Build 8863590584523423952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:56.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:124:0) after 1m0s
[07:34:56.661] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:124:0)
[07:34:56.661] Timer tick, asking Buildbucket for the build status
[07:34:56.851] Build 8863590584523423952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:56.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:125:0) after 1m0s
[07:35:56.991] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:125:0)
[07:35:56.991] Timer tick, asking Buildbucket for the build status
[07:35:57.065] Build 8863590584523423952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:57.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:126:0) after 1m0s
[07:36:57.219] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:126:0)
[07:36:57.219] Timer tick, asking Buildbucket for the build status
[07:36:57.291] Build 8863590584523423952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:57.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:127:0) after 1m0s
[07:37:57.492] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:127:0)
[07:37:57.492] Timer tick, asking Buildbucket for the build status
[07:37:57.623] Build 8863590584523423952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:57.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:128:0) after 1m0s
[07:38:57.682] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:128:0)
[07:38:57.682] Timer tick, asking Buildbucket for the build status
[07:38:57.783] Build 8863590584523423952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:57.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:129:0) after 1m0s
[07:39:57.797] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:129:0)
[07:39:57.797] Timer tick, asking Buildbucket for the build status
[07:39:57.966] Build 8863590584523423952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:57.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:130:0) after 1m0s
[07:40:57.965] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:130:0)
[07:40:57.965] Timer tick, asking Buildbucket for the build status
[07:40:58.030] Build 8863590584523423952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:58.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:131:0) after 1m0s
[07:41:58.086] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:131:0)
[07:41:58.086] Timer tick, asking Buildbucket for the build status
[07:41:58.192] Build 8863590584523423952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:58.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:132:0) after 1m0s
[07:42:58.259] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:132:0)
[07:42:58.259] Timer tick, asking Buildbucket for the build status
[07:42:58.377] Build 8863590584523423952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:58.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029020646638127024:133:0) after 1m0s
[07:43:21.837] Received PubSub notification, asking Buildbucket for the build status
[07:43:22.082] Build 8863590584523423952: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[07:43:22.082] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605426200940623",
"created_by": "project:chromium",
"created_ts": "1605418330607080",
"failure_reason": "BUILD_FAILURE",
"id": "8863590584523423952",
"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\": \"b582a513ec042c25192fd4263b8fe7c99c1c7db1\", \"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-kzdy\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"win-upload-perfetto\", \"buildnumber\": 1749, \"got_angle_revision\": \"f46e6201b40bdee3e0b79d5d97660fc302fd76f5\", \"got_dawn_revision\": \"aabde6c88f91f450b5845c8a720356bc4382ed01\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"b582a513ec042c25192fd4263b8fe7c99c1c7db1\", \"got_revision_cp\": \"refs/heads/master@{#827630}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"e4c1a25cc6797a47043fc415c53b8edcd7b3e37e\", \"got_v8_revision\": \"2dbcdc105b963ee2501c82139eef7e0603977ff0\", \"got_v8_revision_cp\": \"refs/heads/8.8.278@{#1}\", \"got_webrtc_revision\": \"99df1af2aed6a8d10bc62dc348f63d6c084c1949\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32609}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"swarm_hashes_refs/heads/master(at){#827630}_without_patch\": {\"upload_trace_processor\": \"62b7cb7385c2ea48fa5e1ae00a7bb3abd6d0b9e6\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_024bff123481647a20c7dafe5897c9112095480a6680ca28e6413691579f95f9_v2\", \"builder_572c7d4336ecb7e9d9870c0b7d73eb5c4ec9b5267736155cf1f0727554d722db_v2\", \"builder_6b755bbaffc280fb83e7e303317e4888b2acb597c551cb7db892c262f1cf5030_v2\", \"builder_9fe9814ce14bb8ceec490c2f73b354cfd72877294131c83e8f28b19a7d4650fc_v2\", \"git\", \"goma_v2\", \"vpython\"], \"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-kzdy\"], \"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": "1605425587906120",
"status": "COMPLETED",
"status_changed_ts": "1605425589016561",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/1749",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9029020646638127024",
"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/8863590584523423952/+/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:4fe3354d2a67b810",
"user_agent:luci-scheduler"
],
"updated_ts": "1605426201201035",
"url": "https://ci.chromium.org/b/8863590584523423952",
"utcnow_ts": "1605426202022096"
}
}
[07:43:22.082] Invocation finished in 2h11m12.875262426s with status FAILED