[18:36:20.906] New invocation is queued and will start shortly
[18:36:22.482] Starting the invocation (attempt 1)
[18:36:22.519] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:36:22.519] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:36:22.519] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026162803416390448",
"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:9026162803416390448",
"scheduler_job_id:chromium/win-upload-perfetto",
"user_agent:luci-scheduler"
]
}
[18:36:23.183] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608143782691586",
"id": "8860732740879551312",
"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": "1608143783075066",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/1891",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9026162803416390448",
"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/8860732740879551312/+/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": "1608143783075191",
"url": "https://ci.chromium.org/b/8860732740879551312",
"utcnow_ts": "1608143783175782"
}
}
[18:36:23.183] Task URL: https://ci.chromium.org/b/8860732740879551312
[18:36:23.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:2:0) after 1m0s
[18:37:23.165] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:2:0)
[18:37:23.165] Timer tick, asking Buildbucket for the build status
[18:37:23.296] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:37:23.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:3:0) after 1m0s
[18:38:23.294] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:3:0)
[18:38:23.294] Timer tick, asking Buildbucket for the build status
[18:38:23.430] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:38:23.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:4:0) after 1m0s
[18:39:23.512] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:4:0)
[18:39:23.512] Timer tick, asking Buildbucket for the build status
[18:39:23.580] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:39:23.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:5:0) after 1m0s
[18:40:23.623] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:5:0)
[18:40:23.623] Timer tick, asking Buildbucket for the build status
[18:40:23.725] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:40:23.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:6:0) after 1m0s
[18:41:23.760] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:6:0)
[18:41:23.760] Timer tick, asking Buildbucket for the build status
[18:41:23.874] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:41:23.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:7:0) after 1m0s
[18:42:23.936] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:7:0)
[18:42:23.936] Timer tick, asking Buildbucket for the build status
[18:42:24.047] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:42:24.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:8:0) after 1m0s
[18:43:24.088] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:8:0)
[18:43:24.088] Timer tick, asking Buildbucket for the build status
[18:43:24.446] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:43:24.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:9:0) after 1m0s
[18:44:24.514] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:9:0)
[18:44:24.514] Timer tick, asking Buildbucket for the build status
[18:44:24.694] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:44:24.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:10:0) after 1m0s
[18:45:24.724] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:10:0)
[18:45:24.724] Timer tick, asking Buildbucket for the build status
[18:45:24.818] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:45:24.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:11:0) after 1m0s
[18:46:24.866] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:11:0)
[18:46:24.866] Timer tick, asking Buildbucket for the build status
[18:46:25.317] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:46:25.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:12:0) after 1m0s
[18:47:25.338] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:12:0)
[18:47:25.338] Timer tick, asking Buildbucket for the build status
[18:47:26.449] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:47:26.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:13:0) after 1m0s
[18:48:26.563] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:13:0)
[18:48:26.563] Timer tick, asking Buildbucket for the build status
[18:48:26.900] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:48:26.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:14:0) after 1m0s
[18:49:26.907] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:14:0)
[18:49:26.907] Timer tick, asking Buildbucket for the build status
[18:49:27.017] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:49:27.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:15:0) after 1m0s
[18:50:27.010] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:15:0)
[18:50:27.010] Timer tick, asking Buildbucket for the build status
[18:50:27.081] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:50:27.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:16:0) after 1m0s
[18:51:27.153] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:16:0)
[18:51:27.153] Timer tick, asking Buildbucket for the build status
[18:51:27.287] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:51:27.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:17:0) after 1m0s
[18:52:27.317] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:17:0)
[18:52:27.317] Timer tick, asking Buildbucket for the build status
[18:52:27.392] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:52:27.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:18:0) after 1m0s
[18:53:27.931] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:18:0)
[18:53:27.931] Timer tick, asking Buildbucket for the build status
[18:53:28.234] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:53:28.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:19:0) after 1m0s
[18:54:28.384] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:19:0)
[18:54:28.384] Timer tick, asking Buildbucket for the build status
[18:54:28.463] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:54:28.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:20:0) after 1m0s
[18:55:28.529] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:20:0)
[18:55:28.529] Timer tick, asking Buildbucket for the build status
[18:55:28.647] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:55:28.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:21:0) after 1m0s
[18:56:28.805] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:21:0)
[18:56:28.805] Timer tick, asking Buildbucket for the build status
[18:56:29.061] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:56:29.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:22:0) after 1m0s
[18:57:29.224] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:22:0)
[18:57:29.224] Timer tick, asking Buildbucket for the build status
[18:57:29.356] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:57:29.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:23:0) after 1m0s
[18:58:29.596] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:23:0)
[18:58:29.596] Timer tick, asking Buildbucket for the build status
[18:58:29.665] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:58:29.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:24:0) after 1m0s
[18:59:29.899] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:24:0)
[18:59:29.899] Timer tick, asking Buildbucket for the build status
[18:59:29.977] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:59:29.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:25:0) after 1m0s
[19:00:30.196] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:25:0)
[19:00:30.196] Timer tick, asking Buildbucket for the build status
[19:00:30.400] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:00:30.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:26:0) after 1m0s
[19:01:30.417] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:26:0)
[19:01:30.417] Timer tick, asking Buildbucket for the build status
[19:01:30.634] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:01:30.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:27:0) after 1m0s
[19:02:30.855] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:27:0)
[19:02:30.855] Timer tick, asking Buildbucket for the build status
[19:02:30.958] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:02:30.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:28:0) after 1m0s
[19:03:31.883] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:28:0)
[19:03:31.883] Timer tick, asking Buildbucket for the build status
[19:03:31.991] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:03:31.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:29:0) after 1m0s
[19:04:32.033] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:29:0)
[19:04:32.033] Timer tick, asking Buildbucket for the build status
[19:04:32.137] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:04:32.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:30:0) after 1m0s
[19:05:32.209] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:30:0)
[19:05:32.209] Timer tick, asking Buildbucket for the build status
[19:05:32.281] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:05:32.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:31:0) after 1m0s
[19:06:32.304] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:31:0)
[19:06:32.304] Timer tick, asking Buildbucket for the build status
[19:06:32.427] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:06:32.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:32:0) after 1m0s
[19:07:33.538] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:32:0)
[19:07:33.538] Timer tick, asking Buildbucket for the build status
[19:07:33.612] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:07:33.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:33:0) after 1m0s
[19:08:33.818] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:33:0)
[19:08:33.818] Timer tick, asking Buildbucket for the build status
[19:08:33.911] Build 8860732740879551312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:08:33.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:34:0) after 1m0s
[19:09:02.498] Received PubSub notification, asking Buildbucket for the build status
[19:09:02.876] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:33.946] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:34:0)
[19:09:33.946] Timer tick, asking Buildbucket for the build status
[19:09:34.034] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:34.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:36:0) after 1m0s
[19:10:34.026] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:36:0)
[19:10:34.026] Timer tick, asking Buildbucket for the build status
[19:10:34.103] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:34.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:37:0) after 1m0s
[19:11:34.161] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:37:0)
[19:11:34.161] Timer tick, asking Buildbucket for the build status
[19:11:34.276] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:34.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:38:0) after 1m0s
[19:12:34.390] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:38:0)
[19:12:34.390] Timer tick, asking Buildbucket for the build status
[19:12:34.529] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:34.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:39:0) after 1m0s
[19:13:34.652] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:39:0)
[19:13:34.652] Timer tick, asking Buildbucket for the build status
[19:13:35.009] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:35.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:40:0) after 1m0s
[19:14:35.190] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:40:0)
[19:14:35.190] Timer tick, asking Buildbucket for the build status
[19:14:35.317] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:35.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:41:0) after 1m0s
[19:15:35.351] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:41:0)
[19:15:35.351] Timer tick, asking Buildbucket for the build status
[19:15:35.420] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:35.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:42:0) after 1m0s
[19:16:35.521] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:42:0)
[19:16:35.521] Timer tick, asking Buildbucket for the build status
[19:16:35.631] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:35.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:43:0) after 1m0s
[19:17:36.516] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:43:0)
[19:17:36.516] Timer tick, asking Buildbucket for the build status
[19:17:36.811] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:36.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:44:0) after 1m0s
[19:18:36.978] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:44:0)
[19:18:36.978] Timer tick, asking Buildbucket for the build status
[19:18:37.158] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:37.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:45:0) after 1m0s
[19:19:37.400] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:45:0)
[19:19:37.400] Timer tick, asking Buildbucket for the build status
[19:19:38.133] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:38.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:46:0) after 1m0s
[19:20:38.310] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:46:0)
[19:20:38.310] Timer tick, asking Buildbucket for the build status
[19:20:38.409] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:38.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:47:0) after 1m0s
[19:21:38.438] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:47:0)
[19:21:38.438] Timer tick, asking Buildbucket for the build status
[19:21:38.578] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:38.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:48:0) after 1m0s
[19:22:38.595] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:48:0)
[19:22:38.595] Timer tick, asking Buildbucket for the build status
[19:22:39.546] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:39.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:49:0) after 1m0s
[19:23:39.580] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:49:0)
[19:23:39.580] Timer tick, asking Buildbucket for the build status
[19:23:39.653] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:39.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:50:0) after 1m0s
[19:24:39.698] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:50:0)
[19:24:39.698] Timer tick, asking Buildbucket for the build status
[19:24:39.812] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:39.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:51:0) after 1m0s
[19:25:39.813] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:51:0)
[19:25:39.813] Timer tick, asking Buildbucket for the build status
[19:25:39.888] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:39.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:52:0) after 1m0s
[19:26:39.904] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:52:0)
[19:26:39.904] Timer tick, asking Buildbucket for the build status
[19:26:40.164] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:40.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:53:0) after 1m0s
[19:27:40.185] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:53:0)
[19:27:40.185] Timer tick, asking Buildbucket for the build status
[19:27:40.313] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:40.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:54:0) after 1m0s
[19:28:40.349] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:54:0)
[19:28:40.349] Timer tick, asking Buildbucket for the build status
[19:28:40.478] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:40.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:55:0) after 1m0s
[19:29:40.540] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:55:0)
[19:29:40.540] Timer tick, asking Buildbucket for the build status
[19:29:40.665] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:40.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:56:0) after 1m0s
[19:30:40.710] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:56:0)
[19:30:40.710] Timer tick, asking Buildbucket for the build status
[19:30:40.775] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:40.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:57:0) after 1m0s
[19:31:40.917] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:57:0)
[19:31:40.917] Timer tick, asking Buildbucket for the build status
[19:31:41.062] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:41.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:58:0) after 1m0s
[19:32:41.125] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:58:0)
[19:32:41.125] Timer tick, asking Buildbucket for the build status
[19:32:41.196] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:41.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:59:0) after 1m0s
[19:33:41.280] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:59:0)
[19:33:41.280] Timer tick, asking Buildbucket for the build status
[19:33:41.391] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:41.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:60:0) after 1m0s
[19:34:41.413] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:60:0)
[19:34:41.413] Timer tick, asking Buildbucket for the build status
[19:34:41.476] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:41.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:61:0) after 1m0s
[19:35:41.606] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:61:0)
[19:35:41.606] Timer tick, asking Buildbucket for the build status
[19:35:41.801] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:41.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:62:0) after 1m0s
[19:36:41.822] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:62:0)
[19:36:41.822] Timer tick, asking Buildbucket for the build status
[19:36:42.226] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:42.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:63:0) after 1m0s
[19:37:42.285] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:63:0)
[19:37:42.285] Timer tick, asking Buildbucket for the build status
[19:37:42.539] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:42.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:64:0) after 1m0s
[19:38:42.557] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:64:0)
[19:38:42.557] Timer tick, asking Buildbucket for the build status
[19:38:42.705] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:42.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:65:0) after 1m0s
[19:39:42.820] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:65:0)
[19:39:42.820] Timer tick, asking Buildbucket for the build status
[19:39:43.110] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:43.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:66:0) after 1m0s
[19:40:43.321] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:66:0)
[19:40:43.321] Timer tick, asking Buildbucket for the build status
[19:40:43.415] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:43.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:67:0) after 1m0s
[19:41:43.484] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:67:0)
[19:41:43.484] Timer tick, asking Buildbucket for the build status
[19:41:44.171] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:44.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:68:0) after 1m0s
[19:42:44.222] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:68:0)
[19:42:44.222] Timer tick, asking Buildbucket for the build status
[19:42:44.344] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:44.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:69:0) after 1m0s
[19:43:44.397] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:69:0)
[19:43:44.397] Timer tick, asking Buildbucket for the build status
[19:43:44.528] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:44.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:70:0) after 1m0s
[19:44:44.573] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:70:0)
[19:44:44.573] Timer tick, asking Buildbucket for the build status
[19:44:44.675] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:44.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:71:0) after 1m0s
[19:45:44.708] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:71:0)
[19:45:44.708] Timer tick, asking Buildbucket for the build status
[19:45:44.810] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:44.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:72:0) after 1m0s
[19:46:44.862] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:72:0)
[19:46:44.862] Timer tick, asking Buildbucket for the build status
[19:46:44.959] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:44.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:73:0) after 1m0s
[19:47:44.984] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:73:0)
[19:47:44.984] Timer tick, asking Buildbucket for the build status
[19:47:45.082] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:45.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:74:0) after 1m0s
[19:48:45.128] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:74:0)
[19:48:45.128] Timer tick, asking Buildbucket for the build status
[19:48:45.307] Build 8860732740879551312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:45.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9026162803416390448:75:0) after 1m0s
[19:49:42.517] Received PubSub notification, asking Buildbucket for the build status
[19:49:42.601] Build 8860732740879551312: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:49:42.601] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608148181711682",
"created_by": "project:chromium",
"created_ts": "1608143782691586",
"id": "8860732740879551312",
"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\": \"d125613f1cfb552aa5064e47f266e6cf447d11e5\", \"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\": 1891, \"got_angle_revision\": \"c47b951ecbcbbba653db801bc7eb5f4fa5d72042\", \"got_dawn_revision\": \"455dbfdd428fd9d4730af7afa9dbfd6301513971\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"d125613f1cfb552aa5064e47f266e6cf447d11e5\", \"got_revision_cp\": \"refs/heads/master@{#837677}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1ca6504eb7bb9971d317cf8c8033b38b914036b7\", \"got_v8_revision\": \"df6c3b4a8647c5a8acdb6a00501abef416d6faf1\", \"got_v8_revision_cp\": \"refs/heads/8.9.158@{#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){#837677}_without_patch\": {\"upload_trace_processor\": \"acf64f8ea75e1cbe256fc57e2a18a2cbf7304ab1\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-8-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": "1608145741852887",
"status": "COMPLETED",
"status_changed_ts": "1608148182269510",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/1891",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9026162803416390448",
"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/8860732740879551312/+/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:5085a853652b5610",
"user_agent:luci-scheduler"
],
"updated_ts": "1608148182273591",
"url": "https://ci.chromium.org/b/8860732740879551312",
"utcnow_ts": "1608148182592295"
}
}
[19:49:42.601] Invocation finished in 1h13m21.713218604s with status SUCCEEDED