[20:46:55.355] New invocation is queued and will start shortly
[20:46:56.786] Starting the invocation (attempt 1)
[20:46:56.822] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:46:56.822] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:46:56.822] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025520409632764608",
"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:9025520409632764608",
"scheduler_job_id:chromium/win-upload-perfetto",
"user_agent:luci-scheduler"
]
}
[20:46:57.476] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608756416939229",
"id": "8860090347310133264",
"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": "1608756417261612",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/1934",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9025520409632764608",
"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/8860090347310133264/+/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": "1608756417261768",
"url": "https://ci.chromium.org/b/8860090347310133264",
"utcnow_ts": "1608756417467242"
}
}
[20:46:57.476] Task URL: https://ci.chromium.org/b/8860090347310133264
[20:46:57.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:2:0) after 1m0s
[20:47:57.565] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:2:0)
[20:47:57.565] Timer tick, asking Buildbucket for the build status
[20:47:57.815] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:47:57.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:3:0) after 1m0s
[20:48:57.872] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:3:0)
[20:48:57.872] Timer tick, asking Buildbucket for the build status
[20:48:57.956] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:48:57.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:4:0) after 1m0s
[20:49:57.977] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:4:0)
[20:49:57.977] Timer tick, asking Buildbucket for the build status
[20:49:58.055] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:49:58.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:5:0) after 1m0s
[20:50:58.174] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:5:0)
[20:50:58.174] Timer tick, asking Buildbucket for the build status
[20:50:58.236] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:50:58.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:6:0) after 1m0s
[20:51:58.413] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:6:0)
[20:51:58.413] Timer tick, asking Buildbucket for the build status
[20:51:58.482] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:51:58.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:7:0) after 1m0s
[20:52:58.600] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:7:0)
[20:52:58.601] Timer tick, asking Buildbucket for the build status
[20:52:58.806] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:52:58.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:8:0) after 1m0s
[20:53:58.894] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:8:0)
[20:53:58.894] Timer tick, asking Buildbucket for the build status
[20:53:58.999] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:53:58.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:9:0) after 1m0s
[20:54:59.007] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:9:0)
[20:54:59.007] Timer tick, asking Buildbucket for the build status
[20:54:59.092] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:54:59.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:10:0) after 1m0s
[20:55:59.126] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:10:0)
[20:55:59.126] Timer tick, asking Buildbucket for the build status
[20:55:59.222] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:55:59.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:11:0) after 1m0s
[20:56:59.192] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:11:0)
[20:56:59.192] Timer tick, asking Buildbucket for the build status
[20:56:59.332] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:56:59.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:12:0) after 1m0s
[20:57:59.422] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:12:0)
[20:57:59.422] Timer tick, asking Buildbucket for the build status
[20:57:59.516] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:57:59.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:13:0) after 1m0s
[20:58:59.581] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:13:0)
[20:58:59.581] Timer tick, asking Buildbucket for the build status
[20:58:59.655] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:58:59.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:14:0) after 1m0s
[21:00:00.086] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:14:0)
[21:00:00.086] Timer tick, asking Buildbucket for the build status
[21:00:00.454] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:00:00.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:15:0) after 1m0s
[21:01:00.690] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:15:0)
[21:01:00.690] Timer tick, asking Buildbucket for the build status
[21:01:00.902] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:01:00.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:16:0) after 1m0s
[21:02:01.314] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:16:0)
[21:02:01.314] Timer tick, asking Buildbucket for the build status
[21:02:01.512] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:02:01.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:17:0) after 1m0s
[21:03:01.511] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:17:0)
[21:03:01.511] Timer tick, asking Buildbucket for the build status
[21:03:01.711] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:03:01.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:18:0) after 1m0s
[21:04:01.928] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:18:0)
[21:04:01.928] Timer tick, asking Buildbucket for the build status
[21:04:02.257] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:04:02.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:19:0) after 1m0s
[21:05:02.313] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:19:0)
[21:05:02.313] Timer tick, asking Buildbucket for the build status
[21:05:02.662] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:05:02.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:20:0) after 1m0s
[21:06:02.739] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:20:0)
[21:06:02.739] Timer tick, asking Buildbucket for the build status
[21:06:03.129] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:06:03.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:21:0) after 1m0s
[21:07:03.201] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:21:0)
[21:07:03.201] Timer tick, asking Buildbucket for the build status
[21:07:03.618] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:07:03.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:22:0) after 1m0s
[21:08:03.646] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:22:0)
[21:08:03.646] Timer tick, asking Buildbucket for the build status
[21:08:03.723] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:08:03.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:23:0) after 1m0s
[21:09:03.719] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:23:0)
[21:09:03.719] Timer tick, asking Buildbucket for the build status
[21:09:03.809] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:09:03.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:24:0) after 1m0s
[21:10:04.006] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:24:0)
[21:10:04.006] Timer tick, asking Buildbucket for the build status
[21:10:04.099] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:10:04.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:25:0) after 1m0s
[21:11:04.112] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:25:0)
[21:11:04.112] Timer tick, asking Buildbucket for the build status
[21:11:04.205] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:11:04.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:26:0) after 1m0s
[21:12:04.224] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:26:0)
[21:12:04.225] Timer tick, asking Buildbucket for the build status
[21:12:04.344] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:12:04.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:27:0) after 1m0s
[21:13:04.334] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:27:0)
[21:13:04.334] Timer tick, asking Buildbucket for the build status
[21:13:04.464] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:13:04.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:28:0) after 1m0s
[21:14:04.554] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:28:0)
[21:14:04.554] Timer tick, asking Buildbucket for the build status
[21:14:04.810] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:14:04.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:29:0) after 1m0s
[21:15:04.876] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:29:0)
[21:15:04.876] Timer tick, asking Buildbucket for the build status
[21:15:04.981] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:15:04.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:30:0) after 1m0s
[21:16:04.991] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:30:0)
[21:16:04.991] Timer tick, asking Buildbucket for the build status
[21:16:05.065] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:16:05.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:31:0) after 1m0s
[21:17:05.164] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:31:0)
[21:17:05.165] Timer tick, asking Buildbucket for the build status
[21:17:05.285] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:17:05.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:32:0) after 1m0s
[21:18:05.322] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:32:0)
[21:18:05.322] Timer tick, asking Buildbucket for the build status
[21:18:05.416] Build 8860090347310133264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:18:05.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:33:0) after 1m0s
[21:18:51.941] Received PubSub notification, asking Buildbucket for the build status
[21:18:52.107] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:05.500] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:33:0)
[21:19:05.500] Timer tick, asking Buildbucket for the build status
[21:19:05.604] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:05.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:35:0) after 1m0s
[21:20:05.663] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:35:0)
[21:20:05.663] Timer tick, asking Buildbucket for the build status
[21:20:05.795] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:05.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:36:0) after 1m0s
[21:21:05.914] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:36:0)
[21:21:05.914] Timer tick, asking Buildbucket for the build status
[21:21:06.085] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:06.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:37:0) after 1m0s
[21:22:06.106] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:37:0)
[21:22:06.106] Timer tick, asking Buildbucket for the build status
[21:22:06.229] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:06.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:38:0) after 1m0s
[21:23:06.276] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:38:0)
[21:23:06.276] Timer tick, asking Buildbucket for the build status
[21:23:06.339] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:06.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:39:0) after 1m0s
[21:24:06.349] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:39:0)
[21:24:06.349] Timer tick, asking Buildbucket for the build status
[21:24:06.502] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:06.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:40:0) after 1m0s
[21:25:06.565] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:40:0)
[21:25:06.565] Timer tick, asking Buildbucket for the build status
[21:25:06.639] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:06.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:41:0) after 1m0s
[21:26:06.700] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:41:0)
[21:26:06.700] Timer tick, asking Buildbucket for the build status
[21:26:06.803] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:06.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:42:0) after 1m0s
[21:27:06.926] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:42:0)
[21:27:06.926] Timer tick, asking Buildbucket for the build status
[21:27:07.027] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:07.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:43:0) after 1m0s
[21:28:07.101] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:43:0)
[21:28:07.101] Timer tick, asking Buildbucket for the build status
[21:28:07.298] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:07.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:44:0) after 1m0s
[21:29:07.354] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:44:0)
[21:29:07.354] Timer tick, asking Buildbucket for the build status
[21:29:07.491] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:07.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:45:0) after 1m0s
[21:30:07.532] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:45:0)
[21:30:07.532] Timer tick, asking Buildbucket for the build status
[21:30:07.666] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:07.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:46:0) after 1m0s
[21:31:07.754] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:46:0)
[21:31:07.754] Timer tick, asking Buildbucket for the build status
[21:31:07.819] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:07.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:47:0) after 1m0s
[21:32:07.882] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:47:0)
[21:32:07.882] Timer tick, asking Buildbucket for the build status
[21:32:08.309] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:08.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:48:0) after 1m0s
[21:33:08.304] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:48:0)
[21:33:08.304] Timer tick, asking Buildbucket for the build status
[21:33:08.383] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:08.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:49:0) after 1m0s
[21:34:08.447] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:49:0)
[21:34:08.447] Timer tick, asking Buildbucket for the build status
[21:34:08.598] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:08.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:50:0) after 1m0s
[21:35:08.688] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:50:0)
[21:35:08.688] Timer tick, asking Buildbucket for the build status
[21:35:08.786] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:08.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:51:0) after 1m0s
[21:36:08.853] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:51:0)
[21:36:08.853] Timer tick, asking Buildbucket for the build status
[21:36:08.943] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:08.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:52:0) after 1m0s
[21:37:09.101] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:52:0)
[21:37:09.102] Timer tick, asking Buildbucket for the build status
[21:37:09.233] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:09.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:53:0) after 1m0s
[21:38:09.403] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:53:0)
[21:38:09.403] Timer tick, asking Buildbucket for the build status
[21:38:09.635] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:09.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:54:0) after 1m0s
[21:39:09.724] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:54:0)
[21:39:09.724] Timer tick, asking Buildbucket for the build status
[21:39:09.791] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:09.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:55:0) after 1m0s
[21:40:09.900] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:55:0)
[21:40:09.900] Timer tick, asking Buildbucket for the build status
[21:40:09.994] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:09.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:56:0) after 1m0s
[21:41:10.121] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:56:0)
[21:41:10.121] Timer tick, asking Buildbucket for the build status
[21:41:10.176] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:10.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:57:0) after 1m0s
[21:42:10.257] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:57:0)
[21:42:10.257] Timer tick, asking Buildbucket for the build status
[21:42:10.371] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:10.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:58:0) after 1m0s
[21:43:10.427] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:58:0)
[21:43:10.427] Timer tick, asking Buildbucket for the build status
[21:43:10.505] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:10.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:59:0) after 1m0s
[21:44:10.634] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:59:0)
[21:44:10.634] Timer tick, asking Buildbucket for the build status
[21:44:10.903] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:10.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:60:0) after 1m0s
[21:45:10.918] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:60:0)
[21:45:10.918] Timer tick, asking Buildbucket for the build status
[21:45:11.105] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:11.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:61:0) after 1m0s
[21:46:11.145] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:61:0)
[21:46:11.145] Timer tick, asking Buildbucket for the build status
[21:46:11.233] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:11.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:62:0) after 1m0s
[21:47:11.376] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:62:0)
[21:47:11.376] Timer tick, asking Buildbucket for the build status
[21:47:11.510] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:11.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:63:0) after 1m0s
[21:48:11.681] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:63:0)
[21:48:11.681] Timer tick, asking Buildbucket for the build status
[21:48:12.232] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:12.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:64:0) after 1m0s
[21:49:12.266] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:64:0)
[21:49:12.266] Timer tick, asking Buildbucket for the build status
[21:49:12.486] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:12.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:65:0) after 1m0s
[21:50:12.544] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:65:0)
[21:50:12.544] Timer tick, asking Buildbucket for the build status
[21:50:13.360] Build 8860090347310133264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:13.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9025520409632764608:66:0) after 1m0s
[21:50:31.888] Received PubSub notification, asking Buildbucket for the build status
[21:50:32.332] Build 8860090347310133264: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:50:32.332] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608760230665404",
"created_by": "project:chromium",
"created_ts": "1608756416939229",
"id": "8860090347310133264",
"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\": \"13665f892152c8d74ddab5226f02e91c70ca1eb8\", \"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-1-gob7\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"win-upload-perfetto\", \"buildnumber\": 1934, \"got_angle_revision\": \"d447829c19fd6275b94676aab20e303c224fe126\", \"got_dawn_revision\": \"33bf309c1d45e4da80ba77913eab9dadfc8f5e26\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"13665f892152c8d74ddab5226f02e91c70ca1eb8\", \"got_revision_cp\": \"refs/heads/master@{#839179}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"711cf5ad4a12abe0f6b50058cb2248d7fb80374e\", \"got_v8_revision_cp\": \"refs/heads/8.9.187@{#1}\", \"got_webrtc_revision\": \"c463a784c340874c75032da7602ec1fde43f75ed\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32874}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"swarm_hashes_refs/heads/master(at){#839179}_without_patch\": {\"upload_trace_processor\": \"a3d4db2886277af171315062d62c36b7dd6fa5cb\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1bdf55b197d439c94705ddfba40ad846e2fdb8dd164b64a485766434889345a8_v2\", \"builder_1e6a29180cab26dd3348fa54abb67ed061f7a719cdb336cb75143b23c3452e80_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-1-gob7\"], \"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": "1608758331298828",
"status": "COMPLETED",
"status_changed_ts": "1608760231382690",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/1934",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9025520409632764608",
"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/8860090347310133264/+/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:50aa2c5bfd22f210",
"user_agent:luci-scheduler"
],
"updated_ts": "1608760231387032",
"url": "https://ci.chromium.org/b/8860090347310133264",
"utcnow_ts": "1608760232162130"
}
}
[21:50:32.332] Invocation finished in 1h3m36.991952696s with status SUCCEEDED