[19:03:48.562] New invocation is queued and will start shortly
[19:03:49.651] Starting the invocation (attempt 1)
[19:03:49.695] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:03:49.695] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:03:49.695] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026251672696840560",
"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:9026251672696840560",
"scheduler_job_id:chromium/win-upload-perfetto",
"user_agent:luci-scheduler"
]
}
[19:03:50.406] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608059029787266",
"id": "8860821610740450512",
"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": "1608059030203564",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/1885",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9026251672696840560",
"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/8860821610740450512/+/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": "1608059030203730",
"url": "https://ci.chromium.org/b/8860821610740450512",
"utcnow_ts": "1608059030397412"
}
}
[19:03:50.406] Task URL: https://ci.chromium.org/b/8860821610740450512
[19:03:50.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:2:0) after 1m0s
[19:04:50.432] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:2:0)
[19:04:50.432] Timer tick, asking Buildbucket for the build status
[19:04:50.530] Build 8860821610740450512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:04:50.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:3:0) after 1m0s
[19:05:50.548] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:3:0)
[19:05:50.548] Timer tick, asking Buildbucket for the build status
[19:05:50.926] Build 8860821610740450512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:05:50.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:4:0) after 1m0s
[19:06:51.025] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:4:0)
[19:06:51.025] Timer tick, asking Buildbucket for the build status
[19:06:51.424] Build 8860821610740450512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:06:51.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:5:0) after 1m0s
[19:07:51.542] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:5:0)
[19:07:51.542] Timer tick, asking Buildbucket for the build status
[19:07:51.622] Build 8860821610740450512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:07:51.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:6:0) after 1m0s
[19:08:51.628] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:6:0)
[19:08:51.628] Timer tick, asking Buildbucket for the build status
[19:08:51.721] Build 8860821610740450512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:08:51.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:7:0) after 1m0s
[19:09:51.751] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:7:0)
[19:09:51.751] Timer tick, asking Buildbucket for the build status
[19:09:51.862] Build 8860821610740450512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:09:51.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:8:0) after 1m0s
[19:10:52.034] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:8:0)
[19:10:52.034] Timer tick, asking Buildbucket for the build status
[19:10:52.205] Build 8860821610740450512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:10:52.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:9:0) after 1m0s
[19:11:52.251] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:9:0)
[19:11:52.251] Timer tick, asking Buildbucket for the build status
[19:11:52.356] Build 8860821610740450512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:11:52.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:10:0) after 1m0s
[19:12:52.756] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:10:0)
[19:12:52.756] Timer tick, asking Buildbucket for the build status
[19:12:52.841] Build 8860821610740450512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:12:52.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:11:0) after 1m0s
[19:13:52.914] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:11:0)
[19:13:52.914] Timer tick, asking Buildbucket for the build status
[19:13:53.062] Build 8860821610740450512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:13:53.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:12:0) after 1m0s
[19:14:53.174] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:12:0)
[19:14:53.174] Timer tick, asking Buildbucket for the build status
[19:14:53.317] Build 8860821610740450512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:14:53.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:13:0) after 1m0s
[19:15:53.671] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:13:0)
[19:15:53.671] Timer tick, asking Buildbucket for the build status
[19:15:53.734] Build 8860821610740450512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:15:53.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:14:0) after 1m0s
[19:16:53.820] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:14:0)
[19:16:53.820] Timer tick, asking Buildbucket for the build status
[19:16:53.910] Build 8860821610740450512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:16:53.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:15:0) after 1m0s
[19:17:53.970] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:15:0)
[19:17:53.970] Timer tick, asking Buildbucket for the build status
[19:17:54.059] Build 8860821610740450512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:17:54.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:16:0) after 1m0s
[19:18:54.221] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:16:0)
[19:18:54.221] Timer tick, asking Buildbucket for the build status
[19:18:54.354] Build 8860821610740450512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:18:54.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:17:0) after 1m0s
[19:19:54.404] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:17:0)
[19:19:54.404] Timer tick, asking Buildbucket for the build status
[19:19:54.631] Build 8860821610740450512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:19:54.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:18:0) after 1m0s
[19:20:54.677] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:18:0)
[19:20:54.677] Timer tick, asking Buildbucket for the build status
[19:20:54.833] Build 8860821610740450512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:20:54.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:19:0) after 1m0s
[19:21:06.547] Received PubSub notification, asking Buildbucket for the build status
[19:21:06.654] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:54.846] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:19:0)
[19:21:54.846] Timer tick, asking Buildbucket for the build status
[19:21:54.957] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:54.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:21:0) after 1m0s
[19:22:55.172] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:21:0)
[19:22:55.172] Timer tick, asking Buildbucket for the build status
[19:22:55.260] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:55.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:22:0) after 1m0s
[19:23:55.521] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:22:0)
[19:23:55.521] Timer tick, asking Buildbucket for the build status
[19:23:55.689] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:55.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:23:0) after 1m0s
[19:24:55.681] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:23:0)
[19:24:55.681] Timer tick, asking Buildbucket for the build status
[19:24:55.767] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:55.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:24:0) after 1m0s
[19:25:55.783] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:24:0)
[19:25:55.783] Timer tick, asking Buildbucket for the build status
[19:25:55.883] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:55.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:25:0) after 1m0s
[19:26:55.931] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:25:0)
[19:26:55.931] Timer tick, asking Buildbucket for the build status
[19:26:55.999] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:55.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:26:0) after 1m0s
[19:27:56.047] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:26:0)
[19:27:56.047] Timer tick, asking Buildbucket for the build status
[19:27:56.139] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:56.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:27:0) after 1m0s
[19:28:56.165] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:27:0)
[19:28:56.165] Timer tick, asking Buildbucket for the build status
[19:28:56.273] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:56.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:28:0) after 1m0s
[19:29:56.335] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:28:0)
[19:29:56.335] Timer tick, asking Buildbucket for the build status
[19:29:56.414] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:56.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:29:0) after 1m0s
[19:30:56.486] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:29:0)
[19:30:56.486] Timer tick, asking Buildbucket for the build status
[19:30:56.577] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:56.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:30:0) after 1m0s
[19:31:56.594] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:30:0)
[19:31:56.594] Timer tick, asking Buildbucket for the build status
[19:31:56.669] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:56.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:31:0) after 1m0s
[19:32:56.678] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:31:0)
[19:32:56.678] Timer tick, asking Buildbucket for the build status
[19:32:56.799] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:56.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:32:0) after 1m0s
[19:33:56.834] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:32:0)
[19:33:56.834] Timer tick, asking Buildbucket for the build status
[19:33:57.233] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:57.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:33:0) after 1m0s
[19:34:57.259] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:33:0)
[19:34:57.259] Timer tick, asking Buildbucket for the build status
[19:34:57.359] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:57.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:34:0) after 1m0s
[19:35:57.378] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:34:0)
[19:35:57.378] Timer tick, asking Buildbucket for the build status
[19:35:57.455] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:57.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:35:0) after 1m0s
[19:36:57.493] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:35:0)
[19:36:57.493] Timer tick, asking Buildbucket for the build status
[19:36:57.602] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:57.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:36:0) after 1m0s
[19:37:57.643] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:36:0)
[19:37:57.643] Timer tick, asking Buildbucket for the build status
[19:37:57.738] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:57.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:37:0) after 1m0s
[19:38:58.187] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:37:0)
[19:38:58.187] Timer tick, asking Buildbucket for the build status
[19:38:58.370] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:58.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:38:0) after 1m0s
[19:39:59.503] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:38:0)
[19:39:59.503] Timer tick, asking Buildbucket for the build status
[19:39:59.611] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:59.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:39:0) after 1m0s
[19:40:59.871] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:39:0)
[19:40:59.871] Timer tick, asking Buildbucket for the build status
[19:40:59.948] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:59.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:40:0) after 1m0s
[19:42:00.155] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:40:0)
[19:42:00.155] Timer tick, asking Buildbucket for the build status
[19:42:00.235] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:00.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:41:0) after 1m0s
[19:43:00.426] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:41:0)
[19:43:00.426] Timer tick, asking Buildbucket for the build status
[19:43:00.570] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:00.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:42:0) after 1m0s
[19:44:01.325] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:42:0)
[19:44:01.325] Timer tick, asking Buildbucket for the build status
[19:44:01.814] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:01.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:43:0) after 1m0s
[19:45:01.824] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:43:0)
[19:45:01.824] Timer tick, asking Buildbucket for the build status
[19:45:02.171] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:02.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:44:0) after 1m0s
[19:46:02.246] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:44:0)
[19:46:02.246] Timer tick, asking Buildbucket for the build status
[19:46:02.352] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:02.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:45:0) after 1m0s
[19:47:02.418] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:45:0)
[19:47:02.418] Timer tick, asking Buildbucket for the build status
[19:47:02.523] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:02.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:46:0) after 1m0s
[19:48:02.615] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:46:0)
[19:48:02.615] Timer tick, asking Buildbucket for the build status
[19:48:02.736] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:02.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:47:0) after 1m0s
[19:49:02.836] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:47:0)
[19:49:02.836] Timer tick, asking Buildbucket for the build status
[19:49:02.997] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:02.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:48:0) after 1m0s
[19:50:03.072] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:48:0)
[19:50:03.072] Timer tick, asking Buildbucket for the build status
[19:50:03.218] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:03.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:49:0) after 1m0s
[19:51:03.246] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:49:0)
[19:51:03.246] Timer tick, asking Buildbucket for the build status
[19:51:03.398] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:03.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:50:0) after 1m0s
[19:52:03.423] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:50:0)
[19:52:03.423] Timer tick, asking Buildbucket for the build status
[19:52:03.660] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:03.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:51:0) after 1m0s
[19:53:03.660] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:51:0)
[19:53:03.660] Timer tick, asking Buildbucket for the build status
[19:53:03.896] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:03.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:52:0) after 1m0s
[19:54:03.957] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:52:0)
[19:54:03.957] Timer tick, asking Buildbucket for the build status
[19:54:04.167] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:04.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:53:0) after 1m0s
[19:55:04.187] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:53:0)
[19:55:04.187] Timer tick, asking Buildbucket for the build status
[19:55:04.422] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:04.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:54:0) after 1m0s
[19:56:04.475] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:54:0)
[19:56:04.475] Timer tick, asking Buildbucket for the build status
[19:56:04.561] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:04.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:55:0) after 1m0s
[19:57:04.627] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:55:0)
[19:57:04.627] Timer tick, asking Buildbucket for the build status
[19:57:04.721] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:04.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:56:0) after 1m0s
[19:58:04.756] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:56:0)
[19:58:04.756] Timer tick, asking Buildbucket for the build status
[19:58:04.971] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:04.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:57:0) after 1m0s
[19:59:05.043] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:57:0)
[19:59:05.043] Timer tick, asking Buildbucket for the build status
[19:59:05.901] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:05.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:58:0) after 1m0s
[20:00:05.986] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:58:0)
[20:00:05.986] Timer tick, asking Buildbucket for the build status
[20:00:06.422] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:06.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:59:0) after 1m0s
[20:01:06.503] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:59:0)
[20:01:06.503] Timer tick, asking Buildbucket for the build status
[20:01:06.585] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:06.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:60:0) after 1m0s
[20:02:06.704] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:60:0)
[20:02:06.704] Timer tick, asking Buildbucket for the build status
[20:02:06.781] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:06.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:61:0) after 1m0s
[20:03:06.955] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:61:0)
[20:03:06.955] Timer tick, asking Buildbucket for the build status
[20:03:07.395] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:07.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:62:0) after 1m0s
[20:04:07.419] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:62:0)
[20:04:07.419] Timer tick, asking Buildbucket for the build status
[20:04:07.544] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:07.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:63:0) after 1m0s
[20:05:07.800] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:63:0)
[20:05:07.800] Timer tick, asking Buildbucket for the build status
[20:05:07.977] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:07.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:64:0) after 1m0s
[20:06:08.105] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:64:0)
[20:06:08.105] Timer tick, asking Buildbucket for the build status
[20:06:08.609] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:08.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:65:0) after 1m0s
[20:07:08.787] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:65:0)
[20:07:08.787] Timer tick, asking Buildbucket for the build status
[20:07:09.120] Build 8860821610740450512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:09.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026251672696840560:66:0) after 1m0s
[20:08:03.188] Received PubSub notification, asking Buildbucket for the build status
[20:08:03.421] Build 8860821610740450512: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:08:03.421] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608062882115637",
"created_by": "project:chromium",
"created_ts": "1608059029787266",
"id": "8860821610740450512",
"parameters_json": "{\"builder_name\": \"win-upload-perfetto\", \"properties\": {}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"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\": \"b1e9f330a5dedcabf2ba3f4083362bc512c52940\", \"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-3-qd5c\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"win-upload-perfetto\", \"buildnumber\": 1885, \"got_angle_revision\": \"c47b951ecbcbbba653db801bc7eb5f4fa5d72042\", \"got_dawn_revision\": \"985db28c6faec66a28315eb7f5b46f21e04e4d77\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"b1e9f330a5dedcabf2ba3f4083362bc512c52940\", \"got_revision_cp\": \"refs/heads/master@{#837212}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"fa44979d90aac8cca42e2fee711d44f23c9c4d67\", \"got_v8_revision\": \"6b82504a52acfe8ff39dfc8c3e052e853e44244f\", \"got_v8_revision_cp\": \"refs/heads/8.9.154@{#1}\", \"got_webrtc_revision\": \"cf8ec1f6867651204d81b14a8c31a86e6eb97572\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32829}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"swarm_hashes_refs/heads/master(at){#837212}_without_patch\": {\"upload_trace_processor\": \"d47a21e30c0c9ed392261a272ef37807cf24596c\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1bdf55b197d439c94705ddfba40ad846e2fdb8dd164b64a485766434889345a8_v2\", \"builder_1e6a29180cab26dd3348fa54abb67ed061f7a719cdb336cb75143b23c3452e80_v2\", \"builder_44d97c3a86930e13a38e1eaddb8e17f6891cb7883a214371e74f9ddef6b034c5_v2\", \"builder_48ce158d992f85c54a400240cc3d77408bb96a2369de806c235fde7d7ef64068_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-3-qd5c\"], \"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\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608060065472192",
"status": "COMPLETED",
"status_changed_ts": "1608062882604452",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/1885",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9026251672696840560",
"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/8860821610740450512/+/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:50809b13b8e86a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1608062882629900",
"url": "https://ci.chromium.org/b/8860821610740450512",
"utcnow_ts": "1608062883334399"
}
}
[20:08:03.422] Invocation finished in 1h4m14.883498817s with status SUCCEEDED