[11:27:25.281] New invocation is queued and will start shortly
[11:27:26.375] Starting the invocation (attempt 1)
[11:27:26.414] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:27:26.415] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:27:26.415] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029088892098630192",
"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:9029088892098630192",
"scheduler_job_id:chromium/win-upload-perfetto",
"user_agent:luci-scheduler"
]
}
[11:27:28.359] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1605353247794350",
"id": "8863658828799900432",
"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": "1605353248207046",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/1745",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9029088892098630192",
"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/8863658828799900432/+/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": "1605353248207159",
"url": "https://ci.chromium.org/b/8863658828799900432",
"utcnow_ts": "1605353248343908"
}
}
[11:27:28.359] Task URL: https://ci.chromium.org/b/8863658828799900432
[11:27:28.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:2:0) after 1m0s
[11:28:28.418] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:2:0)
[11:28:28.418] Timer tick, asking Buildbucket for the build status
[11:28:28.508] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:28:28.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:3:0) after 1m0s
[11:29:28.531] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:3:0)
[11:29:28.531] Timer tick, asking Buildbucket for the build status
[11:29:28.616] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:29:28.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:4:0) after 1m0s
[11:30:28.670] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:4:0)
[11:30:28.670] Timer tick, asking Buildbucket for the build status
[11:30:28.906] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:30:28.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:5:0) after 1m0s
[11:31:28.959] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:5:0)
[11:31:28.959] Timer tick, asking Buildbucket for the build status
[11:31:29.025] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:31:29.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:6:0) after 1m0s
[11:32:29.091] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:6:0)
[11:32:29.091] Timer tick, asking Buildbucket for the build status
[11:32:29.687] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:32:29.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:7:0) after 1m0s
[11:33:29.680] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:7:0)
[11:33:29.680] Timer tick, asking Buildbucket for the build status
[11:33:29.831] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:33:29.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:8:0) after 1m0s
[11:34:29.898] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:8:0)
[11:34:29.898] Timer tick, asking Buildbucket for the build status
[11:34:30.177] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:34:30.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:9:0) after 1m0s
[11:35:30.185] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:9:0)
[11:35:30.185] Timer tick, asking Buildbucket for the build status
[11:35:30.262] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:35:30.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:10:0) after 1m0s
[11:36:30.331] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:10:0)
[11:36:30.331] Timer tick, asking Buildbucket for the build status
[11:36:30.404] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:36:30.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:11:0) after 1m0s
[11:37:30.421] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:11:0)
[11:37:30.421] Timer tick, asking Buildbucket for the build status
[11:37:30.515] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:37:30.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:12:0) after 1m0s
[11:38:31.881] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:12:0)
[11:38:31.881] Timer tick, asking Buildbucket for the build status
[11:38:32.255] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:38:32.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:13:0) after 1m0s
[11:39:32.274] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:13:0)
[11:39:32.274] Timer tick, asking Buildbucket for the build status
[11:39:32.393] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:39:32.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:14:0) after 1m0s
[11:40:32.413] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:14:0)
[11:40:32.413] Timer tick, asking Buildbucket for the build status
[11:40:32.523] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:40:32.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:15:0) after 1m0s
[11:41:32.561] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:15:0)
[11:41:32.561] Timer tick, asking Buildbucket for the build status
[11:41:32.708] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:41:32.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:16:0) after 1m0s
[11:42:32.737] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:16:0)
[11:42:32.737] Timer tick, asking Buildbucket for the build status
[11:42:33.004] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:42:33.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:17:0) after 1m0s
[11:43:33.072] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:17:0)
[11:43:33.072] Timer tick, asking Buildbucket for the build status
[11:43:33.147] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:43:33.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:18:0) after 1m0s
[11:44:33.279] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:18:0)
[11:44:33.279] Timer tick, asking Buildbucket for the build status
[11:44:33.359] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:44:33.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:19:0) after 1m0s
[11:45:33.504] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:19:0)
[11:45:33.504] Timer tick, asking Buildbucket for the build status
[11:45:33.569] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:45:33.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:20:0) after 1m0s
[11:46:33.672] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:20:0)
[11:46:33.672] Timer tick, asking Buildbucket for the build status
[11:46:33.813] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:46:33.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:21:0) after 1m0s
[11:47:33.913] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:21:0)
[11:47:33.913] Timer tick, asking Buildbucket for the build status
[11:47:34.057] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:47:34.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:22:0) after 1m0s
[11:48:34.118] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:22:0)
[11:48:34.118] Timer tick, asking Buildbucket for the build status
[11:48:34.192] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:48:34.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:23:0) after 1m0s
[11:49:34.214] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:23:0)
[11:49:34.214] Timer tick, asking Buildbucket for the build status
[11:49:34.308] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:49:34.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:24:0) after 1m0s
[11:50:34.328] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:24:0)
[11:50:34.328] Timer tick, asking Buildbucket for the build status
[11:50:34.509] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:50:34.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:25:0) after 1m0s
[11:51:34.504] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:25:0)
[11:51:34.504] Timer tick, asking Buildbucket for the build status
[11:51:34.588] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:51:34.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:26:0) after 1m0s
[11:52:34.607] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:26:0)
[11:52:34.607] Timer tick, asking Buildbucket for the build status
[11:52:34.726] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:52:34.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:27:0) after 1m0s
[11:53:34.747] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:27:0)
[11:53:34.747] Timer tick, asking Buildbucket for the build status
[11:53:34.829] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:53:34.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:28:0) after 1m0s
[11:54:34.887] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:28:0)
[11:54:34.887] Timer tick, asking Buildbucket for the build status
[11:54:34.954] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:54:34.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:29:0) after 1m0s
[11:55:35.076] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:29:0)
[11:55:35.076] Timer tick, asking Buildbucket for the build status
[11:55:35.155] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:55:35.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:30:0) after 1m0s
[11:56:35.226] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:30:0)
[11:56:35.226] Timer tick, asking Buildbucket for the build status
[11:56:35.293] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:56:35.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:31:0) after 1m0s
[11:57:35.443] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:31:0)
[11:57:35.443] Timer tick, asking Buildbucket for the build status
[11:57:35.538] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:57:35.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:32:0) after 1m0s
[11:58:35.788] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:32:0)
[11:58:35.788] Timer tick, asking Buildbucket for the build status
[11:58:35.858] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:58:35.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:33:0) after 1m0s
[11:59:36.023] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:33:0)
[11:59:36.023] Timer tick, asking Buildbucket for the build status
[11:59:36.295] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:59:36.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:34:0) after 1m0s
[12:00:36.422] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:34:0)
[12:00:36.422] Timer tick, asking Buildbucket for the build status
[12:00:36.623] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:00:36.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:35:0) after 1m0s
[12:01:36.638] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:35:0)
[12:01:36.638] Timer tick, asking Buildbucket for the build status
[12:01:37.780] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:01:37.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:36:0) after 1m0s
[12:02:38.345] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:36:0)
[12:02:38.345] Timer tick, asking Buildbucket for the build status
[12:02:38.463] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:02:38.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:37:0) after 1m0s
[12:03:38.688] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:37:0)
[12:03:38.688] Timer tick, asking Buildbucket for the build status
[12:03:38.834] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:03:38.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:38:0) after 1m0s
[12:04:39.011] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:38:0)
[12:04:39.011] Timer tick, asking Buildbucket for the build status
[12:04:39.209] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:04:39.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:39:0) after 1m0s
[12:05:39.318] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:39:0)
[12:05:39.318] Timer tick, asking Buildbucket for the build status
[12:05:39.510] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:05:39.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:40:0) after 1m0s
[12:06:39.606] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:40:0)
[12:06:39.606] Timer tick, asking Buildbucket for the build status
[12:06:39.684] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:06:39.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:41:0) after 1m0s
[12:07:40.055] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:41:0)
[12:07:40.055] Timer tick, asking Buildbucket for the build status
[12:07:40.147] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:07:40.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:42:0) after 1m0s
[12:08:40.407] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:42:0)
[12:08:40.407] Timer tick, asking Buildbucket for the build status
[12:08:40.501] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:08:40.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:43:0) after 1m0s
[12:09:40.643] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:43:0)
[12:09:40.643] Timer tick, asking Buildbucket for the build status
[12:09:40.708] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:09:40.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:44:0) after 1m0s
[12:10:40.828] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:44:0)
[12:10:40.828] Timer tick, asking Buildbucket for the build status
[12:10:40.924] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:10:40.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:45:0) after 1m0s
[12:11:40.949] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:45:0)
[12:11:40.949] Timer tick, asking Buildbucket for the build status
[12:11:43.195] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:11:43.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:46:0) after 1m0s
[12:12:43.216] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:46:0)
[12:12:43.216] Timer tick, asking Buildbucket for the build status
[12:12:43.668] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:12:43.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:47:0) after 1m0s
[12:13:43.692] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:47:0)
[12:13:43.692] Timer tick, asking Buildbucket for the build status
[12:13:43.775] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:13:43.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:48:0) after 1m0s
[12:14:43.872] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:48:0)
[12:14:43.872] Timer tick, asking Buildbucket for the build status
[12:14:43.955] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:14:43.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:49:0) after 1m0s
[12:15:44.045] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:49:0)
[12:15:44.045] Timer tick, asking Buildbucket for the build status
[12:15:44.119] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:15:44.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:50:0) after 1m0s
[12:16:44.145] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:50:0)
[12:16:44.145] Timer tick, asking Buildbucket for the build status
[12:16:46.536] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:16:46.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:51:0) after 1m0s
[12:17:46.659] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:51:0)
[12:17:46.659] Timer tick, asking Buildbucket for the build status
[12:17:46.770] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:17:46.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:52:0) after 1m0s
[12:18:46.851] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:52:0)
[12:18:46.852] Timer tick, asking Buildbucket for the build status
[12:18:47.170] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:18:47.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:53:0) after 1m0s
[12:19:47.292] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:53:0)
[12:19:47.292] Timer tick, asking Buildbucket for the build status
[12:19:47.368] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:19:47.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:54:0) after 1m0s
[12:20:47.448] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:54:0)
[12:20:47.448] Timer tick, asking Buildbucket for the build status
[12:20:47.523] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:20:47.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:55:0) after 1m0s
[12:21:47.601] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:55:0)
[12:21:47.601] Timer tick, asking Buildbucket for the build status
[12:21:47.684] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:21:47.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:56:0) after 1m0s
[12:22:47.868] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:56:0)
[12:22:47.868] Timer tick, asking Buildbucket for the build status
[12:22:47.981] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:22:47.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:57:0) after 1m0s
[12:23:48.076] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:57:0)
[12:23:48.076] Timer tick, asking Buildbucket for the build status
[12:23:48.167] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:23:48.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:58:0) after 1m0s
[12:24:48.217] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:58:0)
[12:24:48.217] Timer tick, asking Buildbucket for the build status
[12:24:48.303] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:24:48.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:59:0) after 1m0s
[12:25:48.445] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:59:0)
[12:25:48.445] Timer tick, asking Buildbucket for the build status
[12:25:48.543] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:25:48.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:60:0) after 1m0s
[12:26:48.728] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:60:0)
[12:26:48.728] Timer tick, asking Buildbucket for the build status
[12:26:48.814] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:26:48.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:61:0) after 1m0s
[12:27:48.886] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:61:0)
[12:27:48.886] Timer tick, asking Buildbucket for the build status
[12:27:52.353] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:27:52.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:62:0) after 1m0s
[12:28:52.378] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:62:0)
[12:28:52.378] Timer tick, asking Buildbucket for the build status
[12:28:52.517] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:28:52.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:63:0) after 1m0s
[12:29:52.537] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:63:0)
[12:29:52.537] Timer tick, asking Buildbucket for the build status
[12:29:52.660] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:29:52.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:64:0) after 1m0s
[12:30:52.787] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:64:0)
[12:30:52.787] Timer tick, asking Buildbucket for the build status
[12:30:52.925] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:30:52.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:65:0) after 1m0s
[12:31:52.946] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:65:0)
[12:31:52.946] Timer tick, asking Buildbucket for the build status
[12:31:53.112] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:31:53.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:66:0) after 1m0s
[12:32:53.136] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:66:0)
[12:32:53.136] Timer tick, asking Buildbucket for the build status
[12:32:53.323] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:32:53.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:67:0) after 1m0s
[12:33:53.350] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:67:0)
[12:33:53.350] Timer tick, asking Buildbucket for the build status
[12:33:53.426] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:33:53.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:68:0) after 1m0s
[12:34:53.501] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:68:0)
[12:34:53.501] Timer tick, asking Buildbucket for the build status
[12:34:53.615] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:34:53.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:69:0) after 1m0s
[12:35:53.653] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:69:0)
[12:35:53.653] Timer tick, asking Buildbucket for the build status
[12:35:53.877] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:35:53.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:70:0) after 1m0s
[12:36:53.900] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:70:0)
[12:36:53.900] Timer tick, asking Buildbucket for the build status
[12:36:53.968] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:36:53.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:71:0) after 1m0s
[12:37:54.049] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:71:0)
[12:37:54.049] Timer tick, asking Buildbucket for the build status
[12:37:54.156] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:37:54.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:72:0) after 1m0s
[12:38:54.238] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:72:0)
[12:38:54.238] Timer tick, asking Buildbucket for the build status
[12:38:54.498] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:38:54.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:73:0) after 1m0s
[12:39:54.633] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:73:0)
[12:39:54.633] Timer tick, asking Buildbucket for the build status
[12:39:54.710] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:39:54.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:74:0) after 1m0s
[12:40:54.746] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:74:0)
[12:40:54.746] Timer tick, asking Buildbucket for the build status
[12:40:54.824] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:40:54.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:75:0) after 1m0s
[12:41:54.857] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:75:0)
[12:41:54.857] Timer tick, asking Buildbucket for the build status
[12:41:54.927] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:41:54.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:76:0) after 1m0s
[12:42:54.952] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:76:0)
[12:42:54.952] Timer tick, asking Buildbucket for the build status
[12:42:55.272] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:42:55.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:77:0) after 1m0s
[12:43:55.298] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:77:0)
[12:43:55.298] Timer tick, asking Buildbucket for the build status
[12:43:55.368] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:43:55.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:78:0) after 1m0s
[12:44:55.395] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:78:0)
[12:44:55.395] Timer tick, asking Buildbucket for the build status
[12:44:55.525] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:44:55.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:79:0) after 1m0s
[12:45:55.549] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:79:0)
[12:45:55.549] Timer tick, asking Buildbucket for the build status
[12:45:55.658] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:45:55.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:80:0) after 1m0s
[12:46:55.685] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:80:0)
[12:46:55.685] Timer tick, asking Buildbucket for the build status
[12:46:55.827] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:46:55.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:81:0) after 1m0s
[12:47:55.848] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:81:0)
[12:47:55.848] Timer tick, asking Buildbucket for the build status
[12:47:55.947] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:47:55.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:82:0) after 1m0s
[12:48:55.974] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:82:0)
[12:48:55.974] Timer tick, asking Buildbucket for the build status
[12:48:56.125] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:48:56.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:83:0) after 1m0s
[12:49:56.789] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:83:0)
[12:49:56.789] Timer tick, asking Buildbucket for the build status
[12:49:57.046] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:49:57.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:84:0) after 1m0s
[12:50:57.523] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:84:0)
[12:50:57.523] Timer tick, asking Buildbucket for the build status
[12:50:57.598] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:50:57.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:85:0) after 1m0s
[12:51:57.598] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:85:0)
[12:51:57.598] Timer tick, asking Buildbucket for the build status
[12:51:57.768] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:51:57.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:86:0) after 1m0s
[12:52:57.790] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:86:0)
[12:52:57.790] Timer tick, asking Buildbucket for the build status
[12:52:57.888] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:52:57.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:87:0) after 1m0s
[12:53:57.942] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:87:0)
[12:53:57.942] Timer tick, asking Buildbucket for the build status
[12:53:58.122] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:53:58.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:88:0) after 1m0s
[12:54:58.225] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:88:0)
[12:54:58.225] Timer tick, asking Buildbucket for the build status
[12:54:58.300] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:54:58.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:89:0) after 1m0s
[12:55:58.309] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:89:0)
[12:55:58.309] Timer tick, asking Buildbucket for the build status
[12:55:58.403] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:55:58.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:90:0) after 1m0s
[12:56:58.446] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:90:0)
[12:56:58.446] Timer tick, asking Buildbucket for the build status
[12:56:58.527] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:56:58.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:91:0) after 1m0s
[12:57:58.548] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:91:0)
[12:57:58.548] Timer tick, asking Buildbucket for the build status
[12:57:58.658] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:57:58.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:92:0) after 1m0s
[12:58:58.743] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:92:0)
[12:58:58.743] Timer tick, asking Buildbucket for the build status
[12:58:58.811] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:58:58.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:93:0) after 1m0s
[12:59:58.846] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:93:0)
[12:59:58.846] Timer tick, asking Buildbucket for the build status
[12:59:58.925] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:59:58.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:94:0) after 1m0s
[13:00:58.966] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:94:0)
[13:00:58.966] Timer tick, asking Buildbucket for the build status
[13:00:59.117] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:00:59.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:95:0) after 1m0s
[13:01:59.208] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:95:0)
[13:01:59.208] Timer tick, asking Buildbucket for the build status
[13:02:00.696] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:02:00.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:96:0) after 1m0s
[13:03:02.165] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:96:0)
[13:03:02.165] Timer tick, asking Buildbucket for the build status
[13:03:02.291] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:03:02.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:97:0) after 1m0s
[13:04:02.502] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:97:0)
[13:04:02.502] Timer tick, asking Buildbucket for the build status
[13:04:02.670] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:04:02.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:98:0) after 1m0s
[13:05:02.694] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:98:0)
[13:05:02.694] Timer tick, asking Buildbucket for the build status
[13:05:02.822] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:05:02.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:99:0) after 1m0s
[13:06:02.827] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:99:0)
[13:06:02.827] Timer tick, asking Buildbucket for the build status
[13:06:02.918] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:06:02.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:100:0) after 1m0s
[13:07:02.946] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:100:0)
[13:07:02.946] Timer tick, asking Buildbucket for the build status
[13:07:03.020] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:07:03.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:101:0) after 1m0s
[13:08:03.059] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:101:0)
[13:08:03.059] Timer tick, asking Buildbucket for the build status
[13:08:03.320] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:08:03.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:102:0) after 1m0s
[13:09:03.355] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:102:0)
[13:09:03.355] Timer tick, asking Buildbucket for the build status
[13:09:03.604] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:09:03.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:103:0) after 1m0s
[13:10:03.691] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:103:0)
[13:10:03.691] Timer tick, asking Buildbucket for the build status
[13:10:03.814] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:10:03.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:104:0) after 1m0s
[13:11:03.882] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:104:0)
[13:11:03.882] Timer tick, asking Buildbucket for the build status
[13:11:04.023] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:11:04.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:105:0) after 1m0s
[13:12:04.082] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:105:0)
[13:12:04.082] Timer tick, asking Buildbucket for the build status
[13:12:04.158] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:12:04.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:106:0) after 1m0s
[13:13:04.269] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:106:0)
[13:13:04.269] Timer tick, asking Buildbucket for the build status
[13:13:04.630] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:13:04.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:107:0) after 1m0s
[13:14:04.773] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:107:0)
[13:14:04.773] Timer tick, asking Buildbucket for the build status
[13:14:04.899] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:14:04.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:108:0) after 1m0s
[13:15:04.979] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:108:0)
[13:15:04.979] Timer tick, asking Buildbucket for the build status
[13:15:05.061] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:15:05.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:109:0) after 1m0s
[13:16:05.095] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:109:0)
[13:16:05.095] Timer tick, asking Buildbucket for the build status
[13:16:05.185] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:16:05.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:110:0) after 1m0s
[13:17:05.208] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:110:0)
[13:17:05.208] Timer tick, asking Buildbucket for the build status
[13:17:05.616] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:17:05.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:111:0) after 1m0s
[13:18:05.651] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:111:0)
[13:18:05.651] Timer tick, asking Buildbucket for the build status
[13:18:05.820] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:18:05.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:112:0) after 1m0s
[13:19:05.945] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:112:0)
[13:19:05.945] Timer tick, asking Buildbucket for the build status
[13:19:06.021] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:19:06.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:113:0) after 1m0s
[13:20:06.105] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:113:0)
[13:20:06.105] Timer tick, asking Buildbucket for the build status
[13:20:06.231] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:20:06.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:114:0) after 1m0s
[13:21:06.281] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:114:0)
[13:21:06.281] Timer tick, asking Buildbucket for the build status
[13:21:06.479] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:21:06.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:115:0) after 1m0s
[13:22:06.502] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:115:0)
[13:22:06.502] Timer tick, asking Buildbucket for the build status
[13:22:06.607] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:22:06.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:116:0) after 1m0s
[13:23:06.609] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:116:0)
[13:23:06.612] Timer tick, asking Buildbucket for the build status
[13:23:06.729] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:23:06.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:117:0) after 1m0s
[13:24:06.763] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:117:0)
[13:24:06.763] Timer tick, asking Buildbucket for the build status
[13:24:07.198] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:24:07.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:118:0) after 1m0s
[13:25:07.247] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:118:0)
[13:25:07.247] Timer tick, asking Buildbucket for the build status
[13:25:07.337] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:25:07.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:119:0) after 1m0s
[13:26:07.369] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:119:0)
[13:26:07.369] Timer tick, asking Buildbucket for the build status
[13:26:07.449] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:26:07.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:120:0) after 1m0s
[13:27:07.541] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:120:0)
[13:27:07.541] Timer tick, asking Buildbucket for the build status
[13:27:07.624] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:27:07.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:121:0) after 1m0s
[13:28:07.744] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:121:0)
[13:28:07.744] Timer tick, asking Buildbucket for the build status
[13:28:07.841] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:28:07.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:122:0) after 1m0s
[13:29:07.868] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:122:0)
[13:29:07.868] Timer tick, asking Buildbucket for the build status
[13:29:07.953] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:29:07.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:123:0) after 1m0s
[13:30:08.032] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:123:0)
[13:30:08.032] Timer tick, asking Buildbucket for the build status
[13:30:08.535] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:30:08.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:124:0) after 1m0s
[13:31:08.653] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:124:0)
[13:31:08.653] Timer tick, asking Buildbucket for the build status
[13:31:08.785] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:31:08.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:125:0) after 1m0s
[13:32:08.853] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:125:0)
[13:32:08.853] Timer tick, asking Buildbucket for the build status
[13:32:08.953] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:32:08.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:126:0) after 1m0s
[13:33:09.071] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:126:0)
[13:33:09.071] Timer tick, asking Buildbucket for the build status
[13:33:09.183] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:33:09.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:127:0) after 1m0s
[13:34:09.242] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:127:0)
[13:34:09.242] Timer tick, asking Buildbucket for the build status
[13:34:09.331] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:34:09.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:128:0) after 1m0s
[13:35:09.409] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:128:0)
[13:35:09.409] Timer tick, asking Buildbucket for the build status
[13:35:09.521] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:35:09.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:129:0) after 1m0s
[13:36:09.546] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:129:0)
[13:36:09.546] Timer tick, asking Buildbucket for the build status
[13:36:09.622] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:36:09.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:130:0) after 1m0s
[13:37:09.655] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:130:0)
[13:37:09.655] Timer tick, asking Buildbucket for the build status
[13:37:09.730] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:37:09.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:131:0) after 1m0s
[13:38:09.739] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:131:0)
[13:38:09.739] Timer tick, asking Buildbucket for the build status
[13:38:09.929] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:38:09.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:132:0) after 1m0s
[13:39:09.954] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:132:0)
[13:39:09.954] Timer tick, asking Buildbucket for the build status
[13:39:10.022] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:39:10.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:133:0) after 1m0s
[13:40:10.048] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:133:0)
[13:40:10.048] Timer tick, asking Buildbucket for the build status
[13:40:10.118] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:40:10.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:134:0) after 1m0s
[13:41:10.127] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:134:0)
[13:41:10.127] Timer tick, asking Buildbucket for the build status
[13:41:10.521] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:41:10.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:135:0) after 1m0s
[13:42:10.552] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:135:0)
[13:42:10.552] Timer tick, asking Buildbucket for the build status
[13:42:10.644] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:42:10.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:136:0) after 1m0s
[13:43:10.673] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:136:0)
[13:43:10.673] Timer tick, asking Buildbucket for the build status
[13:43:10.830] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:43:10.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:137:0) after 1m0s
[13:44:10.890] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:137:0)
[13:44:10.890] Timer tick, asking Buildbucket for the build status
[13:44:10.978] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:44:10.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:138:0) after 1m0s
[13:45:11.024] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:138:0)
[13:45:11.024] Timer tick, asking Buildbucket for the build status
[13:45:11.109] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:45:11.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:139:0) after 1m0s
[13:46:11.212] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:139:0)
[13:46:11.212] Timer tick, asking Buildbucket for the build status
[13:46:11.302] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:46:11.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:140:0) after 1m0s
[13:47:11.327] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:140:0)
[13:47:11.327] Timer tick, asking Buildbucket for the build status
[13:47:12.878] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:47:12.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:141:0) after 1m0s
[13:48:12.933] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:141:0)
[13:48:12.933] Timer tick, asking Buildbucket for the build status
[13:48:13.032] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:48:13.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:142:0) after 1m0s
[13:49:13.063] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:142:0)
[13:49:13.063] Timer tick, asking Buildbucket for the build status
[13:49:13.132] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:49:13.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:143:0) after 1m0s
[13:50:13.182] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:143:0)
[13:50:13.182] Timer tick, asking Buildbucket for the build status
[13:50:13.315] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:50:13.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:144:0) after 1m0s
[13:51:13.338] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:144:0)
[13:51:13.338] Timer tick, asking Buildbucket for the build status
[13:51:13.401] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:51:13.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:145:0) after 1m0s
[13:52:13.990] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:145:0)
[13:52:13.990] Timer tick, asking Buildbucket for the build status
[13:52:14.191] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:52:14.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:146:0) after 1m0s
[13:53:14.285] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:146:0)
[13:53:14.285] Timer tick, asking Buildbucket for the build status
[13:53:14.449] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:53:14.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:147:0) after 1m0s
[13:54:14.504] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:147:0)
[13:54:14.504] Timer tick, asking Buildbucket for the build status
[13:54:14.660] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:54:14.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:148:0) after 1m0s
[13:55:14.970] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:148:0)
[13:55:14.970] Timer tick, asking Buildbucket for the build status
[13:55:15.045] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:55:15.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:149:0) after 1m0s
[13:56:15.291] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:149:0)
[13:56:15.291] Timer tick, asking Buildbucket for the build status
[13:56:15.406] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:56:15.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:150:0) after 1m0s
[13:57:15.535] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:150:0)
[13:57:15.535] Timer tick, asking Buildbucket for the build status
[13:57:15.614] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:57:15.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:151:0) after 1m0s
[13:58:15.759] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:151:0)
[13:58:15.759] Timer tick, asking Buildbucket for the build status
[13:58:15.927] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:58:15.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:152:0) after 1m0s
[13:59:16.839] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:152:0)
[13:59:16.839] Timer tick, asking Buildbucket for the build status
[13:59:16.924] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:59:16.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:153:0) after 1m0s
[14:00:17.156] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:153:0)
[14:00:17.156] Timer tick, asking Buildbucket for the build status
[14:00:17.268] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:00:17.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:154:0) after 1m0s
[14:01:17.446] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:154:0)
[14:01:17.446] Timer tick, asking Buildbucket for the build status
[14:01:17.587] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:01:17.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:155:0) after 1m0s
[14:02:17.750] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:155:0)
[14:02:17.750] Timer tick, asking Buildbucket for the build status
[14:02:17.943] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:02:17.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:156:0) after 1m0s
[14:03:18.052] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:156:0)
[14:03:18.052] Timer tick, asking Buildbucket for the build status
[14:03:18.157] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:03:18.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:157:0) after 1m0s
[14:04:18.289] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:157:0)
[14:04:18.289] Timer tick, asking Buildbucket for the build status
[14:04:18.684] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:04:18.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:158:0) after 1m0s
[14:05:18.770] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:158:0)
[14:05:18.770] Timer tick, asking Buildbucket for the build status
[14:05:19.074] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:05:19.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:159:0) after 1m0s
[14:06:19.117] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:159:0)
[14:06:19.117] Timer tick, asking Buildbucket for the build status
[14:06:19.229] Build 8863658828799900432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:06:19.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:160:0) after 1m0s
[14:06:24.766] Received PubSub notification, asking Buildbucket for the build status
[14:06:24.830] Build 8863658828799900432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:19.365] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:160:0)
[14:07:19.365] Timer tick, asking Buildbucket for the build status
[14:07:19.502] Build 8863658828799900432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:19.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:162:0) after 1m0s
[14:08:19.772] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:162:0)
[14:08:19.772] Timer tick, asking Buildbucket for the build status
[14:08:19.919] Build 8863658828799900432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:19.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:163:0) after 1m0s
[14:09:20.072] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:163:0)
[14:09:20.072] Timer tick, asking Buildbucket for the build status
[14:09:20.152] Build 8863658828799900432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:20.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:164:0) after 1m0s
[14:10:20.177] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:164:0)
[14:10:20.177] Timer tick, asking Buildbucket for the build status
[14:10:21.545] Build 8863658828799900432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:21.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:165:0) after 1m0s
[14:11:21.999] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:165:0)
[14:11:21.999] Timer tick, asking Buildbucket for the build status
[14:11:22.269] Build 8863658828799900432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:22.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:166:0) after 1m0s
[14:12:22.399] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:166:0)
[14:12:22.400] Timer tick, asking Buildbucket for the build status
[14:12:22.478] Build 8863658828799900432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:22.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:167:0) after 1m0s
[14:13:22.685] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:167:0)
[14:13:22.685] Timer tick, asking Buildbucket for the build status
[14:13:22.783] Build 8863658828799900432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:22.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:168:0) after 1m0s
[14:14:22.863] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:168:0)
[14:14:22.863] Timer tick, asking Buildbucket for the build status
[14:14:22.932] Build 8863658828799900432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:22.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:169:0) after 1m0s
[14:15:23.044] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:169:0)
[14:15:23.044] Timer tick, asking Buildbucket for the build status
[14:15:23.121] Build 8863658828799900432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:23.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:170:0) after 1m0s
[14:16:23.275] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:170:0)
[14:16:23.275] Timer tick, asking Buildbucket for the build status
[14:16:23.390] Build 8863658828799900432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:23.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:171:0) after 1m0s
[14:17:23.443] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:171:0)
[14:17:23.443] Timer tick, asking Buildbucket for the build status
[14:17:23.699] Build 8863658828799900432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:23.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:172:0) after 1m0s
[14:18:23.750] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:172:0)
[14:18:23.750] Timer tick, asking Buildbucket for the build status
[14:18:23.891] Build 8863658828799900432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:23.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:173:0) after 1m0s
[14:19:23.922] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:173:0)
[14:19:23.922] Timer tick, asking Buildbucket for the build status
[14:19:24.109] Build 8863658828799900432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:24.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:174:0) after 1m0s
[14:20:24.227] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:174:0)
[14:20:24.228] Timer tick, asking Buildbucket for the build status
[14:20:25.418] Build 8863658828799900432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:25.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:175:0) after 1m0s
[14:21:27.088] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:175:0)
[14:21:27.088] Timer tick, asking Buildbucket for the build status
[14:21:27.410] Build 8863658828799900432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:27.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9029088892098630192:176:0) after 1m0s
[14:21:46.239] Received PubSub notification, asking Buildbucket for the build status
[14:21:46.310] Build 8863658828799900432: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[14:21:46.310] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1605363705918480",
"created_by": "project:chromium",
"created_ts": "1605353247794350",
"failure_reason": "BUILD_FAILURE",
"id": "8863658828799900432",
"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\": \"8b95281b164ea70e1738c9fa3a28aa9eae8b094e\", \"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-0-hemk\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"win-upload-perfetto\", \"buildnumber\": 1745, \"got_angle_revision\": \"f46e6201b40bdee3e0b79d5d97660fc302fd76f5\", \"got_dawn_revision\": \"464aaeb55864832f338717e216324123438b8508\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"8b95281b164ea70e1738c9fa3a28aa9eae8b094e\", \"got_revision_cp\": \"refs/heads/master@{#827608}\", \"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){#827608}_without_patch\": {\"upload_trace_processor\": \"d8541257b4650b72e8af61681494cf579b02f0ba\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_024bff123481647a20c7dafe5897c9112095480a6680ca28e6413691579f95f9_v2\", \"builder_572c7d4336ecb7e9d9870c0b7d73eb5c4ec9b5267736155cf1f0727554d722db_v2\", \"builder_6b755bbaffc280fb83e7e303317e4888b2acb597c551cb7db892c262f1cf5030_v2\", \"builder_7e7f4ebc5094421a46baff10228f22baf4d4d757ab94366d4bf23e3f4dc4e562_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-0-hemk\"], \"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": "1605362783889980",
"status": "COMPLETED",
"status_changed_ts": "1605362784368335",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/1745",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9029088892098630192",
"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/8863658828799900432/+/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:4fdf54203a675d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1605363705979492",
"url": "https://ci.chromium.org/b/8863658828799900432",
"utcnow_ts": "1605363706301429"
}
}
[14:21:46.310] Invocation finished in 2h54m21.046338727s with status FAILED