[23:07:31.473] New invocation is queued and will start shortly
[23:07:32.954] Starting the invocation (attempt 1)
[23:07:33.003] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:07:33.003] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:07:33.003] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9041275435892706464",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"bfb56409167d142b44958df3a527455580d42234\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9041275435892706464",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/bfb56409167d142b44958df3a527455580d42234",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bfb56409167d142b44958df3a527455580d42234",
"gitiles_ref:refs/heads/master"
]
}
[23:07:36.424] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1593731255767181",
"id": "8875845370711205792",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bfb56409167d142b44958df3a527455580d42234\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1593731256268452",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/16764",
"builder:Windows deterministic",
"buildset:commit/git/bfb56409167d142b44958df3a527455580d42234",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bfb56409167d142b44958df3a527455580d42234",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9041275435892706464",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8875845370711205792/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1593731256268700",
"url": "https://ci.chromium.org/b/8875845370711205792",
"utcnow_ts": "1593731256414508"
}
}
[23:07:36.424] Task URL: https://ci.chromium.org/b/8875845370711205792
[23:07:36.424] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:2:0) after 1m0s
[23:08:36.476] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:2:0)
[23:08:36.476] Timer tick, asking Buildbucket for the build status
[23:08:36.619] Build 8875845370711205792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:36.619] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:3:0) after 1m0s
[23:09:36.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:3:0)
[23:09:36.637] Timer tick, asking Buildbucket for the build status
[23:09:36.729] Build 8875845370711205792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:36.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:4:0) after 1m0s
[23:09:39.799] Received PubSub notification, asking Buildbucket for the build status
[23:09:39.943] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:36.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:4:0)
[23:10:36.757] Timer tick, asking Buildbucket for the build status
[23:10:36.981] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:36.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:6:0) after 1m0s
[23:11:37.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:6:0)
[23:11:37.003] Timer tick, asking Buildbucket for the build status
[23:11:37.114] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:37.114] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:7:0) after 1m0s
[23:12:37.167] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:7:0)
[23:12:37.167] Timer tick, asking Buildbucket for the build status
[23:12:37.775] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:37.775] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:8:0) after 1m0s
[23:13:37.825] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:8:0)
[23:13:37.825] Timer tick, asking Buildbucket for the build status
[23:13:37.945] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:37.945] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:9:0) after 1m0s
[23:14:38.013] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:9:0)
[23:14:38.013] Timer tick, asking Buildbucket for the build status
[23:14:38.114] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:38.114] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:10:0) after 1m0s
[23:15:38.158] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:10:0)
[23:15:38.158] Timer tick, asking Buildbucket for the build status
[23:15:38.424] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:38.424] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:11:0) after 1m0s
[23:16:38.489] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:11:0)
[23:16:38.489] Timer tick, asking Buildbucket for the build status
[23:16:38.587] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:38.587] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:12:0) after 1m0s
[23:17:38.600] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:12:0)
[23:17:38.600] Timer tick, asking Buildbucket for the build status
[23:17:38.881] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:38.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:13:0) after 1m0s
[23:18:38.940] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:13:0)
[23:18:38.940] Timer tick, asking Buildbucket for the build status
[23:18:39.073] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:39.073] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:14:0) after 1m0s
[23:19:39.096] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:14:0)
[23:19:39.096] Timer tick, asking Buildbucket for the build status
[23:19:39.244] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:39.244] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:15:0) after 1m0s
[23:20:39.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:15:0)
[23:20:39.273] Timer tick, asking Buildbucket for the build status
[23:20:39.373] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:39.373] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:16:0) after 1m0s
[23:21:39.438] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:16:0)
[23:21:39.438] Timer tick, asking Buildbucket for the build status
[23:21:39.638] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:39.638] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:17:0) after 1m0s
[23:22:39.729] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:17:0)
[23:22:39.729] Timer tick, asking Buildbucket for the build status
[23:22:41.347] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:41.347] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:18:0) after 1m0s
[23:23:41.445] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:18:0)
[23:23:41.445] Timer tick, asking Buildbucket for the build status
[23:23:41.768] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:41.768] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:19:0) after 1m0s
[23:24:41.881] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:19:0)
[23:24:41.881] Timer tick, asking Buildbucket for the build status
[23:24:42.021] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:42.021] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:20:0) after 1m0s
[23:25:42.093] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:20:0)
[23:25:42.093] Timer tick, asking Buildbucket for the build status
[23:25:42.430] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:42.430] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:21:0) after 1m0s
[23:26:42.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:21:0)
[23:26:42.588] Timer tick, asking Buildbucket for the build status
[23:26:42.738] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:42.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:22:0) after 1m0s
[23:27:42.803] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:22:0)
[23:27:42.803] Timer tick, asking Buildbucket for the build status
[23:27:44.023] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:44.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:23:0) after 1m0s
[23:28:44.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:23:0)
[23:28:44.045] Timer tick, asking Buildbucket for the build status
[23:28:44.249] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:44.249] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:24:0) after 1m0s
[23:29:44.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:24:0)
[23:29:44.277] Timer tick, asking Buildbucket for the build status
[23:29:44.579] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:44.580] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:25:0) after 1m0s
[23:30:44.717] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:25:0)
[23:30:44.717] Timer tick, asking Buildbucket for the build status
[23:30:44.850] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:44.850] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:26:0) after 1m0s
[23:31:44.935] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:26:0)
[23:31:44.935] Timer tick, asking Buildbucket for the build status
[23:31:45.154] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:45.154] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:27:0) after 1m0s
[23:32:45.275] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:27:0)
[23:32:45.275] Timer tick, asking Buildbucket for the build status
[23:32:45.865] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:45.865] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:28:0) after 1m0s
[23:33:45.928] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:28:0)
[23:33:45.928] Timer tick, asking Buildbucket for the build status
[23:33:46.171] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:46.171] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:29:0) after 1m0s
[23:34:46.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:29:0)
[23:34:46.228] Timer tick, asking Buildbucket for the build status
[23:34:46.637] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:46.637] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:30:0) after 1m0s
[23:35:46.661] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:30:0)
[23:35:46.661] Timer tick, asking Buildbucket for the build status
[23:35:46.946] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:46.946] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:31:0) after 1m0s
[23:36:46.975] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:31:0)
[23:36:46.975] Timer tick, asking Buildbucket for the build status
[23:36:47.189] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:47.189] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:32:0) after 1m0s
[23:37:47.211] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:32:0)
[23:37:47.211] Timer tick, asking Buildbucket for the build status
[23:37:49.178] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:49.178] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:33:0) after 1m0s
[23:38:49.298] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:33:0)
[23:38:49.298] Timer tick, asking Buildbucket for the build status
[23:38:49.442] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:49.442] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:34:0) after 1m0s
[23:39:49.416] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:34:0)
[23:39:49.416] Timer tick, asking Buildbucket for the build status
[23:39:49.826] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:49.827] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:35:0) after 1m0s
[23:40:49.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:35:0)
[23:40:49.968] Timer tick, asking Buildbucket for the build status
[23:40:50.074] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:50.074] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:36:0) after 1m0s
[23:41:50.186] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:36:0)
[23:41:50.186] Timer tick, asking Buildbucket for the build status
[23:41:50.351] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:50.351] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:37:0) after 1m0s
[23:42:50.382] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:37:0)
[23:42:50.382] Timer tick, asking Buildbucket for the build status
[23:42:50.519] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:50.519] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:38:0) after 1m0s
[23:43:50.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:38:0)
[23:43:50.591] Timer tick, asking Buildbucket for the build status
[23:43:50.781] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:50.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:39:0) after 1m0s
[23:44:50.889] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:39:0)
[23:44:50.889] Timer tick, asking Buildbucket for the build status
[23:44:51.641] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:51.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:40:0) after 1m0s
[23:45:51.671] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:40:0)
[23:45:51.671] Timer tick, asking Buildbucket for the build status
[23:45:51.797] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:51.797] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:41:0) after 1m0s
[23:46:51.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:41:0)
[23:46:51.869] Timer tick, asking Buildbucket for the build status
[23:46:52.586] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:52.586] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:42:0) after 1m0s
[23:47:52.685] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:42:0)
[23:47:52.685] Timer tick, asking Buildbucket for the build status
[23:47:53.123] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:53.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:43:0) after 1m0s
[23:48:53.181] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:43:0)
[23:48:53.181] Timer tick, asking Buildbucket for the build status
[23:48:53.291] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:53.291] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:44:0) after 1m0s
[23:49:53.302] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:44:0)
[23:49:53.302] Timer tick, asking Buildbucket for the build status
[23:49:53.439] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:53.439] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:45:0) after 1m0s
[23:50:53.498] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:45:0)
[23:50:53.498] Timer tick, asking Buildbucket for the build status
[23:50:53.605] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:53.605] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:46:0) after 1m0s
[23:51:53.625] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:46:0)
[23:51:53.625] Timer tick, asking Buildbucket for the build status
[23:51:53.795] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:53.795] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:47:0) after 1m0s
[23:52:53.891] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:47:0)
[23:52:53.891] Timer tick, asking Buildbucket for the build status
[23:52:54.177] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:54.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:48:0) after 1m0s
[23:53:54.262] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:48:0)
[23:53:54.262] Timer tick, asking Buildbucket for the build status
[23:53:54.397] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:54.398] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:49:0) after 1m0s
[23:54:54.524] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:49:0)
[23:54:54.524] Timer tick, asking Buildbucket for the build status
[23:54:54.690] Build 8875845370711205792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:54.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9041275435892706464:50:0) after 1m0s
[23:54:59.916] Received PubSub notification, asking Buildbucket for the build status
[23:55:00.166] Build 8875845370711205792: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:55:00.166] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1593734099364940",
"created_by": "project:chromium",
"created_ts": "1593731255767181",
"failure_reason": "BUILD_FAILURE",
"id": "8875845370711205792",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bfb56409167d142b44958df3a527455580d42234\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"#### Step _First build_ failed. Error logs are shown below:\\n```\\n[22004/24644] CXX obj/third_party/blink/renderer/core/unit_tests/paint_property_tree_builder_test.obj\\nFAILED: obj/third_party/blink/renderer/core/unit_tests/paint_property_tree_builder_test.obj\\nC:\\\\b\\\\s\\\\w\\\\ir\\\\cache\\\\goma\\\\client\\\\gomacc.exe ..\\\\..\\\\third_party\\\\llvm-build\\\\Release+Asserts\\\\bin\\\\clang-cl.e...(too long)\\n../../third_party/blink/renderer/core/paint/paint_property_tree_builder_test.cc(1962,59): error: no member named 'Translation2D' in 'blink::TransformPaintPropertyNodeOrAlias'\\nchild_local_border_box_properties.Transform().Translation2D());\\n~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ ^\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 150.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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\": \"windows-deterministic-0-rjvv\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 16764, \"got_angle_revision\": \"5fec8ecf9cf43f8664d7b8355ffc092b6eb447cb\", \"got_dawn_revision\": \"f695264d30e63d951f46e6e5c9da063dda988ffe\", \"got_nacl_revision\": \"fd20fafa8ec19700e12daa031bf792bae8b6bb21\", \"got_revision\": \"bfb56409167d142b44958df3a527455580d42234\", \"got_revision_cp\": \"refs/heads/master@{#784938}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"5d4c9817d54eddf8d584d960261a2828ebd2688d\", \"got_v8_revision\": \"9771bc55333c281e27a8ce64f982c2a4a1c9cebd\", \"got_v8_revision_cp\": \"refs/heads/8.6.50@{#1}\", \"got_webrtc_revision\": \"1e10a6156405f1c9b4c432c57a955f70642ccfc8\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31617}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bfb56409167d142b44958df3a527455580d42234\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Windows deterministic\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"windows-deterministic-0-rjvv\"], \"image\": [\"chrome-win10-1703-20052900-58250aa4257\"], \"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\": [\"5284-0b74e14\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}, \"ui\": {\"info\": \"#### Step _First build_ failed. Error logs are shown below:\\n```\\n[22004/24644] CXX obj/third_party/blink/renderer/core/unit_tests/paint_property_tree_builder_test.obj\\nFAILED: obj/third_party/blink/renderer/core/unit_tests/paint_property_tree_builder_test.obj\\nC:\\\\b\\\\s\\\\w\\\\ir\\\\cache\\\\goma\\\\client\\\\gomacc.exe ..\\\\..\\\\third_party\\\\llvm-build\\\\Release+Asserts\\\\bin\\\\clang-cl.e...(too long)\\n../../third_party/blink/renderer/core/paint/paint_property_tree_builder_test.cc(1962,59): error: no member named 'Translation2D' in 'blink::TransformPaintPropertyNodeOrAlias'\\nchild_local_border_box_properties.Transform().Translation2D());\\n~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ ^\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1593731377893737",
"status": "COMPLETED",
"status_changed_ts": "1593731378383521",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/16764",
"builder:Windows deterministic",
"buildset:commit/git/bfb56409167d142b44958df3a527455580d42234",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bfb56409167d142b44958df3a527455580d42234",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9041275435892706464",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8875845370711205792/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4d2a9a99e23e2b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1593734099520268",
"url": "https://ci.chromium.org/b/8875845370711205792",
"utcnow_ts": "1593734100025921"
}
}
[23:55:00.171] Invocation finished in 47m28.728654889s with status FAILED