[19:23:56.377] New invocation is queued and will start shortly
[19:23:57.498] Starting the invocation (attempt 1)
[19:23:57.538] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:23:57.538] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:23:57.538] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022988915416392592",
"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:9022988915416392592",
"scheduler_job_id:chromium/win-upload-perfetto",
"user_agent:luci-scheduler"
]
}
[19:23:58.395] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611170637624927",
"id": "8857558853440959024",
"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": "1611170638094987",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/2110",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9022988915416392592",
"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/8857558853440959024/+/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": "1611170638095092",
"url": "https://ci.chromium.org/b/8857558853440959024",
"utcnow_ts": "1611170638386445"
}
}
[19:23:58.395] Task URL: https://ci.chromium.org/b/8857558853440959024
[19:23:58.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:2:0) after 1m0s
[19:24:58.435] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:2:0)
[19:24:58.435] Timer tick, asking Buildbucket for the build status
[19:24:58.581] Build 8857558853440959024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:24:58.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:3:0) after 1m0s
[19:25:58.658] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:3:0)
[19:25:58.658] Timer tick, asking Buildbucket for the build status
[19:25:58.840] Build 8857558853440959024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:25:58.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:4:0) after 1m0s
[19:26:59.020] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:4:0)
[19:26:59.020] Timer tick, asking Buildbucket for the build status
[19:26:59.097] Build 8857558853440959024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:26:59.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:5:0) after 1m0s
[19:27:59.171] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:5:0)
[19:27:59.171] Timer tick, asking Buildbucket for the build status
[19:27:59.277] Build 8857558853440959024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:27:59.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:6:0) after 1m0s
[19:28:59.264] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:6:0)
[19:28:59.264] Timer tick, asking Buildbucket for the build status
[19:28:59.489] Build 8857558853440959024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:28:59.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:7:0) after 1m0s
[19:29:59.513] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:7:0)
[19:29:59.513] Timer tick, asking Buildbucket for the build status
[19:29:59.609] Build 8857558853440959024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:29:59.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:8:0) after 1m0s
[19:30:59.685] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:8:0)
[19:30:59.685] Timer tick, asking Buildbucket for the build status
[19:30:59.892] Build 8857558853440959024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:30:59.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:9:0) after 1m0s
[19:31:59.939] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:9:0)
[19:31:59.939] Timer tick, asking Buildbucket for the build status
[19:32:00.237] Build 8857558853440959024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:32:00.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:10:0) after 1m0s
[19:33:00.277] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:10:0)
[19:33:00.277] Timer tick, asking Buildbucket for the build status
[19:33:00.425] Build 8857558853440959024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:33:00.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:11:0) after 1m0s
[19:34:00.492] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:11:0)
[19:34:00.492] Timer tick, asking Buildbucket for the build status
[19:34:01.572] Build 8857558853440959024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:34:01.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:12:0) after 1m0s
[19:35:01.828] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:12:0)
[19:35:01.828] Timer tick, asking Buildbucket for the build status
[19:35:03.476] Build 8857558853440959024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:35:03.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:13:0) after 1m0s
[19:36:03.477] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:13:0)
[19:36:03.477] Timer tick, asking Buildbucket for the build status
[19:36:03.608] Build 8857558853440959024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:36:03.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:14:0) after 1m0s
[19:37:03.647] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:14:0)
[19:37:03.647] Timer tick, asking Buildbucket for the build status
[19:37:03.746] Build 8857558853440959024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:37:03.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:15:0) after 1m0s
[19:38:03.800] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:15:0)
[19:38:03.800] Timer tick, asking Buildbucket for the build status
[19:38:04.033] Build 8857558853440959024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:38:04.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:16:0) after 1m0s
[19:39:04.240] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:16:0)
[19:39:04.240] Timer tick, asking Buildbucket for the build status
[19:39:04.396] Build 8857558853440959024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:39:04.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:17:0) after 1m0s
[19:39:35.501] Received PubSub notification, asking Buildbucket for the build status
[19:39:35.573] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:04.735] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:17:0)
[19:40:04.735] Timer tick, asking Buildbucket for the build status
[19:40:04.817] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:04.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:19:0) after 1m0s
[19:41:05.216] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:19:0)
[19:41:05.216] Timer tick, asking Buildbucket for the build status
[19:41:05.312] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:05.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:20:0) after 1m0s
[19:42:05.637] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:20:0)
[19:42:05.637] Timer tick, asking Buildbucket for the build status
[19:42:05.861] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:05.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:21:0) after 1m0s
[19:43:05.853] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:21:0)
[19:43:05.853] Timer tick, asking Buildbucket for the build status
[19:43:06.122] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:06.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:22:0) after 1m0s
[19:44:06.256] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:22:0)
[19:44:06.256] Timer tick, asking Buildbucket for the build status
[19:44:06.884] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:06.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:23:0) after 1m0s
[19:45:06.948] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:23:0)
[19:45:06.948] Timer tick, asking Buildbucket for the build status
[19:45:07.245] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:07.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:24:0) after 1m0s
[19:46:07.275] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:24:0)
[19:46:07.275] Timer tick, asking Buildbucket for the build status
[19:46:07.973] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:07.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:25:0) after 1m0s
[19:47:08.145] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:25:0)
[19:47:08.145] Timer tick, asking Buildbucket for the build status
[19:47:08.203] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:08.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:26:0) after 1m0s
[19:48:08.325] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:26:0)
[19:48:08.325] Timer tick, asking Buildbucket for the build status
[19:48:09.129] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:09.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:27:0) after 1m0s
[19:49:09.394] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:27:0)
[19:49:09.394] Timer tick, asking Buildbucket for the build status
[19:49:09.622] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:09.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:28:0) after 1m0s
[19:50:09.724] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:28:0)
[19:50:09.724] Timer tick, asking Buildbucket for the build status
[19:50:09.818] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:09.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:29:0) after 1m0s
[19:51:10.044] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:29:0)
[19:51:10.044] Timer tick, asking Buildbucket for the build status
[19:51:10.116] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:10.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:30:0) after 1m0s
[19:52:10.148] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:30:0)
[19:52:10.148] Timer tick, asking Buildbucket for the build status
[19:52:10.653] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:10.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:31:0) after 1m0s
[19:53:10.744] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:31:0)
[19:53:10.744] Timer tick, asking Buildbucket for the build status
[19:53:10.805] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:10.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:32:0) after 1m0s
[19:54:10.798] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:32:0)
[19:54:10.798] Timer tick, asking Buildbucket for the build status
[19:54:10.941] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:10.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:33:0) after 1m0s
[19:55:10.988] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:33:0)
[19:55:10.988] Timer tick, asking Buildbucket for the build status
[19:55:11.164] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:11.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:34:0) after 1m0s
[19:56:11.205] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:34:0)
[19:56:11.205] Timer tick, asking Buildbucket for the build status
[19:56:11.310] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:11.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:35:0) after 1m0s
[19:57:11.366] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:35:0)
[19:57:11.366] Timer tick, asking Buildbucket for the build status
[19:57:11.457] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:11.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:36:0) after 1m0s
[19:58:11.665] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:36:0)
[19:58:11.665] Timer tick, asking Buildbucket for the build status
[19:58:11.930] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:11.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:37:0) after 1m0s
[19:59:11.984] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:37:0)
[19:59:11.984] Timer tick, asking Buildbucket for the build status
[19:59:12.099] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:12.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:38:0) after 1m0s
[20:00:12.118] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:38:0)
[20:00:12.118] Timer tick, asking Buildbucket for the build status
[20:00:12.204] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:12.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:39:0) after 1m0s
[20:01:12.272] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:39:0)
[20:01:12.272] Timer tick, asking Buildbucket for the build status
[20:01:13.152] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:13.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:40:0) after 1m0s
[20:02:13.319] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:40:0)
[20:02:13.319] Timer tick, asking Buildbucket for the build status
[20:02:13.390] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:13.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:41:0) after 1m0s
[20:03:13.509] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:41:0)
[20:03:13.509] Timer tick, asking Buildbucket for the build status
[20:03:13.609] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:13.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:42:0) after 1m0s
[20:04:13.702] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:42:0)
[20:04:13.702] Timer tick, asking Buildbucket for the build status
[20:04:13.953] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:13.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:43:0) after 1m0s
[20:05:14.073] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:43:0)
[20:05:14.073] Timer tick, asking Buildbucket for the build status
[20:05:14.220] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:14.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:44:0) after 1m0s
[20:06:14.262] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:44:0)
[20:06:14.262] Timer tick, asking Buildbucket for the build status
[20:06:14.388] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:14.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:45:0) after 1m0s
[20:07:14.500] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:45:0)
[20:07:14.500] Timer tick, asking Buildbucket for the build status
[20:07:14.758] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:14.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:46:0) after 1m0s
[20:08:14.764] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:46:0)
[20:08:14.764] Timer tick, asking Buildbucket for the build status
[20:08:14.892] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:14.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:47:0) after 1m0s
[20:09:14.930] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:47:0)
[20:09:14.930] Timer tick, asking Buildbucket for the build status
[20:09:15.187] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:15.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:48:0) after 1m0s
[20:10:15.242] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:48:0)
[20:10:15.242] Timer tick, asking Buildbucket for the build status
[20:10:15.403] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:15.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:49:0) after 1m0s
[20:11:15.413] Handling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:49:0)
[20:11:15.413] Timer tick, asking Buildbucket for the build status
[20:11:15.667] Build 8857558853440959024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:15.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-upload-perfetto:9022988915416392592:50:0) after 1m0s
[20:11:29.973] Received PubSub notification, asking Buildbucket for the build status
[20:11:30.320] Build 8857558853440959024: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:11:30.320] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611173489054807",
"created_by": "project:chromium",
"created_ts": "1611170637624927",
"id": "8857558853440959024",
"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\": \"202d44ec5a334f0321f832002707c25c7eb2dc6e\", \"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-oj9q\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"win-upload-perfetto\", \"buildnumber\": 2110, \"got_angle_revision\": \"2900a0f4cb8219be40997f72c25bdc71cef66038\", \"got_dawn_revision\": \"064f33e4416ed498c91137f518fedb56c325b99b\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"202d44ec5a334f0321f832002707c25c7eb2dc6e\", \"got_revision_cp\": \"refs/heads/master@{#845286}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"e27e7488e4ad0ef2099149c66e8b57742e9d5a1c\", \"got_v8_revision\": \"1d681a1a9f5b66ce7c18d756f80e1c3afab911d5\", \"got_v8_revision_cp\": \"refs/heads/9.0.28@{#1}\", \"got_webrtc_revision\": \"6dcbcead3da45604b84aef9d9496689fef5ff8f5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33041}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"swarm_hashes_refs/heads/master(at){#845286}_without_patch\": {\"upload_trace_processor\": \"edf76ec1d8fc28a12f2725d5cca4c69222f1e914\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1bdf55b197d439c94705ddfba40ad846e2fdb8dd164b64a485766434889345a8_v2\", \"builder_1e6a29180cab26dd3348fa54abb67ed061f7a719cdb336cb75143b23c3452e80_v2\", \"builder_44d97c3a86930e13a38e1eaddb8e17f6891cb7883a214371e74f9ddef6b034c5_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-1-oj9q\"], \"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\": [\"5666-679c9bd\"], \"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": "1611171574917485",
"status": "COMPLETED",
"status_changed_ts": "1611173489564970",
"tags": [
"build_address:luci.chromium.ci/win-upload-perfetto/2110",
"builder:win-upload-perfetto",
"scheduler_invocation_id:9022988915416392592",
"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/8857558853440959024/+/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:513a1273b0a3ec10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611173489574222",
"url": "https://ci.chromium.org/b/8857558853440959024",
"utcnow_ts": "1611173490296046"
}
}
[20:11:30.320] Invocation finished in 47m33.96359407s with status SUCCEEDED