[10:30:16.800] New invocation is queued and will start shortly
[10:30:17.913] Starting the invocation (attempt 1)
[10:30:17.974] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:30:17.974] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:30:17.974] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026102787229124768",
"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:9026102787229124768",
"scheduler_job_id:chromium/win-upload-perfetto",
"user_agent:luci-scheduler"
]
}
[10:30:22.902] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608201018221260",
"id": "8860672725076379040",
"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": "1608201019851990",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/1895",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9026102787229124768",
"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/8860672725076379040/+/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": "1608201019852141",
"url": "https://ci.chromium.org/b/8860672725076379040",
"utcnow_ts": "1608201020155619"
}
}
[10:30:22.902] Task URL: https://ci.chromium.org/b/8860672725076379040
[10:30:22.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:2:0) after 1m0s
[10:31:23.034] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:2:0)
[10:31:23.034] Timer tick, asking Buildbucket for the build status
[10:31:23.399] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:31:23.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:3:0) after 1m0s
[10:32:23.480] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:3:0)
[10:32:23.480] Timer tick, asking Buildbucket for the build status
[10:32:23.538] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:32:23.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:4:0) after 1m0s
[10:33:23.667] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:4:0)
[10:33:23.667] Timer tick, asking Buildbucket for the build status
[10:33:23.933] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:33:23.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:5:0) after 1m0s
[10:34:24.036] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:5:0)
[10:34:24.037] Timer tick, asking Buildbucket for the build status
[10:34:24.119] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:34:24.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:6:0) after 1m0s
[10:35:24.187] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:6:0)
[10:35:24.188] Timer tick, asking Buildbucket for the build status
[10:35:24.275] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:35:24.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:7:0) after 1m0s
[10:36:24.334] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:7:0)
[10:36:24.334] Timer tick, asking Buildbucket for the build status
[10:36:24.451] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:36:24.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:8:0) after 1m0s
[10:37:24.503] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:8:0)
[10:37:24.503] Timer tick, asking Buildbucket for the build status
[10:37:24.580] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:37:24.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:9:0) after 1m0s
[10:38:24.596] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:9:0)
[10:38:24.596] Timer tick, asking Buildbucket for the build status
[10:38:24.721] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:38:24.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:10:0) after 1m0s
[10:39:24.758] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:10:0)
[10:39:24.758] Timer tick, asking Buildbucket for the build status
[10:39:24.820] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:39:24.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:11:0) after 1m0s
[10:40:24.849] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:11:0)
[10:40:24.849] Timer tick, asking Buildbucket for the build status
[10:40:24.942] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:40:24.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:12:0) after 1m0s
[10:41:24.962] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:12:0)
[10:41:24.962] Timer tick, asking Buildbucket for the build status
[10:41:25.027] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:41:25.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:13:0) after 1m0s
[10:42:25.057] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:13:0)
[10:42:25.057] Timer tick, asking Buildbucket for the build status
[10:42:25.199] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:42:25.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:14:0) after 1m0s
[10:43:25.244] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:14:0)
[10:43:25.244] Timer tick, asking Buildbucket for the build status
[10:43:25.592] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:43:25.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:15:0) after 1m0s
[10:44:25.671] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:15:0)
[10:44:25.671] Timer tick, asking Buildbucket for the build status
[10:44:25.943] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:44:25.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:16:0) after 1m0s
[10:45:26.082] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:16:0)
[10:45:26.082] Timer tick, asking Buildbucket for the build status
[10:45:26.240] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:45:26.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:17:0) after 1m0s
[10:46:26.335] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:17:0)
[10:46:26.335] Timer tick, asking Buildbucket for the build status
[10:46:26.395] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:46:26.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:18:0) after 1m0s
[10:47:26.442] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:18:0)
[10:47:26.442] Timer tick, asking Buildbucket for the build status
[10:47:26.515] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:47:26.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:19:0) after 1m0s
[10:48:26.571] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:19:0)
[10:48:26.571] Timer tick, asking Buildbucket for the build status
[10:48:26.653] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:48:26.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:20:0) after 1m0s
[10:49:26.815] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:20:0)
[10:49:26.815] Timer tick, asking Buildbucket for the build status
[10:49:26.900] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:49:26.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:21:0) after 1m0s
[10:50:26.974] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:21:0)
[10:50:26.974] Timer tick, asking Buildbucket for the build status
[10:50:27.093] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:50:27.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:22:0) after 1m0s
[10:51:27.147] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:22:0)
[10:51:27.147] Timer tick, asking Buildbucket for the build status
[10:51:27.232] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:51:27.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:23:0) after 1m0s
[10:52:27.267] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:23:0)
[10:52:27.267] Timer tick, asking Buildbucket for the build status
[10:52:27.342] Build 8860672725076379040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:52:27.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:24:0) after 1m0s
[10:53:02.563] Received PubSub notification, asking Buildbucket for the build status
[10:53:02.718] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:27.356] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:24:0)
[10:53:27.356] Timer tick, asking Buildbucket for the build status
[10:53:27.431] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:27.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:26:0) after 1m0s
[10:54:27.461] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:26:0)
[10:54:27.461] Timer tick, asking Buildbucket for the build status
[10:54:27.539] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:27.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:27:0) after 1m0s
[10:55:27.605] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:27:0)
[10:55:27.605] Timer tick, asking Buildbucket for the build status
[10:55:27.698] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:27.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:28:0) after 1m0s
[10:56:27.751] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:28:0)
[10:56:27.751] Timer tick, asking Buildbucket for the build status
[10:56:27.820] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:27.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:29:0) after 1m0s
[10:57:27.857] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:29:0)
[10:57:27.857] Timer tick, asking Buildbucket for the build status
[10:57:28.113] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:28.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:30:0) after 1m0s
[10:58:28.179] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:30:0)
[10:58:28.179] Timer tick, asking Buildbucket for the build status
[10:58:28.267] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:28.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:31:0) after 1m0s
[10:59:28.443] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:31:0)
[10:59:28.443] Timer tick, asking Buildbucket for the build status
[10:59:28.615] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:28.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:32:0) after 1m0s
[11:00:28.747] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:32:0)
[11:00:28.747] Timer tick, asking Buildbucket for the build status
[11:00:28.850] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:28.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:33:0) after 1m0s
[11:01:28.957] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:33:0)
[11:01:28.957] Timer tick, asking Buildbucket for the build status
[11:01:29.055] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:29.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:34:0) after 1m0s
[11:02:29.168] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:34:0)
[11:02:29.168] Timer tick, asking Buildbucket for the build status
[11:02:29.256] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:29.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:35:0) after 1m0s
[11:03:29.310] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:35:0)
[11:03:29.310] Timer tick, asking Buildbucket for the build status
[11:03:29.390] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:29.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:36:0) after 1m0s
[11:04:29.418] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:36:0)
[11:04:29.418] Timer tick, asking Buildbucket for the build status
[11:04:29.486] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:29.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:37:0) after 1m0s
[11:05:29.521] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:37:0)
[11:05:29.521] Timer tick, asking Buildbucket for the build status
[11:05:29.727] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:29.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:38:0) after 1m0s
[11:06:29.767] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:38:0)
[11:06:29.767] Timer tick, asking Buildbucket for the build status
[11:06:29.831] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:29.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:39:0) after 1m0s
[11:07:30.038] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:39:0)
[11:07:30.038] Timer tick, asking Buildbucket for the build status
[11:07:30.213] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:30.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:40:0) after 1m0s
[11:08:30.393] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:40:0)
[11:08:30.393] Timer tick, asking Buildbucket for the build status
[11:08:30.464] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:30.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:41:0) after 1m0s
[11:09:30.642] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:41:0)
[11:09:30.642] Timer tick, asking Buildbucket for the build status
[11:09:30.714] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:30.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:42:0) after 1m0s
[11:10:30.823] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:42:0)
[11:10:30.823] Timer tick, asking Buildbucket for the build status
[11:10:31.069] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:31.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:43:0) after 1m0s
[11:11:31.168] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:43:0)
[11:11:31.168] Timer tick, asking Buildbucket for the build status
[11:11:31.239] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:31.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:44:0) after 1m0s
[11:12:31.309] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:44:0)
[11:12:31.309] Timer tick, asking Buildbucket for the build status
[11:12:31.436] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:31.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:45:0) after 1m0s
[11:13:31.470] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:45:0)
[11:13:31.470] Timer tick, asking Buildbucket for the build status
[11:13:31.603] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:31.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:46:0) after 1m0s
[11:14:31.674] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:46:0)
[11:14:31.674] Timer tick, asking Buildbucket for the build status
[11:14:31.747] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:31.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:47:0) after 1m0s
[11:15:31.825] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:47:0)
[11:15:31.825] Timer tick, asking Buildbucket for the build status
[11:15:32.054] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:32.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:48:0) after 1m0s
[11:16:32.151] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:48:0)
[11:16:32.151] Timer tick, asking Buildbucket for the build status
[11:16:32.274] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:32.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:49:0) after 1m0s
[11:17:32.309] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:49:0)
[11:17:32.309] Timer tick, asking Buildbucket for the build status
[11:17:32.418] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:32.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:50:0) after 1m0s
[11:18:32.476] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:50:0)
[11:18:32.476] Timer tick, asking Buildbucket for the build status
[11:18:32.881] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:32.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:51:0) after 1m0s
[11:19:32.968] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:51:0)
[11:19:32.968] Timer tick, asking Buildbucket for the build status
[11:19:33.125] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:33.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:52:0) after 1m0s
[11:20:33.201] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:52:0)
[11:20:33.201] Timer tick, asking Buildbucket for the build status
[11:20:33.287] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:33.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:53:0) after 1m0s
[11:21:33.271] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:53:0)
[11:21:33.271] Timer tick, asking Buildbucket for the build status
[11:21:33.326] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:33.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:54:0) after 1m0s
[11:22:33.348] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:54:0)
[11:22:33.348] Timer tick, asking Buildbucket for the build status
[11:22:33.410] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:33.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:55:0) after 1m0s
[11:23:33.438] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:55:0)
[11:23:33.438] Timer tick, asking Buildbucket for the build status
[11:23:33.508] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:33.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:56:0) after 1m0s
[11:24:33.533] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:56:0)
[11:24:33.533] Timer tick, asking Buildbucket for the build status
[11:24:33.584] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:33.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:57:0) after 1m0s
[11:25:33.643] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:57:0)
[11:25:33.643] Timer tick, asking Buildbucket for the build status
[11:25:33.748] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:33.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:58:0) after 1m0s
[11:26:33.775] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:58:0)
[11:26:33.775] Timer tick, asking Buildbucket for the build status
[11:26:34.226] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:34.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:59:0) after 1m0s
[11:27:34.274] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:59:0)
[11:27:34.274] Timer tick, asking Buildbucket for the build status
[11:27:34.375] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:34.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:60:0) after 1m0s
[11:28:34.423] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:60:0)
[11:28:34.423] Timer tick, asking Buildbucket for the build status
[11:28:34.493] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:34.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:61:0) after 1m0s
[11:29:34.521] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:61:0)
[11:29:34.521] Timer tick, asking Buildbucket for the build status
[11:29:34.591] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:34.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:62:0) after 1m0s
[11:30:34.627] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:62:0)
[11:30:34.627] Timer tick, asking Buildbucket for the build status
[11:30:34.997] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:34.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:63:0) after 1m0s
[11:31:35.098] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:63:0)
[11:31:35.098] Timer tick, asking Buildbucket for the build status
[11:31:35.512] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:35.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:64:0) after 1m0s
[11:32:35.559] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:64:0)
[11:32:35.559] Timer tick, asking Buildbucket for the build status
[11:32:35.627] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:35.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:65:0) after 1m0s
[11:33:35.648] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:65:0)
[11:33:35.648] Timer tick, asking Buildbucket for the build status
[11:33:35.807] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:35.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:66:0) after 1m0s
[11:34:35.796] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:66:0)
[11:34:35.796] Timer tick, asking Buildbucket for the build status
[11:34:35.849] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:35.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:67:0) after 1m0s
[11:35:35.871] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:67:0)
[11:35:35.871] Timer tick, asking Buildbucket for the build status
[11:35:35.977] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:35.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:68:0) after 1m0s
[11:36:35.999] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:68:0)
[11:36:35.999] Timer tick, asking Buildbucket for the build status
[11:36:36.056] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:36.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:69:0) after 1m0s
[11:37:36.087] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:69:0)
[11:37:36.087] Timer tick, asking Buildbucket for the build status
[11:37:36.158] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:36.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:70:0) after 1m0s
[11:38:36.185] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:70:0)
[11:38:36.185] Timer tick, asking Buildbucket for the build status
[11:38:36.277] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:36.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:71:0) after 1m0s
[11:39:36.333] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:71:0)
[11:39:36.333] Timer tick, asking Buildbucket for the build status
[11:39:36.472] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:36.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:72:0) after 1m0s
[11:40:36.493] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:72:0)
[11:40:36.493] Timer tick, asking Buildbucket for the build status
[11:40:36.737] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:36.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:73:0) after 1m0s
[11:41:36.762] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:73:0)
[11:41:36.762] Timer tick, asking Buildbucket for the build status
[11:41:37.546] Build 8860672725076379040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:37.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026102787229124768:74:0) after 1m0s
[11:42:15.518] Received PubSub notification, asking Buildbucket for the build status
[11:42:15.618] Build 8860672725076379040: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:42:15.618] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608205334236853",
"created_by": "project:chromium",
"created_ts": "1608201018221260",
"id": "8860672725076379040",
"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\": \"16ebe3cdbedd62c16f572ee00c120a741a39ce93\", \"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-4-c765\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"win-upload-perfetto\", \"buildnumber\": 1895, \"got_angle_revision\": \"c47b951ecbcbbba653db801bc7eb5f4fa5d72042\", \"got_dawn_revision\": \"b31cd871ad1000a56302409b62d76e079f8a0b46\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"16ebe3cdbedd62c16f572ee00c120a741a39ce93\", \"got_revision_cp\": \"refs/heads/master@{#838016}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1ca6504eb7bb9971d317cf8c8033b38b914036b7\", \"got_v8_revision\": \"448c4502537e675b5c79ee3934ccf84f6be3fa51\", \"got_v8_revision_cp\": \"refs/heads/8.9.162@{#1}\", \"got_webrtc_revision\": \"8592111e458d253e53806db65d23d21bfe9d9db4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32849}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"swarm_hashes_refs/heads/master(at){#838016}_without_patch\": {\"upload_trace_processor\": \"9eacb6c138410c267dea56f7c229fc157ecd6cd2\"}}, \"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\", \"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-4-c765\"], \"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": "1608202381098026",
"status": "COMPLETED",
"status_changed_ts": "1608205334812201",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/1895",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9026102787229124768",
"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/8860672725076379040/+/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:508911ac79f79210",
"user_agent:luci-scheduler"
],
"updated_ts": "1608205334895959",
"url": "https://ci.chromium.org/b/8860672725076379040",
"utcnow_ts": "1608205335610849"
}
}
[11:42:15.618] Invocation finished in 1h11m58.834039417s with status SUCCEEDED