[04:36:50.425] New invocation is queued and will start shortly
[04:36:51.630] Starting the invocation (attempt 1)
[04:36:51.681] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:36:51.681] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:36:51.682] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028933529684793952",
"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:9028933529684793952",
"scheduler_job_id:chromium/win-upload-perfetto",
"user_agent:luci-scheduler"
]
}
[04:36:52.516] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605501411777825",
"id": "8863503467602394672",
"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": "1605501412377176",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/1753",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9028933529684793952",
"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/8863503467602394672/+/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": "1605501412377331",
"url": "https://ci.chromium.org/b/8863503467602394672",
"utcnow_ts": "1605501412506026"
}
}
[04:36:52.516] Task URL: https://ci.chromium.org/b/8863503467602394672
[04:36:52.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:2:0) after 1m0s
[04:37:52.537] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:2:0)
[04:37:52.537] Timer tick, asking Buildbucket for the build status
[04:37:52.710] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:37:52.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:3:0) after 1m0s
[04:38:52.714] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:3:0)
[04:38:52.714] Timer tick, asking Buildbucket for the build status
[04:38:52.791] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:38:52.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:4:0) after 1m0s
[04:39:52.863] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:4:0)
[04:39:52.863] Timer tick, asking Buildbucket for the build status
[04:39:52.935] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:39:52.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:5:0) after 1m0s
[04:40:53.007] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:5:0)
[04:40:53.007] Timer tick, asking Buildbucket for the build status
[04:40:53.137] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:40:53.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:6:0) after 1m0s
[04:41:53.165] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:6:0)
[04:41:53.165] Timer tick, asking Buildbucket for the build status
[04:41:53.591] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:41:53.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:7:0) after 1m0s
[04:42:53.631] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:7:0)
[04:42:53.631] Timer tick, asking Buildbucket for the build status
[04:42:53.753] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:42:53.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:8:0) after 1m0s
[04:43:53.756] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:8:0)
[04:43:53.756] Timer tick, asking Buildbucket for the build status
[04:43:53.837] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:43:53.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:9:0) after 1m0s
[04:44:53.917] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:9:0)
[04:44:53.917] Timer tick, asking Buildbucket for the build status
[04:44:54.205] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:44:54.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:10:0) after 1m0s
[04:45:54.237] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:10:0)
[04:45:54.237] Timer tick, asking Buildbucket for the build status
[04:45:54.313] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:45:54.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:11:0) after 1m0s
[04:46:54.398] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:11:0)
[04:46:54.398] Timer tick, asking Buildbucket for the build status
[04:46:54.501] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:46:54.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:12:0) after 1m0s
[04:47:54.526] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:12:0)
[04:47:54.526] Timer tick, asking Buildbucket for the build status
[04:47:54.656] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:47:54.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:13:0) after 1m0s
[04:48:54.682] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:13:0)
[04:48:54.682] Timer tick, asking Buildbucket for the build status
[04:48:54.748] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:48:54.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:14:0) after 1m0s
[04:49:54.720] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:14:0)
[04:49:54.720] Timer tick, asking Buildbucket for the build status
[04:49:54.795] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:49:54.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:15:0) after 1m0s
[04:50:54.843] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:15:0)
[04:50:54.843] Timer tick, asking Buildbucket for the build status
[04:50:54.953] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:50:54.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:16:0) after 1m0s
[04:51:55.094] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:16:0)
[04:51:55.094] Timer tick, asking Buildbucket for the build status
[04:51:55.271] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:51:55.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:17:0) after 1m0s
[04:52:55.347] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:17:0)
[04:52:55.347] Timer tick, asking Buildbucket for the build status
[04:52:55.425] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:52:55.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:18:0) after 1m0s
[04:53:55.452] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:18:0)
[04:53:55.452] Timer tick, asking Buildbucket for the build status
[04:53:55.528] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:53:55.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:19:0) after 1m0s
[04:54:55.599] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:19:0)
[04:54:55.599] Timer tick, asking Buildbucket for the build status
[04:54:55.714] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:54:55.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:20:0) after 1m0s
[04:55:55.733] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:20:0)
[04:55:55.733] Timer tick, asking Buildbucket for the build status
[04:55:55.977] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:55:55.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:21:0) after 1m0s
[04:56:56.000] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:21:0)
[04:56:56.000] Timer tick, asking Buildbucket for the build status
[04:56:56.095] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:56:56.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:22:0) after 1m0s
[04:57:56.122] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:22:0)
[04:57:56.122] Timer tick, asking Buildbucket for the build status
[04:57:56.279] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:57:56.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:23:0) after 1m0s
[04:58:56.388] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:23:0)
[04:58:56.388] Timer tick, asking Buildbucket for the build status
[04:58:56.463] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:58:56.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:24:0) after 1m0s
[04:59:56.547] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:24:0)
[04:59:56.547] Timer tick, asking Buildbucket for the build status
[04:59:56.711] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:59:56.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:25:0) after 1m0s
[05:00:56.756] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:25:0)
[05:00:56.756] Timer tick, asking Buildbucket for the build status
[05:00:56.893] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:00:56.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:26:0) after 1m0s
[05:01:56.974] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:26:0)
[05:01:56.974] Timer tick, asking Buildbucket for the build status
[05:01:57.108] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:01:57.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:27:0) after 1m0s
[05:02:57.142] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:27:0)
[05:02:57.142] Timer tick, asking Buildbucket for the build status
[05:02:57.224] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:02:57.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:28:0) after 1m0s
[05:03:57.268] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:28:0)
[05:03:57.268] Timer tick, asking Buildbucket for the build status
[05:03:57.360] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:03:57.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:29:0) after 1m0s
[05:04:57.400] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:29:0)
[05:04:57.400] Timer tick, asking Buildbucket for the build status
[05:04:57.785] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:04:57.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:30:0) after 1m0s
[05:05:57.861] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:30:0)
[05:05:57.861] Timer tick, asking Buildbucket for the build status
[05:05:58.080] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:05:58.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:31:0) after 1m0s
[05:06:58.124] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:31:0)
[05:06:58.124] Timer tick, asking Buildbucket for the build status
[05:06:58.228] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:06:58.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:32:0) after 1m0s
[05:07:58.305] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:32:0)
[05:07:58.305] Timer tick, asking Buildbucket for the build status
[05:07:58.398] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:07:58.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:33:0) after 1m0s
[05:08:58.455] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:33:0)
[05:08:58.455] Timer tick, asking Buildbucket for the build status
[05:08:58.533] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:08:58.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:34:0) after 1m0s
[05:09:58.558] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:34:0)
[05:09:58.558] Timer tick, asking Buildbucket for the build status
[05:09:58.655] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:09:58.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:35:0) after 1m0s
[05:10:58.687] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:35:0)
[05:10:58.687] Timer tick, asking Buildbucket for the build status
[05:10:58.767] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:10:58.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:36:0) after 1m0s
[05:11:58.787] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:36:0)
[05:11:58.787] Timer tick, asking Buildbucket for the build status
[05:11:58.900] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:11:58.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:37:0) after 1m0s
[05:12:58.959] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:37:0)
[05:12:58.959] Timer tick, asking Buildbucket for the build status
[05:12:59.036] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:12:59.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:38:0) after 1m0s
[05:13:59.062] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:38:0)
[05:13:59.062] Timer tick, asking Buildbucket for the build status
[05:13:59.162] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:13:59.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:39:0) after 1m0s
[05:14:59.235] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:39:0)
[05:14:59.235] Timer tick, asking Buildbucket for the build status
[05:14:59.300] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:14:59.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:40:0) after 1m0s
[05:15:59.331] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:40:0)
[05:15:59.331] Timer tick, asking Buildbucket for the build status
[05:15:59.561] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:15:59.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:41:0) after 1m0s
[05:16:59.587] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:41:0)
[05:16:59.587] Timer tick, asking Buildbucket for the build status
[05:16:59.734] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:16:59.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:42:0) after 1m0s
[05:17:59.758] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:42:0)
[05:17:59.758] Timer tick, asking Buildbucket for the build status
[05:17:59.835] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:17:59.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:43:0) after 1m0s
[05:18:59.869] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:43:0)
[05:18:59.869] Timer tick, asking Buildbucket for the build status
[05:18:59.950] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:18:59.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:44:0) after 1m0s
[05:19:59.983] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:44:0)
[05:19:59.983] Timer tick, asking Buildbucket for the build status
[05:20:00.158] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:20:00.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:45:0) after 1m0s
[05:21:00.182] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:45:0)
[05:21:00.182] Timer tick, asking Buildbucket for the build status
[05:21:00.270] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:21:00.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:46:0) after 1m0s
[05:22:00.655] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:46:0)
[05:22:00.655] Timer tick, asking Buildbucket for the build status
[05:22:00.798] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:22:00.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:47:0) after 1m0s
[05:23:02.434] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:47:0)
[05:23:02.434] Timer tick, asking Buildbucket for the build status
[05:23:02.500] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:23:02.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:48:0) after 1m0s
[05:24:02.601] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:48:0)
[05:24:02.601] Timer tick, asking Buildbucket for the build status
[05:24:02.674] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:24:02.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:49:0) after 1m0s
[05:25:02.750] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:49:0)
[05:25:02.750] Timer tick, asking Buildbucket for the build status
[05:25:02.832] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:25:02.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:50:0) after 1m0s
[05:26:03.221] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:50:0)
[05:26:03.221] Timer tick, asking Buildbucket for the build status
[05:26:03.361] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:26:03.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:51:0) after 1m0s
[05:27:03.988] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:51:0)
[05:27:03.988] Timer tick, asking Buildbucket for the build status
[05:27:04.081] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:27:04.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:52:0) after 1m0s
[05:28:04.322] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:52:0)
[05:28:04.322] Timer tick, asking Buildbucket for the build status
[05:28:04.414] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:28:04.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:53:0) after 1m0s
[05:29:04.558] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:53:0)
[05:29:04.558] Timer tick, asking Buildbucket for the build status
[05:29:04.728] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:29:04.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:54:0) after 1m0s
[05:30:04.859] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:54:0)
[05:30:04.859] Timer tick, asking Buildbucket for the build status
[05:30:04.942] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:30:04.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:55:0) after 1m0s
[05:31:05.194] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:55:0)
[05:31:05.194] Timer tick, asking Buildbucket for the build status
[05:31:05.571] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:31:05.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:56:0) after 1m0s
[05:32:05.644] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:56:0)
[05:32:05.644] Timer tick, asking Buildbucket for the build status
[05:32:05.765] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:32:05.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:57:0) after 1m0s
[05:33:05.844] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:57:0)
[05:33:05.844] Timer tick, asking Buildbucket for the build status
[05:33:06.098] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:33:06.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:58:0) after 1m0s
[05:34:06.262] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:58:0)
[05:34:06.262] Timer tick, asking Buildbucket for the build status
[05:34:06.360] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:34:06.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:59:0) after 1m0s
[05:35:06.529] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:59:0)
[05:35:06.529] Timer tick, asking Buildbucket for the build status
[05:35:06.663] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:35:06.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:60:0) after 1m0s
[05:36:06.719] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:60:0)
[05:36:06.719] Timer tick, asking Buildbucket for the build status
[05:36:06.810] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:36:06.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:61:0) after 1m0s
[05:37:06.854] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:61:0)
[05:37:06.854] Timer tick, asking Buildbucket for the build status
[05:37:07.047] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:37:07.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:62:0) after 1m0s
[05:38:07.077] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:62:0)
[05:38:07.077] Timer tick, asking Buildbucket for the build status
[05:38:07.160] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:38:07.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:63:0) after 1m0s
[05:39:07.185] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:63:0)
[05:39:07.185] Timer tick, asking Buildbucket for the build status
[05:39:07.361] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:39:07.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:64:0) after 1m0s
[05:40:07.460] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:64:0)
[05:40:07.462] Timer tick, asking Buildbucket for the build status
[05:40:07.588] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:40:07.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:65:0) after 1m0s
[05:41:07.574] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:65:0)
[05:41:07.574] Timer tick, asking Buildbucket for the build status
[05:41:07.650] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:41:07.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:66:0) after 1m0s
[05:42:07.747] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:66:0)
[05:42:07.747] Timer tick, asking Buildbucket for the build status
[05:42:07.893] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:42:07.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:67:0) after 1m0s
[05:43:08.056] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:67:0)
[05:43:08.056] Timer tick, asking Buildbucket for the build status
[05:43:08.175] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:43:08.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:68:0) after 1m0s
[05:44:08.389] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:68:0)
[05:44:08.389] Timer tick, asking Buildbucket for the build status
[05:44:08.609] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:44:08.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:69:0) after 1m0s
[05:45:08.842] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:69:0)
[05:45:08.842] Timer tick, asking Buildbucket for the build status
[05:45:08.966] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:45:08.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:70:0) after 1m0s
[05:46:08.989] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:70:0)
[05:46:08.989] Timer tick, asking Buildbucket for the build status
[05:46:09.093] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:46:09.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:71:0) after 1m0s
[05:47:09.158] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:71:0)
[05:47:09.158] Timer tick, asking Buildbucket for the build status
[05:47:09.279] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:47:09.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:72:0) after 1m0s
[05:48:09.404] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:72:0)
[05:48:09.404] Timer tick, asking Buildbucket for the build status
[05:48:09.473] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:48:09.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:73:0) after 1m0s
[05:49:09.448] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:73:0)
[05:49:09.448] Timer tick, asking Buildbucket for the build status
[05:49:09.541] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:49:09.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:74:0) after 1m0s
[05:50:09.640] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:74:0)
[05:50:09.640] Timer tick, asking Buildbucket for the build status
[05:50:09.727] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:50:09.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:75:0) after 1m0s
[05:51:09.893] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:75:0)
[05:51:09.893] Timer tick, asking Buildbucket for the build status
[05:51:10.018] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:51:10.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:76:0) after 1m0s
[05:52:10.048] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:76:0)
[05:52:10.048] Timer tick, asking Buildbucket for the build status
[05:52:10.144] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:52:10.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:77:0) after 1m0s
[05:53:10.187] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:77:0)
[05:53:10.187] Timer tick, asking Buildbucket for the build status
[05:53:10.270] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:53:10.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:78:0) after 1m0s
[05:54:10.357] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:78:0)
[05:54:10.357] Timer tick, asking Buildbucket for the build status
[05:54:10.441] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:54:10.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:79:0) after 1m0s
[05:55:10.506] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:79:0)
[05:55:10.506] Timer tick, asking Buildbucket for the build status
[05:55:10.581] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:55:10.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:80:0) after 1m0s
[05:56:10.616] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:80:0)
[05:56:10.617] Timer tick, asking Buildbucket for the build status
[05:56:10.723] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:56:10.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:81:0) after 1m0s
[05:57:10.752] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:81:0)
[05:57:10.752] Timer tick, asking Buildbucket for the build status
[05:57:10.855] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:57:10.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:82:0) after 1m0s
[05:58:10.938] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:82:0)
[05:58:10.938] Timer tick, asking Buildbucket for the build status
[05:58:11.028] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:58:11.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:83:0) after 1m0s
[05:59:11.054] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:83:0)
[05:59:11.054] Timer tick, asking Buildbucket for the build status
[05:59:11.160] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:59:11.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:84:0) after 1m0s
[06:00:11.293] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:84:0)
[06:00:11.293] Timer tick, asking Buildbucket for the build status
[06:00:14.390] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:00:14.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:85:0) after 1m0s
[06:01:18.275] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:85:0)
[06:01:18.275] Timer tick, asking Buildbucket for the build status
[06:01:18.426] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:01:18.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:86:0) after 1m0s
[06:02:18.443] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:86:0)
[06:02:18.443] Timer tick, asking Buildbucket for the build status
[06:02:18.551] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:02:18.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:87:0) after 1m0s
[06:03:18.577] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:87:0)
[06:03:18.577] Timer tick, asking Buildbucket for the build status
[06:03:18.655] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:03:18.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:88:0) after 1m0s
[06:04:18.661] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:88:0)
[06:04:18.661] Timer tick, asking Buildbucket for the build status
[06:04:18.749] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:04:18.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:89:0) after 1m0s
[06:05:18.767] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:89:0)
[06:05:18.768] Timer tick, asking Buildbucket for the build status
[06:05:18.999] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:05:18.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:90:0) after 1m0s
[06:06:19.010] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:90:0)
[06:06:19.010] Timer tick, asking Buildbucket for the build status
[06:06:19.086] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:06:19.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:91:0) after 1m0s
[06:07:19.149] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:91:0)
[06:07:19.149] Timer tick, asking Buildbucket for the build status
[06:07:19.256] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:07:19.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:92:0) after 1m0s
[06:08:19.305] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:92:0)
[06:08:19.305] Timer tick, asking Buildbucket for the build status
[06:08:19.505] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:08:19.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:93:0) after 1m0s
[06:09:19.557] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:93:0)
[06:09:19.557] Timer tick, asking Buildbucket for the build status
[06:09:20.459] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:09:20.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:94:0) after 1m0s
[06:10:20.810] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:94:0)
[06:10:20.810] Timer tick, asking Buildbucket for the build status
[06:10:21.308] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:10:21.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:95:0) after 1m0s
[06:11:21.438] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:95:0)
[06:11:21.438] Timer tick, asking Buildbucket for the build status
[06:11:21.519] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:11:21.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:96:0) after 1m0s
[06:12:21.720] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:96:0)
[06:12:21.720] Timer tick, asking Buildbucket for the build status
[06:12:22.019] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:12:22.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:97:0) after 1m0s
[06:13:22.225] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:97:0)
[06:13:22.225] Timer tick, asking Buildbucket for the build status
[06:13:23.552] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:13:23.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:98:0) after 1m0s
[06:14:23.657] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:98:0)
[06:14:23.657] Timer tick, asking Buildbucket for the build status
[06:14:23.746] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:14:23.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:99:0) after 1m0s
[06:15:24.309] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:99:0)
[06:15:24.309] Timer tick, asking Buildbucket for the build status
[06:15:24.387] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:15:24.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:100:0) after 1m0s
[06:16:24.618] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:100:0)
[06:16:24.618] Timer tick, asking Buildbucket for the build status
[06:16:24.696] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:16:24.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:101:0) after 1m0s
[06:17:24.858] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:101:0)
[06:17:24.858] Timer tick, asking Buildbucket for the build status
[06:17:25.650] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:17:25.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:102:0) after 1m0s
[06:18:25.751] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:102:0)
[06:18:25.751] Timer tick, asking Buildbucket for the build status
[06:18:25.823] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:18:25.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:103:0) after 1m0s
[06:19:25.952] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:103:0)
[06:19:25.952] Timer tick, asking Buildbucket for the build status
[06:19:26.164] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:19:26.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:104:0) after 1m0s
[06:20:26.236] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:104:0)
[06:20:26.236] Timer tick, asking Buildbucket for the build status
[06:20:26.617] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:20:26.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:105:0) after 1m0s
[06:21:26.707] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:105:0)
[06:21:26.707] Timer tick, asking Buildbucket for the build status
[06:21:26.817] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:21:26.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:106:0) after 1m0s
[06:22:26.888] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:106:0)
[06:22:26.888] Timer tick, asking Buildbucket for the build status
[06:22:27.009] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:22:27.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:107:0) after 1m0s
[06:23:27.053] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:107:0)
[06:23:27.053] Timer tick, asking Buildbucket for the build status
[06:23:27.226] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:23:27.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:108:0) after 1m0s
[06:24:27.294] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:108:0)
[06:24:27.294] Timer tick, asking Buildbucket for the build status
[06:24:27.386] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:24:27.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:109:0) after 1m0s
[06:25:27.491] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:109:0)
[06:25:27.491] Timer tick, asking Buildbucket for the build status
[06:25:27.585] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:25:27.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:110:0) after 1m0s
[06:26:27.609] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:110:0)
[06:26:27.609] Timer tick, asking Buildbucket for the build status
[06:26:27.722] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:26:27.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:111:0) after 1m0s
[06:27:27.823] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:111:0)
[06:27:27.823] Timer tick, asking Buildbucket for the build status
[06:27:27.981] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:27:27.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:112:0) after 1m0s
[06:28:28.016] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:112:0)
[06:28:28.016] Timer tick, asking Buildbucket for the build status
[06:28:28.139] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:28:28.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:113:0) after 1m0s
[06:29:28.166] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:113:0)
[06:29:28.166] Timer tick, asking Buildbucket for the build status
[06:29:28.289] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:29:28.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:114:0) after 1m0s
[06:30:28.372] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:114:0)
[06:30:28.372] Timer tick, asking Buildbucket for the build status
[06:30:28.442] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:30:28.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:115:0) after 1m0s
[06:31:28.484] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:115:0)
[06:31:28.484] Timer tick, asking Buildbucket for the build status
[06:31:28.583] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:31:28.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:116:0) after 1m0s
[06:32:28.674] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:116:0)
[06:32:28.674] Timer tick, asking Buildbucket for the build status
[06:32:29.695] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:32:29.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:117:0) after 1m0s
[06:33:29.755] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:117:0)
[06:33:29.756] Timer tick, asking Buildbucket for the build status
[06:33:29.829] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:33:29.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:118:0) after 1m0s
[06:34:29.868] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:118:0)
[06:34:29.868] Timer tick, asking Buildbucket for the build status
[06:34:29.943] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:34:29.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:119:0) after 1m0s
[06:35:29.969] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:119:0)
[06:35:29.969] Timer tick, asking Buildbucket for the build status
[06:35:30.057] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:35:30.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:120:0) after 1m0s
[06:36:30.087] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:120:0)
[06:36:30.087] Timer tick, asking Buildbucket for the build status
[06:36:30.165] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:36:30.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:121:0) after 1m0s
[06:37:30.260] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:121:0)
[06:37:30.260] Timer tick, asking Buildbucket for the build status
[06:37:30.424] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:37:30.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:122:0) after 1m0s
[06:38:30.458] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:122:0)
[06:38:30.458] Timer tick, asking Buildbucket for the build status
[06:38:30.535] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:38:30.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:123:0) after 1m0s
[06:39:30.519] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:123:0)
[06:39:30.519] Timer tick, asking Buildbucket for the build status
[06:39:30.704] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:39:30.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:124:0) after 1m0s
[06:40:30.755] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:124:0)
[06:40:30.755] Timer tick, asking Buildbucket for the build status
[06:40:30.853] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:40:30.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:125:0) after 1m0s
[06:41:30.901] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:125:0)
[06:41:30.901] Timer tick, asking Buildbucket for the build status
[06:41:30.986] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:41:30.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:126:0) after 1m0s
[06:42:31.022] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:126:0)
[06:42:31.022] Timer tick, asking Buildbucket for the build status
[06:42:31.304] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:42:31.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:127:0) after 1m0s
[06:43:31.481] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:127:0)
[06:43:31.481] Timer tick, asking Buildbucket for the build status
[06:43:31.702] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:43:31.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:128:0) after 1m0s
[06:44:31.763] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:128:0)
[06:44:31.763] Timer tick, asking Buildbucket for the build status
[06:44:31.991] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:44:31.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:129:0) after 1m0s
[06:45:32.024] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:129:0)
[06:45:32.024] Timer tick, asking Buildbucket for the build status
[06:45:32.314] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:45:32.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:130:0) after 1m0s
[06:46:32.340] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:130:0)
[06:46:32.340] Timer tick, asking Buildbucket for the build status
[06:46:32.420] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:46:32.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:131:0) after 1m0s
[06:47:32.422] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:131:0)
[06:47:32.422] Timer tick, asking Buildbucket for the build status
[06:47:33.246] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:47:33.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:132:0) after 1m0s
[06:48:33.302] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:132:0)
[06:48:33.302] Timer tick, asking Buildbucket for the build status
[06:48:33.425] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:48:33.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:133:0) after 1m0s
[06:49:33.450] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:133:0)
[06:49:33.450] Timer tick, asking Buildbucket for the build status
[06:49:33.517] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:49:33.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:134:0) after 1m0s
[06:50:33.533] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:134:0)
[06:50:33.533] Timer tick, asking Buildbucket for the build status
[06:50:33.601] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:50:33.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:135:0) after 1m0s
[06:51:33.627] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:135:0)
[06:51:33.627] Timer tick, asking Buildbucket for the build status
[06:51:33.772] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:51:33.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:136:0) after 1m0s
[06:52:33.816] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:136:0)
[06:52:33.816] Timer tick, asking Buildbucket for the build status
[06:52:33.914] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:52:33.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:137:0) after 1m0s
[06:53:33.897] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:137:0)
[06:53:33.897] Timer tick, asking Buildbucket for the build status
[06:53:33.986] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:53:33.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:138:0) after 1m0s
[06:54:34.050] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:138:0)
[06:54:34.050] Timer tick, asking Buildbucket for the build status
[06:54:34.153] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:54:34.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:139:0) after 1m0s
[06:55:34.180] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:139:0)
[06:55:34.180] Timer tick, asking Buildbucket for the build status
[06:55:34.271] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:55:34.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:140:0) after 1m0s
[06:56:34.304] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:140:0)
[06:56:34.304] Timer tick, asking Buildbucket for the build status
[06:56:34.425] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:56:34.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:141:0) after 1m0s
[06:57:34.577] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:141:0)
[06:57:34.577] Timer tick, asking Buildbucket for the build status
[06:57:34.728] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:57:34.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:142:0) after 1m0s
[06:58:34.771] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:142:0)
[06:58:34.771] Timer tick, asking Buildbucket for the build status
[06:58:34.851] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:58:34.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:143:0) after 1m0s
[06:59:34.913] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:143:0)
[06:59:34.913] Timer tick, asking Buildbucket for the build status
[06:59:35.001] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:59:35.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:144:0) after 1m0s
[07:00:35.066] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:144:0)
[07:00:35.066] Timer tick, asking Buildbucket for the build status
[07:00:35.153] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:00:35.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:145:0) after 1m0s
[07:01:35.222] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:145:0)
[07:01:35.222] Timer tick, asking Buildbucket for the build status
[07:01:35.288] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:01:35.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:146:0) after 1m0s
[07:02:35.351] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:146:0)
[07:02:35.351] Timer tick, asking Buildbucket for the build status
[07:02:35.453] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:02:35.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:147:0) after 1m0s
[07:03:35.483] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:147:0)
[07:03:35.483] Timer tick, asking Buildbucket for the build status
[07:03:35.622] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:03:35.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:148:0) after 1m0s
[07:04:35.650] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:148:0)
[07:04:35.650] Timer tick, asking Buildbucket for the build status
[07:04:35.759] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:04:35.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:149:0) after 1m0s
[07:05:35.770] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:149:0)
[07:05:35.770] Timer tick, asking Buildbucket for the build status
[07:05:35.841] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:05:35.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:150:0) after 1m0s
[07:06:35.904] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:150:0)
[07:06:35.904] Timer tick, asking Buildbucket for the build status
[07:06:35.976] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:06:35.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:151:0) after 1m0s
[07:07:36.036] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:151:0)
[07:07:36.036] Timer tick, asking Buildbucket for the build status
[07:07:36.165] Build 8863503467602394672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:07:36.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:152:0) after 1m0s
[07:07:44.177] Received PubSub notification, asking Buildbucket for the build status
[07:07:44.273] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:36.225] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:152:0)
[07:08:36.225] Timer tick, asking Buildbucket for the build status
[07:08:36.304] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:36.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:154:0) after 1m0s
[07:09:36.334] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:154:0)
[07:09:36.334] Timer tick, asking Buildbucket for the build status
[07:09:36.442] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:36.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:155:0) after 1m0s
[07:10:36.488] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:155:0)
[07:10:36.488] Timer tick, asking Buildbucket for the build status
[07:10:36.665] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:36.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:156:0) after 1m0s
[07:11:36.692] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:156:0)
[07:11:36.692] Timer tick, asking Buildbucket for the build status
[07:11:36.754] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:36.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:157:0) after 1m0s
[07:12:36.864] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:157:0)
[07:12:36.864] Timer tick, asking Buildbucket for the build status
[07:12:37.065] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:37.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:158:0) after 1m0s
[07:13:37.105] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:158:0)
[07:13:37.105] Timer tick, asking Buildbucket for the build status
[07:13:37.209] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:37.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:159:0) after 1m0s
[07:14:37.228] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:159:0)
[07:14:37.228] Timer tick, asking Buildbucket for the build status
[07:14:37.472] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:37.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:160:0) after 1m0s
[07:15:37.498] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:160:0)
[07:15:37.498] Timer tick, asking Buildbucket for the build status
[07:15:37.562] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:37.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:161:0) after 1m0s
[07:16:37.657] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:161:0)
[07:16:37.657] Timer tick, asking Buildbucket for the build status
[07:16:37.737] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:37.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:162:0) after 1m0s
[07:17:37.827] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:162:0)
[07:17:37.828] Timer tick, asking Buildbucket for the build status
[07:17:37.981] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:37.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:163:0) after 1m0s
[07:18:38.039] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:163:0)
[07:18:38.039] Timer tick, asking Buildbucket for the build status
[07:18:38.207] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:38.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:164:0) after 1m0s
[07:19:38.318] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:164:0)
[07:19:38.318] Timer tick, asking Buildbucket for the build status
[07:19:38.448] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:38.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:165:0) after 1m0s
[07:20:38.512] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:165:0)
[07:20:38.512] Timer tick, asking Buildbucket for the build status
[07:20:38.709] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:38.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:166:0) after 1m0s
[07:21:38.801] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:166:0)
[07:21:38.801] Timer tick, asking Buildbucket for the build status
[07:21:38.866] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:38.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:167:0) after 1m0s
[07:22:38.968] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:167:0)
[07:22:38.968] Timer tick, asking Buildbucket for the build status
[07:22:39.110] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:39.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:168:0) after 1m0s
[07:23:39.174] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:168:0)
[07:23:39.174] Timer tick, asking Buildbucket for the build status
[07:23:39.250] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:39.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:169:0) after 1m0s
[07:24:39.442] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:169:0)
[07:24:39.442] Timer tick, asking Buildbucket for the build status
[07:24:39.493] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:39.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:170:0) after 1m0s
[07:25:39.756] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:170:0)
[07:25:39.756] Timer tick, asking Buildbucket for the build status
[07:25:39.819] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:39.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:171:0) after 1m0s
[07:26:39.848] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:171:0)
[07:26:39.848] Timer tick, asking Buildbucket for the build status
[07:26:39.929] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:39.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:172:0) after 1m0s
[07:27:40.027] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:172:0)
[07:27:40.027] Timer tick, asking Buildbucket for the build status
[07:27:40.465] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:40.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:173:0) after 1m0s
[07:28:40.530] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:173:0)
[07:28:40.531] Timer tick, asking Buildbucket for the build status
[07:28:40.624] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:40.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:174:0) after 1m0s
[07:29:40.787] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:174:0)
[07:29:40.787] Timer tick, asking Buildbucket for the build status
[07:29:40.850] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:40.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:175:0) after 1m0s
[07:30:41.000] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:175:0)
[07:30:41.000] Timer tick, asking Buildbucket for the build status
[07:30:41.206] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:41.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:176:0) after 1m0s
[07:31:41.248] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:176:0)
[07:31:41.248] Timer tick, asking Buildbucket for the build status
[07:31:41.802] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:41.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:177:0) after 1m0s
[07:32:41.862] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:177:0)
[07:32:41.862] Timer tick, asking Buildbucket for the build status
[07:32:42.165] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:42.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:178:0) after 1m0s
[07:33:42.248] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:178:0)
[07:33:42.248] Timer tick, asking Buildbucket for the build status
[07:33:43.713] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:43.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:179:0) after 1m0s
[07:34:43.739] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:179:0)
[07:34:43.739] Timer tick, asking Buildbucket for the build status
[07:34:43.864] Build 8863503467602394672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:43.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9028933529684793952:180:0) after 1m0s
[07:35:03.004] Received PubSub notification, asking Buildbucket for the build status
[07:35:09.677] Build 8863503467602394672: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[07:35:09.678] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605512102594836",
"created_by": "project:chromium",
"created_ts": "1605501411777825",
"failure_reason": "BUILD_FAILURE",
"id": "8863503467602394672",
"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\": \"2940e7199be460c387e114502bb727891248510b\", \"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\": 1753, \"got_angle_revision\": \"f46e6201b40bdee3e0b79d5d97660fc302fd76f5\", \"got_dawn_revision\": \"575729e8dd25e0ac4631c9739a82118ea3545c6b\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"2940e7199be460c387e114502bb727891248510b\", \"got_revision_cp\": \"refs/heads/master@{#827710}\", \"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){#827710}_without_patch\": {\"upload_trace_processor\": \"19eb7396c42754bddbe07ee7907f93016d5ca455\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"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\": [\"5632-2e614e1\"], \"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": "1605510463107838",
"status": "COMPLETED",
"status_changed_ts": "1605510463671484",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/1753",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9028933529684793952",
"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/8863503467602394672/+/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:4fe828ee06f5aa10",
"user_agent:luci-scheduler"
],
"updated_ts": "1605512102715808",
"url": "https://ci.chromium.org/b/8863503467602394672",
"utcnow_ts": "1605512109661888"
}
}
[07:35:09.678] Invocation finished in 2h58m19.269873714s with status FAILED