[16:13:15.850] New invocation is queued and will start shortly
[16:13:17.217] Starting the invocation (attempt 1)
[16:13:17.259] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:13:17.259] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:13:17.259] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9035140905181683168",
"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:9035140905181683168",
"scheduler_job_id:chromium/win-upload-perfetto",
"user_agent:luci-scheduler"
]
}
[16:13:18.497] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1599581597384107",
"id": "8869710842899561456",
"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": "1599581598113511",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/1282",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9035140905181683168",
"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/8869710842899561456/+/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": "1599581598113667",
"url": "https://ci.chromium.org/b/8869710842899561456",
"utcnow_ts": "1599581598446979"
}
}
[16:13:18.497] Task URL: https://ci.chromium.org/b/8869710842899561456
[16:13:18.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:2:0) after 1m0s
[16:14:18.717] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:2:0)
[16:14:18.717] Timer tick, asking Buildbucket for the build status
[16:14:18.934] Build 8869710842899561456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:14:18.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:3:0) after 1m0s
[16:15:19.201] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:3:0)
[16:15:19.201] Timer tick, asking Buildbucket for the build status
[16:15:19.410] Build 8869710842899561456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:15:19.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:4:0) after 1m0s
[16:16:19.580] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:4:0)
[16:16:19.580] Timer tick, asking Buildbucket for the build status
[16:16:19.701] Build 8869710842899561456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:16:19.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:5:0) after 1m0s
[16:17:19.795] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:5:0)
[16:17:19.795] Timer tick, asking Buildbucket for the build status
[16:17:19.888] Build 8869710842899561456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:17:19.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:6:0) after 1m0s
[16:18:20.262] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:6:0)
[16:18:20.262] Timer tick, asking Buildbucket for the build status
[16:18:20.603] Build 8869710842899561456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:18:20.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:7:0) after 1m0s
[16:19:20.838] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:7:0)
[16:19:20.838] Timer tick, asking Buildbucket for the build status
[16:19:20.941] Build 8869710842899561456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:19:20.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:8:0) after 1m0s
[16:19:47.184] Received PubSub notification, asking Buildbucket for the build status
[16:19:47.403] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:21.036] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:8:0)
[16:20:21.036] Timer tick, asking Buildbucket for the build status
[16:20:21.193] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:21.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:10:0) after 1m0s
[16:21:21.305] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:10:0)
[16:21:21.305] Timer tick, asking Buildbucket for the build status
[16:21:21.424] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:21.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:11:0) after 1m0s
[16:22:21.514] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:11:0)
[16:22:21.514] Timer tick, asking Buildbucket for the build status
[16:22:21.631] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:21.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:12:0) after 1m0s
[16:23:21.741] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:12:0)
[16:23:21.741] Timer tick, asking Buildbucket for the build status
[16:23:21.902] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:21.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:13:0) after 1m0s
[16:24:22.217] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:13:0)
[16:24:22.217] Timer tick, asking Buildbucket for the build status
[16:24:22.365] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:22.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:14:0) after 1m0s
[16:25:24.329] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:14:0)
[16:25:24.329] Timer tick, asking Buildbucket for the build status
[16:25:24.440] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:24.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:15:0) after 1m0s
[16:26:24.725] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:15:0)
[16:26:24.725] Timer tick, asking Buildbucket for the build status
[16:26:24.845] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:24.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:16:0) after 1m0s
[16:27:25.041] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:16:0)
[16:27:25.041] Timer tick, asking Buildbucket for the build status
[16:27:25.472] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:25.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:17:0) after 1m0s
[16:28:25.523] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:17:0)
[16:28:25.524] Timer tick, asking Buildbucket for the build status
[16:28:25.677] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:25.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:18:0) after 1m0s
[16:29:25.739] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:18:0)
[16:29:25.739] Timer tick, asking Buildbucket for the build status
[16:29:26.023] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:26.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:19:0) after 1m0s
[16:30:26.031] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:19:0)
[16:30:26.031] Timer tick, asking Buildbucket for the build status
[16:30:26.150] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:26.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:20:0) after 1m0s
[16:31:26.197] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:20:0)
[16:31:26.197] Timer tick, asking Buildbucket for the build status
[16:31:26.296] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:26.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:21:0) after 1m0s
[16:32:26.389] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:21:0)
[16:32:26.389] Timer tick, asking Buildbucket for the build status
[16:32:26.759] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:26.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:22:0) after 1m0s
[16:33:26.876] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:22:0)
[16:33:26.876] Timer tick, asking Buildbucket for the build status
[16:33:27.158] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:27.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:23:0) after 1m0s
[16:34:27.186] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:23:0)
[16:34:27.186] Timer tick, asking Buildbucket for the build status
[16:34:27.702] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:27.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:24:0) after 1m0s
[16:35:27.810] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:24:0)
[16:35:27.810] Timer tick, asking Buildbucket for the build status
[16:35:27.906] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:27.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:25:0) after 1m0s
[16:36:27.924] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:25:0)
[16:36:27.924] Timer tick, asking Buildbucket for the build status
[16:36:28.276] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:28.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:26:0) after 1m0s
[16:37:28.325] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:26:0)
[16:37:28.325] Timer tick, asking Buildbucket for the build status
[16:37:28.558] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:28.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:27:0) after 1m0s
[16:38:28.638] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:27:0)
[16:38:28.638] Timer tick, asking Buildbucket for the build status
[16:38:28.726] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:28.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:28:0) after 1m0s
[16:39:28.784] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:28:0)
[16:39:28.784] Timer tick, asking Buildbucket for the build status
[16:39:29.238] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:29.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:29:0) after 1m0s
[16:40:29.232] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:29:0)
[16:40:29.232] Timer tick, asking Buildbucket for the build status
[16:40:29.564] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:29.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:30:0) after 1m0s
[16:41:29.638] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:30:0)
[16:41:29.638] Timer tick, asking Buildbucket for the build status
[16:41:29.764] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:29.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:31:0) after 1m0s
[16:42:29.818] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:31:0)
[16:42:29.818] Timer tick, asking Buildbucket for the build status
[16:42:29.899] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:29.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:32:0) after 1m0s
[16:43:30.608] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:32:0)
[16:43:30.608] Timer tick, asking Buildbucket for the build status
[16:43:30.774] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:30.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:33:0) after 1m0s
[16:44:31.092] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:33:0)
[16:44:31.092] Timer tick, asking Buildbucket for the build status
[16:44:31.341] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:31.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:34:0) after 1m0s
[16:45:31.511] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:34:0)
[16:45:31.511] Timer tick, asking Buildbucket for the build status
[16:45:31.683] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:31.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:35:0) after 1m0s
[16:46:31.713] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:35:0)
[16:46:31.713] Timer tick, asking Buildbucket for the build status
[16:46:31.806] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:31.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:36:0) after 1m0s
[16:47:31.989] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:36:0)
[16:47:31.989] Timer tick, asking Buildbucket for the build status
[16:47:32.086] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:32.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:37:0) after 1m0s
[16:48:32.190] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:37:0)
[16:48:32.190] Timer tick, asking Buildbucket for the build status
[16:48:32.505] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:32.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:38:0) after 1m0s
[16:49:32.527] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:38:0)
[16:49:32.527] Timer tick, asking Buildbucket for the build status
[16:49:32.615] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:32.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:39:0) after 1m0s
[16:50:32.641] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:39:0)
[16:50:32.641] Timer tick, asking Buildbucket for the build status
[16:50:32.847] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:32.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:40:0) after 1m0s
[16:51:32.935] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:40:0)
[16:51:32.935] Timer tick, asking Buildbucket for the build status
[16:51:33.041] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:33.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:41:0) after 1m0s
[16:52:33.111] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:41:0)
[16:52:33.111] Timer tick, asking Buildbucket for the build status
[16:52:33.506] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:33.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:42:0) after 1m0s
[16:53:33.541] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:42:0)
[16:53:33.541] Timer tick, asking Buildbucket for the build status
[16:53:33.817] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:33.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:43:0) after 1m0s
[16:54:33.893] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:43:0)
[16:54:33.893] Timer tick, asking Buildbucket for the build status
[16:54:33.969] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:33.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:44:0) after 1m0s
[16:55:34.000] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:44:0)
[16:55:34.000] Timer tick, asking Buildbucket for the build status
[16:55:34.117] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:34.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:45:0) after 1m0s
[16:56:34.162] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:45:0)
[16:56:34.162] Timer tick, asking Buildbucket for the build status
[16:56:34.498] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:34.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:46:0) after 1m0s
[16:57:34.618] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:46:0)
[16:57:34.618] Timer tick, asking Buildbucket for the build status
[16:57:34.673] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:34.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:47:0) after 1m0s
[16:58:34.813] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:47:0)
[16:58:34.813] Timer tick, asking Buildbucket for the build status
[16:58:35.001] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:35.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:48:0) after 1m0s
[16:59:35.099] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:48:0)
[16:59:35.099] Timer tick, asking Buildbucket for the build status
[16:59:35.298] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:35.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:49:0) after 1m0s
[17:00:35.379] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:49:0)
[17:00:35.379] Timer tick, asking Buildbucket for the build status
[17:00:35.928] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:35.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:50:0) after 1m0s
[17:01:36.052] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:50:0)
[17:01:36.052] Timer tick, asking Buildbucket for the build status
[17:01:36.154] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:36.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:51:0) after 1m0s
[17:02:36.130] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:51:0)
[17:02:36.130] Timer tick, asking Buildbucket for the build status
[17:02:36.712] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:36.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:52:0) after 1m0s
[17:03:36.762] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:52:0)
[17:03:36.762] Timer tick, asking Buildbucket for the build status
[17:03:37.979] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:37.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:53:0) after 1m0s
[17:04:38.018] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:53:0)
[17:04:38.018] Timer tick, asking Buildbucket for the build status
[17:04:38.233] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:38.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:54:0) after 1m0s
[17:05:38.270] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:54:0)
[17:05:38.270] Timer tick, asking Buildbucket for the build status
[17:05:38.802] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:38.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:55:0) after 1m0s
[17:06:38.853] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:55:0)
[17:06:38.853] Timer tick, asking Buildbucket for the build status
[17:06:39.223] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:39.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:56:0) after 1m0s
[17:07:39.292] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:56:0)
[17:07:39.292] Timer tick, asking Buildbucket for the build status
[17:07:39.818] Build 8869710842899561456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:39.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9035140905181683168:57:0) after 1m0s
[17:08:16.278] Received PubSub notification, asking Buildbucket for the build status
[17:08:16.361] Build 8869710842899561456: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[17:08:16.361] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1599584895904753",
"created_by": "project:chromium",
"created_ts": "1599581597384107",
"failure_reason": "BUILD_FAILURE",
"id": "8869710842899561456",
"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\"}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"9e1c8b228abddab4bee6ba3ce9ab13aaaac3bc72\", \"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-21-r11m\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"win-upload-perfetto\", \"buildnumber\": 1282, \"got_angle_revision\": \"0485634fe3f98cfe8976e7a820f73df65b75c286\", \"got_dawn_revision\": \"62442aceb0fb8af0c1edd07b5f18b4ec181b8fbf\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"9e1c8b228abddab4bee6ba3ce9ab13aaaac3bc72\", \"got_revision_cp\": \"refs/heads/master@{#804944}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"10121443c16deb0435be8ea61be54e3bee1b9855\", \"got_v8_revision\": \"3e2d6551eed76b7bdafb278b44c630965a840da8\", \"got_v8_revision_cp\": \"refs/heads/8.7.75@{#1}\", \"got_webrtc_revision\": \"9e9c8b7155d49c2f38b82fe9c9707181c57073c9\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32051}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"swarm_hashes\": {\"upload_trace_processor\": \"e0b6d69fe523d56bbd1528348f1c7d8a1a1a6e8b\"}, \"swarm_hashes_refs/heads/master(at){#804944}_without_patch\": {\"upload_trace_processor\": \"e0b6d69fe523d56bbd1528348f1c7d8a1a1a6e8b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_005c76091fbb2ec818a8c9df104002809214fca54c9e24e91c9996a9ec481d93_v2\", \"builder_1dd797315577e4a08e14fd318948b1febd6d4105daaa742ca85f0803368397d7_v2\", \"builder_1f9dfd66311a5f1a69d55bdf3e4ff1e1852ecaedf76e206f7744ee2fcf075686_v2\", \"builder_29be0a95c30e79ff7cd0e576819236851e848922c626f04e390e712e254045ed_v2\", \"builder_6973b5124d9fb1b1280c4c7705c15dfa58dc6e700fedc38960c680d6c81bc56c_v2\", \"builder_748a09fd5e3e61d5f24f5a632f1a2ca76e54f1550d20a83444873bb562a312d4_v2\", \"builder_a802005d1337bdd5570d8b075f9e6d72c645c798dccaf3ee22764e7cd0ffcd63_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-21-r11m\"], \"image\": [\"chrome-win10-1703-20083100-22dcea0839e\"], \"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\": [\"5513-ee65082\"], \"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": "1599581986421781",
"status": "COMPLETED",
"status_changed_ts": "1599581986751390",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/1282",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9035140905181683168",
"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/8869710842899561456/+/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:4e874fbb04179f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1599584895969220",
"url": "https://ci.chromium.org/b/8869710842899561456",
"utcnow_ts": "1599584896351539"
}
}
[17:08:16.361] Invocation finished in 55m0.537581486s with status FAILED