[14:10:56.785] New invocation is queued and will start shortly
[14:10:57.860] Starting the invocation (attempt 1)
[14:10:57.921] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:10:57.921] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:10:57.921] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022283831509676864",
"parameters_json": "{\"builder_name\":\"ToTWin(dll)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b1eab082179e4c51f9698b57561de504f2fa9040\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTWin(dll)",
"scheduler_invocation_id:9022283831509676864",
"scheduler_job_id:chromium/ToTWin(dll)",
"user_agent:luci-scheduler",
"buildset:commit/git/b1eab082179e4c51f9698b57561de504f2fa9040",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b1eab082179e4c51f9698b57561de504f2fa9040",
"gitiles_ref:refs/heads/master"
]
}
[14:10:58.575] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611843058031788",
"id": "8856853769540410560",
"parameters_json": "{\"builder_name\": \"ToTWin(dll)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b1eab082179e4c51f9698b57561de504f2fa9040\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611843058425023",
"tags": [
"build_address:luci.chromium.ci/ToTWin(dll)/7732",
"builder:ToTWin(dll)",
"buildset:commit/git/b1eab082179e4c51f9698b57561de504f2fa9040",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b1eab082179e4c51f9698b57561de504f2fa9040",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022283831509676864",
"scheduler_job_id:chromium/ToTWin(dll)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856853769540410560/+/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": "1611843058425207",
"url": "https://ci.chromium.org/b/8856853769540410560",
"utcnow_ts": "1611843058568520"
}
}
[14:10:58.576] Task URL: https://ci.chromium.org/b/8856853769540410560
[14:10:58.576] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:2:0) after 1m0s
[14:11:58.833] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:2:0)
[14:11:58.833] Timer tick, asking Buildbucket for the build status
[14:11:58.937] Build 8856853769540410560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:11:58.937] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:3:0) after 1m0s
[14:12:59.034] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:3:0)
[14:12:59.034] Timer tick, asking Buildbucket for the build status
[14:12:59.151] Build 8856853769540410560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:12:59.151] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:4:0) after 1m0s
[14:13:59.259] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:4:0)
[14:13:59.259] Timer tick, asking Buildbucket for the build status
[14:13:59.450] Build 8856853769540410560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:13:59.450] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:5:0) after 1m0s
[14:14:59.468] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:5:0)
[14:14:59.468] Timer tick, asking Buildbucket for the build status
[14:14:59.554] Build 8856853769540410560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:14:59.554] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:6:0) after 1m0s
[14:15:59.959] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:6:0)
[14:15:59.959] Timer tick, asking Buildbucket for the build status
[14:16:00.354] Build 8856853769540410560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:16:00.354] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:7:0) after 1m0s
[14:17:00.382] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:7:0)
[14:17:00.382] Timer tick, asking Buildbucket for the build status
[14:17:00.475] Build 8856853769540410560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:17:00.475] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:8:0) after 1m0s
[14:18:00.534] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:8:0)
[14:18:00.534] Timer tick, asking Buildbucket for the build status
[14:18:00.615] Build 8856853769540410560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:18:00.615] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:9:0) after 1m0s
[14:18:41.803] Received PubSub notification, asking Buildbucket for the build status
[14:18:41.883] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:01.256] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:9:0)
[14:19:01.256] Timer tick, asking Buildbucket for the build status
[14:19:01.345] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:01.345] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:11:0) after 1m0s
[14:20:01.364] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:11:0)
[14:20:01.364] Timer tick, asking Buildbucket for the build status
[14:20:01.482] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:01.482] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:12:0) after 1m0s
[14:21:02.669] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:12:0)
[14:21:02.669] Timer tick, asking Buildbucket for the build status
[14:21:03.458] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:03.458] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:13:0) after 1m0s
[14:22:03.607] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:13:0)
[14:22:03.607] Timer tick, asking Buildbucket for the build status
[14:22:03.720] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:03.720] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:14:0) after 1m0s
[14:23:03.797] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:14:0)
[14:23:03.797] Timer tick, asking Buildbucket for the build status
[14:23:03.882] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:03.882] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:15:0) after 1m0s
[14:24:03.960] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:15:0)
[14:24:03.960] Timer tick, asking Buildbucket for the build status
[14:24:04.034] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:04.034] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:16:0) after 1m0s
[14:25:04.119] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:16:0)
[14:25:04.119] Timer tick, asking Buildbucket for the build status
[14:25:04.279] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:04.279] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:17:0) after 1m0s
[14:26:04.307] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:17:0)
[14:26:04.307] Timer tick, asking Buildbucket for the build status
[14:26:04.401] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:04.401] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:18:0) after 1m0s
[14:27:04.463] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:18:0)
[14:27:04.463] Timer tick, asking Buildbucket for the build status
[14:27:04.528] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:04.528] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:19:0) after 1m0s
[14:28:04.698] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:19:0)
[14:28:04.698] Timer tick, asking Buildbucket for the build status
[14:28:04.778] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:04.778] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:20:0) after 1m0s
[14:29:04.832] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:20:0)
[14:29:04.832] Timer tick, asking Buildbucket for the build status
[14:29:04.944] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:04.944] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:21:0) after 1m0s
[14:30:05.024] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:21:0)
[14:30:05.024] Timer tick, asking Buildbucket for the build status
[14:30:05.541] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:05.541] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:22:0) after 1m0s
[14:31:05.839] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:22:0)
[14:31:05.839] Timer tick, asking Buildbucket for the build status
[14:31:05.962] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:05.962] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:23:0) after 1m0s
[14:32:06.128] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:23:0)
[14:32:06.128] Timer tick, asking Buildbucket for the build status
[14:32:06.256] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:06.256] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:24:0) after 1m0s
[14:33:06.269] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:24:0)
[14:33:06.269] Timer tick, asking Buildbucket for the build status
[14:33:06.435] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:06.436] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:25:0) after 1m0s
[14:34:06.513] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:25:0)
[14:34:06.513] Timer tick, asking Buildbucket for the build status
[14:34:06.597] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:06.597] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:26:0) after 1m0s
[14:35:06.621] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:26:0)
[14:35:06.621] Timer tick, asking Buildbucket for the build status
[14:35:06.759] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:06.759] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:27:0) after 1m0s
[14:36:06.938] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:27:0)
[14:36:06.938] Timer tick, asking Buildbucket for the build status
[14:36:07.456] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:07.456] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:28:0) after 1m0s
[14:37:07.546] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:28:0)
[14:37:07.546] Timer tick, asking Buildbucket for the build status
[14:37:07.643] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:07.643] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:29:0) after 1m0s
[14:38:07.743] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:29:0)
[14:38:07.743] Timer tick, asking Buildbucket for the build status
[14:38:07.810] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:07.810] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:30:0) after 1m0s
[14:39:07.907] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:30:0)
[14:39:07.907] Timer tick, asking Buildbucket for the build status
[14:39:07.981] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:07.981] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:31:0) after 1m0s
[14:40:07.970] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:31:0)
[14:40:07.970] Timer tick, asking Buildbucket for the build status
[14:40:08.037] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:08.037] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:32:0) after 1m0s
[14:41:08.064] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:32:0)
[14:41:08.064] Timer tick, asking Buildbucket for the build status
[14:41:08.214] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:08.214] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:33:0) after 1m0s
[14:42:08.214] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:33:0)
[14:42:08.214] Timer tick, asking Buildbucket for the build status
[14:42:08.480] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:08.480] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:34:0) after 1m0s
[14:43:08.626] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:34:0)
[14:43:08.626] Timer tick, asking Buildbucket for the build status
[14:43:08.758] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:08.758] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:35:0) after 1m0s
[14:44:08.819] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:35:0)
[14:44:08.819] Timer tick, asking Buildbucket for the build status
[14:44:08.886] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:08.886] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:36:0) after 1m0s
[14:45:08.918] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:36:0)
[14:45:08.918] Timer tick, asking Buildbucket for the build status
[14:45:09.032] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:09.032] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:37:0) after 1m0s
[14:46:09.265] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:37:0)
[14:46:09.265] Timer tick, asking Buildbucket for the build status
[14:46:09.391] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:09.391] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:38:0) after 1m0s
[14:47:10.215] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:38:0)
[14:47:10.216] Timer tick, asking Buildbucket for the build status
[14:47:10.404] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:10.404] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:39:0) after 1m0s
[14:48:10.488] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:39:0)
[14:48:10.488] Timer tick, asking Buildbucket for the build status
[14:48:10.581] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:10.581] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:40:0) after 1m0s
[14:49:10.605] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:40:0)
[14:49:10.605] Timer tick, asking Buildbucket for the build status
[14:49:10.667] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:10.667] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:41:0) after 1m0s
[14:50:10.759] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:41:0)
[14:50:10.759] Timer tick, asking Buildbucket for the build status
[14:50:11.846] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:11.846] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:42:0) after 1m0s
[14:51:11.984] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:42:0)
[14:51:11.984] Timer tick, asking Buildbucket for the build status
[14:51:12.354] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:12.354] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:43:0) after 1m0s
[14:52:12.390] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:43:0)
[14:52:12.390] Timer tick, asking Buildbucket for the build status
[14:52:12.518] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:12.518] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:44:0) after 1m0s
[14:53:12.561] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:44:0)
[14:53:12.561] Timer tick, asking Buildbucket for the build status
[14:53:12.643] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:12.643] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:45:0) after 1m0s
[14:54:13.654] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:45:0)
[14:54:13.654] Timer tick, asking Buildbucket for the build status
[14:54:13.713] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:13.713] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:46:0) after 1m0s
[14:55:13.869] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:46:0)
[14:55:13.869] Timer tick, asking Buildbucket for the build status
[14:55:13.924] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:13.924] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:47:0) after 1m0s
[14:56:14.166] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:47:0)
[14:56:14.166] Timer tick, asking Buildbucket for the build status
[14:56:14.250] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:14.250] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:48:0) after 1m0s
[14:57:14.459] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:48:0)
[14:57:14.459] Timer tick, asking Buildbucket for the build status
[14:57:14.530] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:14.530] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:49:0) after 1m0s
[14:58:14.807] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:49:0)
[14:58:14.807] Timer tick, asking Buildbucket for the build status
[14:58:14.895] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:14.895] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:50:0) after 1m0s
[14:59:15.101] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:50:0)
[14:59:15.101] Timer tick, asking Buildbucket for the build status
[14:59:15.306] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:15.307] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:51:0) after 1m0s
[15:00:15.361] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:51:0)
[15:00:15.361] Timer tick, asking Buildbucket for the build status
[15:00:15.792] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:15.792] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:52:0) after 1m0s
[15:01:15.862] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:52:0)
[15:01:15.862] Timer tick, asking Buildbucket for the build status
[15:01:15.971] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:15.971] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:53:0) after 1m0s
[15:02:16.103] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:53:0)
[15:02:16.103] Timer tick, asking Buildbucket for the build status
[15:02:16.374] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:16.374] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:54:0) after 1m0s
[15:03:16.561] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:54:0)
[15:03:16.561] Timer tick, asking Buildbucket for the build status
[15:03:16.657] Build 8856853769540410560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:16.657] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dll):9022283831509676864:55:0) after 1m0s
[15:03:35.666] Received PubSub notification, asking Buildbucket for the build status
[15:03:36.757] Build 8856853769540410560: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[15:03:36.757] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611846215182803",
"created_by": "project:chromium",
"created_ts": "1611843058031788",
"failure_reason": "BUILD_FAILURE",
"id": "8856853769540410560",
"parameters_json": "{\"builder_name\": \"ToTWin(dll)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b1eab082179e4c51f9698b57561de504f2fa9040\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('gclient runhooks') (retcode: 2)\"}, \"properties\": {\"$build/goma\": {\"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"b1eab082179e4c51f9698b57561de504f2fa9040\", \"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-32-9-snau\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.clang\", \"buildername\": \"ToTWin(dll)\", \"buildnumber\": 7732, \"got_angle_revision\": \"4ce2e2135123407e09e37eb2f3400fd263febecc\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"b1eab082179e4c51f9698b57561de504f2fa9040\", \"got_revision_cp\": \"refs/heads/master@{#848091}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"fd95b5debcbd6ac0436ac015ce9da1b1582ff166\", \"got_v8_revision_cp\": \"refs/heads/9.0.95@{#1}\", \"got_webrtc_revision\": \"49dbad021e802197b3c0b966c03b4aa766a5b578\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33080}\", \"path_config\": \"generic\", \"perf_dashboard_machine_group\": \"ChromiumClang\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b1eab082179e4c51f9698b57561de504f2fa9040\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-9-snau\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"Step('gclient runhooks') (retcode: 2)\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611843521192939",
"status": "COMPLETED",
"status_changed_ts": "1611843521537084",
"tags": [
"build_address:luci.chromium.ci/ToTWin(dll)/7732",
"builder:ToTWin(dll)",
"buildset:commit/git/b1eab082179e4c51f9698b57561de504f2fa9040",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b1eab082179e4c51f9698b57561de504f2fa9040",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022283831509676864",
"scheduler_job_id:chromium/ToTWin(dll)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856853769540410560/+/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:516226c45fd88210",
"user_agent:luci-scheduler"
],
"updated_ts": "1611846215255810",
"url": "https://ci.chromium.org/b/8856853769540410560",
"utcnow_ts": "1611846216734544"
}
}
[15:03:36.757] Invocation finished in 52m39.988242318s with status FAILED