[03:57:48.095] New invocation is queued and will start shortly
[03:57:49.236] Starting the invocation (attempt 1)
[03:57:49.267] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:57:49.267] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:57:49.267] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019876289154916800",
"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:9019876289154916800",
"scheduler_job_id:chromium/win-upload-perfetto",
"user_agent:luci-scheduler"
]
}
[03:57:49.951] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1614139069347573",
"id": "8854446227178644080",
"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": "1614139069726111",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/2330",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9019876289154916800",
"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/8854446227178644080/+/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": "1614139069726229",
"url": "https://ci.chromium.org/b/8854446227178644080",
"utcnow_ts": "1614139069935456"
}
}
[03:57:49.951] Task URL: https://ci.chromium.org/b/8854446227178644080
[03:57:49.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:2:0) after 1m0s
[03:58:49.976] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:2:0)
[03:58:49.976] Timer tick, asking Buildbucket for the build status
[03:58:50.071] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:58:50.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:3:0) after 1m0s
[03:59:50.259] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:3:0)
[03:59:50.259] Timer tick, asking Buildbucket for the build status
[03:59:50.337] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:59:50.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:4:0) after 1m0s
[04:00:50.352] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:4:0)
[04:00:50.352] Timer tick, asking Buildbucket for the build status
[04:00:50.433] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:00:50.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:5:0) after 1m0s
[04:01:50.436] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:5:0)
[04:01:50.436] Timer tick, asking Buildbucket for the build status
[04:01:50.495] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:01:50.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:6:0) after 1m0s
[04:02:50.506] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:6:0)
[04:02:50.506] Timer tick, asking Buildbucket for the build status
[04:02:50.583] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:02:50.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:7:0) after 1m0s
[04:03:50.640] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:7:0)
[04:03:50.640] Timer tick, asking Buildbucket for the build status
[04:03:50.724] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:03:50.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:8:0) after 1m0s
[04:04:50.715] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:8:0)
[04:04:50.715] Timer tick, asking Buildbucket for the build status
[04:04:50.772] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:04:50.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:9:0) after 1m0s
[04:05:50.841] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:9:0)
[04:05:50.841] Timer tick, asking Buildbucket for the build status
[04:05:50.945] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:05:50.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:10:0) after 1m0s
[04:06:50.990] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:10:0)
[04:06:50.990] Timer tick, asking Buildbucket for the build status
[04:06:51.063] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:06:51.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:11:0) after 1m0s
[04:07:51.093] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:11:0)
[04:07:51.093] Timer tick, asking Buildbucket for the build status
[04:07:51.219] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:07:51.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:12:0) after 1m0s
[04:08:51.255] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:12:0)
[04:08:51.255] Timer tick, asking Buildbucket for the build status
[04:08:51.329] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:08:51.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:13:0) after 1m0s
[04:09:51.357] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:13:0)
[04:09:51.357] Timer tick, asking Buildbucket for the build status
[04:09:51.439] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:09:51.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:14:0) after 1m0s
[04:10:51.473] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:14:0)
[04:10:51.473] Timer tick, asking Buildbucket for the build status
[04:10:51.816] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:10:51.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:15:0) after 1m0s
[04:11:51.860] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:15:0)
[04:11:51.860] Timer tick, asking Buildbucket for the build status
[04:11:51.943] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:11:51.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:16:0) after 1m0s
[04:12:52.001] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:16:0)
[04:12:52.001] Timer tick, asking Buildbucket for the build status
[04:12:52.342] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:12:52.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:17:0) after 1m0s
[04:13:52.394] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:17:0)
[04:13:52.394] Timer tick, asking Buildbucket for the build status
[04:13:52.685] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:13:52.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:18:0) after 1m0s
[04:14:52.702] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:18:0)
[04:14:52.702] Timer tick, asking Buildbucket for the build status
[04:14:52.797] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:14:52.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:19:0) after 1m0s
[04:15:52.789] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:19:0)
[04:15:52.789] Timer tick, asking Buildbucket for the build status
[04:15:52.934] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:15:52.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:20:0) after 1m0s
[04:16:52.960] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:20:0)
[04:16:52.960] Timer tick, asking Buildbucket for the build status
[04:16:53.040] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:16:53.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:21:0) after 1m0s
[04:17:53.044] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:21:0)
[04:17:53.044] Timer tick, asking Buildbucket for the build status
[04:17:53.233] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:17:53.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:22:0) after 1m0s
[04:18:53.245] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:22:0)
[04:18:53.245] Timer tick, asking Buildbucket for the build status
[04:18:53.321] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:18:53.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:23:0) after 1m0s
[04:19:53.334] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:23:0)
[04:19:53.334] Timer tick, asking Buildbucket for the build status
[04:19:53.400] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:19:53.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:24:0) after 1m0s
[04:20:53.429] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:24:0)
[04:20:53.429] Timer tick, asking Buildbucket for the build status
[04:20:53.523] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:20:53.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:25:0) after 1m0s
[04:21:53.609] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:25:0)
[04:21:53.609] Timer tick, asking Buildbucket for the build status
[04:21:54.202] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:21:54.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:26:0) after 1m0s
[04:22:54.242] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:26:0)
[04:22:54.242] Timer tick, asking Buildbucket for the build status
[04:22:54.342] Build 8854446227178644080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:22:54.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:27:0) after 1m0s
[04:23:00.004] Received PubSub notification, asking Buildbucket for the build status
[04:23:00.591] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:54.435] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:27:0)
[04:23:54.435] Timer tick, asking Buildbucket for the build status
[04:23:54.510] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:54.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:29:0) after 1m0s
[04:24:54.512] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:29:0)
[04:24:54.512] Timer tick, asking Buildbucket for the build status
[04:24:54.730] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:54.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:30:0) after 1m0s
[04:25:54.801] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:30:0)
[04:25:54.801] Timer tick, asking Buildbucket for the build status
[04:25:54.919] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:54.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:31:0) after 1m0s
[04:26:54.985] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:31:0)
[04:26:54.985] Timer tick, asking Buildbucket for the build status
[04:26:55.056] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:55.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:32:0) after 1m0s
[04:27:55.111] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:32:0)
[04:27:55.111] Timer tick, asking Buildbucket for the build status
[04:27:55.190] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:55.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:33:0) after 1m0s
[04:28:55.264] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:33:0)
[04:28:55.264] Timer tick, asking Buildbucket for the build status
[04:28:55.383] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:55.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:34:0) after 1m0s
[04:29:55.482] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:34:0)
[04:29:55.482] Timer tick, asking Buildbucket for the build status
[04:29:55.540] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:55.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:35:0) after 1m0s
[04:30:55.572] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:35:0)
[04:30:55.572] Timer tick, asking Buildbucket for the build status
[04:30:55.849] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:55.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:36:0) after 1m0s
[04:31:55.973] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:36:0)
[04:31:55.973] Timer tick, asking Buildbucket for the build status
[04:31:56.126] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:56.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:37:0) after 1m0s
[04:32:56.149] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:37:0)
[04:32:56.149] Timer tick, asking Buildbucket for the build status
[04:32:56.236] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:56.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:38:0) after 1m0s
[04:33:56.355] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:38:0)
[04:33:56.355] Timer tick, asking Buildbucket for the build status
[04:33:56.424] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:56.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:39:0) after 1m0s
[04:34:58.557] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:39:0)
[04:34:58.557] Timer tick, asking Buildbucket for the build status
[04:34:58.732] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:58.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:40:0) after 1m0s
[04:35:58.749] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:40:0)
[04:35:58.749] Timer tick, asking Buildbucket for the build status
[04:35:58.816] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:58.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:41:0) after 1m0s
[04:36:58.948] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:41:0)
[04:36:58.948] Timer tick, asking Buildbucket for the build status
[04:36:59.258] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:59.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:42:0) after 1m0s
[04:37:59.290] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:42:0)
[04:37:59.290] Timer tick, asking Buildbucket for the build status
[04:37:59.382] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:59.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:43:0) after 1m0s
[04:38:59.416] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:43:0)
[04:38:59.416] Timer tick, asking Buildbucket for the build status
[04:38:59.509] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:59.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:44:0) after 1m0s
[04:39:59.527] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:44:0)
[04:39:59.527] Timer tick, asking Buildbucket for the build status
[04:39:59.602] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:59.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:45:0) after 1m0s
[04:40:59.617] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:45:0)
[04:40:59.617] Timer tick, asking Buildbucket for the build status
[04:40:59.943] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:59.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:46:0) after 1m0s
[04:42:00.003] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:46:0)
[04:42:00.003] Timer tick, asking Buildbucket for the build status
[04:42:00.705] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:00.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:47:0) after 1m0s
[04:43:00.746] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:47:0)
[04:43:00.746] Timer tick, asking Buildbucket for the build status
[04:43:00.918] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:00.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:48:0) after 1m0s
[04:44:01.737] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:48:0)
[04:44:01.737] Timer tick, asking Buildbucket for the build status
[04:44:01.847] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:01.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:49:0) after 1m0s
[04:45:01.923] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:49:0)
[04:45:01.923] Timer tick, asking Buildbucket for the build status
[04:45:02.190] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:02.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:50:0) after 1m0s
[04:46:02.289] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:50:0)
[04:46:02.289] Timer tick, asking Buildbucket for the build status
[04:46:02.383] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:02.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:51:0) after 1m0s
[04:47:02.413] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:51:0)
[04:47:02.413] Timer tick, asking Buildbucket for the build status
[04:47:02.496] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:02.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:52:0) after 1m0s
[04:48:02.617] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:52:0)
[04:48:02.617] Timer tick, asking Buildbucket for the build status
[04:48:02.891] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:02.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:53:0) after 1m0s
[04:49:02.962] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:53:0)
[04:49:02.962] Timer tick, asking Buildbucket for the build status
[04:49:03.042] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:03.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:54:0) after 1m0s
[04:50:03.229] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:54:0)
[04:50:03.229] Timer tick, asking Buildbucket for the build status
[04:50:03.326] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:03.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:55:0) after 1m0s
[04:51:03.357] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:55:0)
[04:51:03.357] Timer tick, asking Buildbucket for the build status
[04:51:03.433] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:03.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:56:0) after 1m0s
[04:52:03.768] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:56:0)
[04:52:03.768] Timer tick, asking Buildbucket for the build status
[04:52:03.864] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:03.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:57:0) after 1m0s
[04:53:03.963] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:57:0)
[04:53:03.963] Timer tick, asking Buildbucket for the build status
[04:53:04.409] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:04.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:58:0) after 1m0s
[04:54:04.481] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:58:0)
[04:54:04.481] Timer tick, asking Buildbucket for the build status
[04:54:04.624] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:04.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:59:0) after 1m0s
[04:55:04.718] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:59:0)
[04:55:04.718] Timer tick, asking Buildbucket for the build status
[04:55:09.703] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:09.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:60:0) after 1m0s
[04:56:09.713] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:60:0)
[04:56:09.713] Timer tick, asking Buildbucket for the build status
[04:56:09.818] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:09.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:61:0) after 1m0s
[04:57:09.900] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:61:0)
[04:57:09.900] Timer tick, asking Buildbucket for the build status
[04:57:09.971] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:09.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:62:0) after 1m0s
[04:58:10.028] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:62:0)
[04:58:10.028] Timer tick, asking Buildbucket for the build status
[04:58:10.128] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:10.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:63:0) after 1m0s
[04:59:10.180] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:63:0)
[04:59:10.181] Timer tick, asking Buildbucket for the build status
[04:59:10.268] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:10.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:64:0) after 1m0s
[05:00:10.296] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:64:0)
[05:00:10.296] Timer tick, asking Buildbucket for the build status
[05:00:10.383] Build 8854446227178644080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:10.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9019876289154916800:65:0) after 1m0s
[05:00:44.470] Received PubSub notification, asking Buildbucket for the build status
[05:00:44.569] Build 8854446227178644080: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:00:44.569] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1614142841657292",
"created_by": "project:chromium",
"created_ts": "1614139069347573",
"id": "8854446227178644080",
"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\": \"c59814057fc8d58e1a2fc99ff60396883c36616e\", \"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-2-5ewu\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"win-upload-perfetto\", \"buildnumber\": 2330, \"got_angle_revision\": \"f19f319a59c83d9ccdce08a3b41024cfc997fd8d\", \"got_dawn_revision\": \"d67544797e54d3866f378c2a7c05181db552f125\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"c59814057fc8d58e1a2fc99ff60396883c36616e\", \"got_revision_cp\": \"refs/heads/master@{#857019}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"22186f076bf3bbe1a818792bde93b297e3773f7e\", \"got_v8_revision\": \"e6d93982be7f6f3686225c97ffe908935ef372dc\", \"got_v8_revision_cp\": \"refs/heads/9.0.242@{#1}\", \"got_webrtc_revision\": \"d0844a80deaab28d7df8e5dff9f71e6ab6919025\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33323}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"swarm_hashes_refs/heads/master(at){#857019}_without_patch\": {\"upload_trace_processor\": \"fef75e56905d124f8b3f15a78050e7e9b3b75cd3\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1bdf55b197d439c94705ddfba40ad846e2fdb8dd164b64a485766434889345a8_v2\", \"builder_1e6a29180cab26dd3348fa54abb67ed061f7a719cdb336cb75143b23c3452e80_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-2-5ewu\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5709-d3c4474\"], \"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": "1614140578919111",
"status": "COMPLETED",
"status_changed_ts": "1614142843910945",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/2330",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9019876289154916800",
"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/8854446227178644080/+/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:51eb011e83713c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614142843914830",
"url": "https://ci.chromium.org/b/8854446227178644080",
"utcnow_ts": "1614142844542168"
}
}
[05:00:44.569] Invocation finished in 1h2m56.490411393s with status SUCCEEDED