[01:15:48.214] New invocation is queued and will start shortly
[01:15:49.473] Starting the invocation (attempt 1)
[01:15:49.506] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:15:49.506] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:15:49.506] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9045525422680684800",
"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:9045525422680684800",
"scheduler_job_id:chromium/win-upload-perfetto",
"user_agent:luci-scheduler"
]
}
[01:15:50.332] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1589678149609812",
"id": "8880095360553822192",
"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": "1589678150144073",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/500",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9045525422680684800",
"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/8880095360553822192/+/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": "1589678150144218",
"url": "https://ci.chromium.org/b/8880095360553822192",
"utcnow_ts": "1589678150323824"
}
}
[01:15:50.332] Task URL: https://ci.chromium.org/b/8880095360553822192
[01:15:50.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:2:0) after 1m0s
[01:16:50.462] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:2:0)
[01:16:50.462] Timer tick, asking Buildbucket for the build status
[01:16:50.662] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:50.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:3:0) after 1m0s
[01:17:50.865] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:3:0)
[01:17:50.865] Timer tick, asking Buildbucket for the build status
[01:17:51.251] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:17:51.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:4:0) after 1m0s
[01:18:51.382] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:4:0)
[01:18:51.382] Timer tick, asking Buildbucket for the build status
[01:18:51.557] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:18:51.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:5:0) after 1m0s
[01:19:51.673] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:5:0)
[01:19:51.673] Timer tick, asking Buildbucket for the build status
[01:19:51.809] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:19:51.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:6:0) after 1m0s
[01:20:52.002] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:6:0)
[01:20:52.002] Timer tick, asking Buildbucket for the build status
[01:20:52.256] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:20:52.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:7:0) after 1m0s
[01:21:52.519] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:7:0)
[01:21:52.519] Timer tick, asking Buildbucket for the build status
[01:21:52.649] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:21:52.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:8:0) after 1m0s
[01:22:52.793] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:8:0)
[01:22:52.793] Timer tick, asking Buildbucket for the build status
[01:22:53.066] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:22:53.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:9:0) after 1m0s
[01:23:53.187] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:9:0)
[01:23:53.187] Timer tick, asking Buildbucket for the build status
[01:23:53.402] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:53.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:10:0) after 1m0s
[01:24:53.560] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:10:0)
[01:24:53.560] Timer tick, asking Buildbucket for the build status
[01:24:53.703] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:24:53.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:11:0) after 1m0s
[01:25:53.814] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:11:0)
[01:25:53.814] Timer tick, asking Buildbucket for the build status
[01:25:53.955] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:25:53.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:12:0) after 1m0s
[01:26:54.155] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:12:0)
[01:26:54.155] Timer tick, asking Buildbucket for the build status
[01:26:54.334] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:26:54.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:13:0) after 1m0s
[01:27:54.393] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:13:0)
[01:27:54.393] Timer tick, asking Buildbucket for the build status
[01:27:54.553] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:27:54.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:14:0) after 1m0s
[01:28:54.685] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:14:0)
[01:28:54.685] Timer tick, asking Buildbucket for the build status
[01:28:54.832] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:28:54.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:15:0) after 1m0s
[01:29:54.899] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:15:0)
[01:29:54.899] Timer tick, asking Buildbucket for the build status
[01:29:55.075] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:29:55.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:16:0) after 1m0s
[01:30:55.204] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:16:0)
[01:30:55.204] Timer tick, asking Buildbucket for the build status
[01:30:55.430] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:30:55.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:17:0) after 1m0s
[01:31:55.553] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:17:0)
[01:31:55.553] Timer tick, asking Buildbucket for the build status
[01:31:55.800] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:31:55.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:18:0) after 1m0s
[01:32:55.845] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:18:0)
[01:32:55.846] Timer tick, asking Buildbucket for the build status
[01:32:55.949] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:32:55.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:19:0) after 1m0s
[01:33:55.994] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:19:0)
[01:33:55.994] Timer tick, asking Buildbucket for the build status
[01:33:56.327] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:33:56.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:20:0) after 1m0s
[01:34:56.349] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:20:0)
[01:34:56.349] Timer tick, asking Buildbucket for the build status
[01:34:56.479] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:34:56.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:21:0) after 1m0s
[01:35:56.453] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:21:0)
[01:35:56.453] Timer tick, asking Buildbucket for the build status
[01:35:56.573] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:35:56.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:22:0) after 1m0s
[01:36:56.579] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:22:0)
[01:36:56.579] Timer tick, asking Buildbucket for the build status
[01:36:56.669] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:36:56.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:23:0) after 1m0s
[01:37:56.837] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:23:0)
[01:37:56.837] Timer tick, asking Buildbucket for the build status
[01:37:57.049] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:37:57.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:24:0) after 1m0s
[01:38:57.135] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:24:0)
[01:38:57.135] Timer tick, asking Buildbucket for the build status
[01:38:57.394] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:38:57.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:25:0) after 1m0s
[01:39:57.521] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:25:0)
[01:39:57.521] Timer tick, asking Buildbucket for the build status
[01:39:59.269] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:39:59.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:26:0) after 1m0s
[01:40:59.398] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:26:0)
[01:40:59.398] Timer tick, asking Buildbucket for the build status
[01:40:59.537] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:40:59.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:27:0) after 1m0s
[01:41:59.604] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:27:0)
[01:41:59.604] Timer tick, asking Buildbucket for the build status
[01:41:59.769] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:41:59.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:28:0) after 1m0s
[01:42:59.790] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:28:0)
[01:42:59.790] Timer tick, asking Buildbucket for the build status
[01:43:00.481] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:43:00.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:29:0) after 1m0s
[01:44:00.552] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:29:0)
[01:44:00.552] Timer tick, asking Buildbucket for the build status
[01:44:00.655] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:44:00.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:30:0) after 1m0s
[01:45:00.762] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:30:0)
[01:45:00.762] Timer tick, asking Buildbucket for the build status
[01:45:01.024] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:45:01.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:31:0) after 1m0s
[01:46:01.043] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:31:0)
[01:46:01.043] Timer tick, asking Buildbucket for the build status
[01:46:01.458] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:46:01.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:32:0) after 1m0s
[01:47:01.478] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:32:0)
[01:47:01.478] Timer tick, asking Buildbucket for the build status
[01:47:01.590] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:47:01.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:33:0) after 1m0s
[01:48:01.608] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:33:0)
[01:48:01.608] Timer tick, asking Buildbucket for the build status
[01:48:01.801] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:48:01.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:34:0) after 1m0s
[01:49:01.830] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:34:0)
[01:49:01.830] Timer tick, asking Buildbucket for the build status
[01:49:02.117] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:49:02.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:35:0) after 1m0s
[01:50:02.158] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:35:0)
[01:50:02.158] Timer tick, asking Buildbucket for the build status
[01:50:02.410] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:50:02.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:36:0) after 1m0s
[01:51:02.515] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:36:0)
[01:51:02.515] Timer tick, asking Buildbucket for the build status
[01:51:02.959] Build 8880095360553822192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:51:02.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:37:0) after 1m0s
[01:51:19.678] Received PubSub notification, asking Buildbucket for the build status
[01:51:20.067] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:03.078] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:37:0)
[01:52:03.078] Timer tick, asking Buildbucket for the build status
[01:52:03.511] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:03.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:39:0) after 1m0s
[01:53:03.529] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:39:0)
[01:53:03.529] Timer tick, asking Buildbucket for the build status
[01:53:03.720] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:03.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:40:0) after 1m0s
[01:54:03.780] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:40:0)
[01:54:03.780] Timer tick, asking Buildbucket for the build status
[01:54:03.932] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:03.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:41:0) after 1m0s
[01:55:03.986] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:41:0)
[01:55:03.986] Timer tick, asking Buildbucket for the build status
[01:55:04.374] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:04.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:42:0) after 1m0s
[01:56:04.397] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:42:0)
[01:56:04.397] Timer tick, asking Buildbucket for the build status
[01:56:04.546] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:04.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:43:0) after 1m0s
[01:57:04.572] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:43:0)
[01:57:04.572] Timer tick, asking Buildbucket for the build status
[01:57:04.713] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:04.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:44:0) after 1m0s
[01:58:04.734] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:44:0)
[01:58:04.734] Timer tick, asking Buildbucket for the build status
[01:58:05.067] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:05.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:45:0) after 1m0s
[01:59:05.252] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:45:0)
[01:59:05.252] Timer tick, asking Buildbucket for the build status
[01:59:05.413] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:05.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:46:0) after 1m0s
[02:00:05.491] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:46:0)
[02:00:05.491] Timer tick, asking Buildbucket for the build status
[02:00:05.630] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:05.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:47:0) after 1m0s
[02:01:05.664] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:47:0)
[02:01:05.664] Timer tick, asking Buildbucket for the build status
[02:01:05.802] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:05.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:48:0) after 1m0s
[02:02:05.826] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:48:0)
[02:02:05.826] Timer tick, asking Buildbucket for the build status
[02:02:06.059] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:06.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:49:0) after 1m0s
[02:03:06.091] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:49:0)
[02:03:06.091] Timer tick, asking Buildbucket for the build status
[02:03:06.337] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:06.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:50:0) after 1m0s
[02:04:06.341] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:50:0)
[02:04:06.341] Timer tick, asking Buildbucket for the build status
[02:04:06.455] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:06.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:51:0) after 1m0s
[02:05:06.529] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:51:0)
[02:05:06.529] Timer tick, asking Buildbucket for the build status
[02:05:06.728] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:06.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:52:0) after 1m0s
[02:06:06.749] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:52:0)
[02:06:06.749] Timer tick, asking Buildbucket for the build status
[02:06:06.870] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:06.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:53:0) after 1m0s
[02:07:06.902] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:53:0)
[02:07:06.902] Timer tick, asking Buildbucket for the build status
[02:07:07.033] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:07.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:54:0) after 1m0s
[02:08:07.088] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:54:0)
[02:08:07.088] Timer tick, asking Buildbucket for the build status
[02:08:07.434] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:07.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:55:0) after 1m0s
[02:09:07.537] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:55:0)
[02:09:07.537] Timer tick, asking Buildbucket for the build status
[02:09:07.655] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:07.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:56:0) after 1m0s
[02:10:07.747] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:56:0)
[02:10:07.747] Timer tick, asking Buildbucket for the build status
[02:10:07.901] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:07.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:57:0) after 1m0s
[02:11:08.073] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:57:0)
[02:11:08.073] Timer tick, asking Buildbucket for the build status
[02:11:08.216] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:08.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:58:0) after 1m0s
[02:12:08.363] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:58:0)
[02:12:08.363] Timer tick, asking Buildbucket for the build status
[02:12:08.884] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:08.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:59:0) after 1m0s
[02:13:08.934] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:59:0)
[02:13:08.934] Timer tick, asking Buildbucket for the build status
[02:13:10.400] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:10.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:60:0) after 1m0s
[02:14:10.430] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:60:0)
[02:14:10.430] Timer tick, asking Buildbucket for the build status
[02:14:10.580] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:10.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:61:0) after 1m0s
[02:15:10.606] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:61:0)
[02:15:10.606] Timer tick, asking Buildbucket for the build status
[02:15:10.748] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:10.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:62:0) after 1m0s
[02:16:10.795] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:62:0)
[02:16:10.795] Timer tick, asking Buildbucket for the build status
[02:16:11.604] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:11.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:63:0) after 1m0s
[02:17:11.628] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:63:0)
[02:17:11.628] Timer tick, asking Buildbucket for the build status
[02:17:11.704] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:11.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:64:0) after 1m0s
[02:18:11.689] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:64:0)
[02:18:11.689] Timer tick, asking Buildbucket for the build status
[02:18:11.962] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:11.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:65:0) after 1m0s
[02:19:12.003] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:65:0)
[02:19:12.003] Timer tick, asking Buildbucket for the build status
[02:19:12.348] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:12.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:66:0) after 1m0s
[02:20:12.371] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:66:0)
[02:20:12.371] Timer tick, asking Buildbucket for the build status
[02:20:13.024] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:13.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:67:0) after 1m0s
[02:21:13.049] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:67:0)
[02:21:13.049] Timer tick, asking Buildbucket for the build status
[02:21:13.193] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:13.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:68:0) after 1m0s
[02:22:13.225] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:68:0)
[02:22:13.225] Timer tick, asking Buildbucket for the build status
[02:22:13.345] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:13.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:69:0) after 1m0s
[02:23:13.414] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:69:0)
[02:23:13.414] Timer tick, asking Buildbucket for the build status
[02:23:13.554] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:13.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:70:0) after 1m0s
[02:24:13.622] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:70:0)
[02:24:13.622] Timer tick, asking Buildbucket for the build status
[02:24:13.794] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:13.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:71:0) after 1m0s
[02:25:13.866] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:71:0)
[02:25:13.866] Timer tick, asking Buildbucket for the build status
[02:25:14.317] Build 8880095360553822192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:14.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9045525422680684800:72:0) after 1m0s
[02:25:30.113] Received PubSub notification, asking Buildbucket for the build status
[02:25:30.244] Build 8880095360553822192: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:25:30.244] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1589682329473128",
"created_by": "project:chromium",
"created_ts": "1589678149609812",
"failure_reason": "BUILD_FAILURE",
"id": "8880095360553822192",
"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/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-16-o2s9\", \"buildername\": \"win-upload-perfetto\", \"buildnumber\": 500, \"got_angle_revision\": \"ab8d424a96210c0bc007e084cc8cc547c400563c\", \"got_dawn_revision\": \"da722adec9a914fd17519faebdd6c9f3934fb518\", \"got_nacl_revision\": \"f45e60c61cc1ae967e6f5fefc8f3e560e3b3c501\", \"got_revision\": \"396ada4d126af16a0c647f411db12a24541ebe35\", \"got_revision_cp\": \"refs/heads/master@{#769541}\", \"got_swarming_client_revision\": \"160b445a44e0daacf6f3f8570ca2707ec451f374\", \"got_swiftshader_revision\": \"57eb48aed36160c4876bc8310d9ca84d42ee9e2a\", \"got_v8_revision\": \"f868d50932314ee8da53d75797829c5a5b969d23\", \"got_v8_revision_cp\": \"refs/heads/8.5.6@{#1}\", \"got_webrtc_revision\": \"4515a55eed39d5d3440be2420cfcebd96576a43d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31280}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"swarm_hashes\": {\"upload_trace_processor\": \"854100d38e8845a577a986321e13f8ad174fb9f4\"}, \"swarm_hashes_refs/heads/master(at){#769541}_without_patch\": {\"upload_trace_processor\": \"854100d38e8845a577a986321e13f8ad174fb9f4\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-8-16-o2s9\"], \"image\": [\"chrome-win10-1703-20042500-5e6af220c3f\"], \"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\": [\"5088-a734129\"], \"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": "1589680278297264",
"status": "COMPLETED",
"status_changed_ts": "1589680279181806",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/500",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9045525422680684800",
"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/8880095360553822192/+/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:4c39051c28341110",
"user_agent:luci-scheduler"
],
"updated_ts": "1589682329591057",
"url": "https://ci.chromium.org/b/8880095360553822192",
"utcnow_ts": "1589682330234651"
}
}
[02:25:30.244] Invocation finished in 1h9m42.047065664s with status FAILED