[12:36:40.264] New invocation is queued and will start shortly
[12:36:41.671] Starting the invocation (attempt 1)
[12:36:41.709] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:36:41.711] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:36:41.711] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9077553912308444128",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"893892534f0c2879765b83231ccd443384d2f6de\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9077553912308444128",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/893892534f0c2879765b83231ccd443384d2f6de",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/893892534f0c2879765b83231ccd443384d2f6de",
"gitiles_ref:refs/heads/master"
]
}
[12:36:43.057] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1559133401925935",
"id": "8912123849901039360",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"893892534f0c2879765b83231ccd443384d2f6de\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1559133402925384",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13600",
"builder:Windows deterministic",
"buildset:commit/git/893892534f0c2879765b83231ccd443384d2f6de",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/893892534f0c2879765b83231ccd443384d2f6de",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077553912308444128",
"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/8912123849901039360/+/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": "1559133402925515",
"url": "https://ci.chromium.org/b/8912123849901039360",
"utcnow_ts": "1559133403043653"
}
}
[12:36:43.057] Task URL: https://ci.chromium.org/b/8912123849901039360
[12:36:43.057] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:2:0) after 1m0s
[12:36:49.169] Received PubSub notification, asking Buildbucket for the build status
[12:36:49.791] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:43.207] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:2:0)
[12:37:43.207] Timer tick, asking Buildbucket for the build status
[12:37:45.705] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:45.705] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:4:0) after 1m0s
[12:38:45.798] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:4:0)
[12:38:45.798] Timer tick, asking Buildbucket for the build status
[12:38:47.845] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:47.846] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:5:0) after 1m0s
[12:39:47.833] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:5:0)
[12:39:47.833] Timer tick, asking Buildbucket for the build status
[12:39:49.624] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:49.624] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:6:0) after 1m0s
[12:40:49.894] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:6:0)
[12:40:49.894] Timer tick, asking Buildbucket for the build status
[12:40:50.908] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:50.908] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:7:0) after 1m0s
[12:41:51.001] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:7:0)
[12:41:51.001] Timer tick, asking Buildbucket for the build status
[12:41:51.228] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:51.228] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:8:0) after 1m0s
[12:42:51.300] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:8:0)
[12:42:51.300] Timer tick, asking Buildbucket for the build status
[12:42:51.631] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:51.631] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:9:0) after 1m0s
[12:43:52.168] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:9:0)
[12:43:52.168] Timer tick, asking Buildbucket for the build status
[12:43:52.442] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:52.442] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:10:0) after 1m0s
[12:44:52.567] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:10:0)
[12:44:52.567] Timer tick, asking Buildbucket for the build status
[12:44:52.755] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:52.755] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:11:0) after 1m0s
[12:45:53.213] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:11:0)
[12:45:53.213] Timer tick, asking Buildbucket for the build status
[12:45:53.925] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:53.925] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:12:0) after 1m0s
[12:46:54.091] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:12:0)
[12:46:54.091] Timer tick, asking Buildbucket for the build status
[12:46:54.390] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:54.390] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:13:0) after 1m0s
[12:47:54.458] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:13:0)
[12:47:54.458] Timer tick, asking Buildbucket for the build status
[12:47:55.539] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:55.539] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:14:0) after 1m0s
[12:48:55.586] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:14:0)
[12:48:55.586] Timer tick, asking Buildbucket for the build status
[12:48:55.983] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:55.983] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:15:0) after 1m0s
[12:49:56.106] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:15:0)
[12:49:56.106] Timer tick, asking Buildbucket for the build status
[12:49:56.620] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:56.620] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:16:0) after 1m0s
[12:50:56.676] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:16:0)
[12:50:56.676] Timer tick, asking Buildbucket for the build status
[12:50:57.227] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:57.227] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:17:0) after 1m0s
[12:51:57.301] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:17:0)
[12:51:57.301] Timer tick, asking Buildbucket for the build status
[12:51:57.505] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:57.505] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:18:0) after 1m0s
[12:52:57.604] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:18:0)
[12:52:57.604] Timer tick, asking Buildbucket for the build status
[12:52:57.908] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:57.908] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:19:0) after 1m0s
[12:53:58.012] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:19:0)
[12:53:58.012] Timer tick, asking Buildbucket for the build status
[12:53:58.203] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:58.203] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:20:0) after 1m0s
[12:54:58.267] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:20:0)
[12:54:58.267] Timer tick, asking Buildbucket for the build status
[12:54:59.999] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:59.999] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:21:0) after 1m0s
[12:56:00.076] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:21:0)
[12:56:00.076] Timer tick, asking Buildbucket for the build status
[12:56:01.575] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:01.575] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:22:0) after 1m0s
[12:57:01.610] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:22:0)
[12:57:01.610] Timer tick, asking Buildbucket for the build status
[12:57:02.170] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:02.170] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:23:0) after 1m0s
[12:58:02.641] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:23:0)
[12:58:02.641] Timer tick, asking Buildbucket for the build status
[12:58:03.138] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:03.138] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:24:0) after 1m0s
[12:59:05.017] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:24:0)
[12:59:05.017] Timer tick, asking Buildbucket for the build status
[12:59:07.943] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:07.943] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:25:0) after 1m0s
[13:00:08.357] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:25:0)
[13:00:08.357] Timer tick, asking Buildbucket for the build status
[13:00:08.572] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:08.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:26:0) after 1m0s
[13:01:08.710] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:26:0)
[13:01:08.710] Timer tick, asking Buildbucket for the build status
[13:01:09.264] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:09.264] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:27:0) after 1m0s
[13:02:09.384] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:27:0)
[13:02:09.384] Timer tick, asking Buildbucket for the build status
[13:02:09.701] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:09.701] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:28:0) after 1m0s
[13:03:09.784] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:28:0)
[13:03:09.784] Timer tick, asking Buildbucket for the build status
[13:03:11.289] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:11.289] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:29:0) after 1m0s
[13:04:11.392] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:29:0)
[13:04:11.392] Timer tick, asking Buildbucket for the build status
[13:04:13.828] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:13.828] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:30:0) after 1m0s
[13:05:14.006] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:30:0)
[13:05:14.006] Timer tick, asking Buildbucket for the build status
[13:05:14.849] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:14.849] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:31:0) after 1m0s
[13:06:15.059] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:31:0)
[13:06:15.059] Timer tick, asking Buildbucket for the build status
[13:06:16.696] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:16.696] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:32:0) after 1m0s
[13:07:16.835] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:32:0)
[13:07:16.835] Timer tick, asking Buildbucket for the build status
[13:07:18.458] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:18.458] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:33:0) after 1m0s
[13:08:18.962] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:33:0)
[13:08:18.962] Timer tick, asking Buildbucket for the build status
[13:08:19.092] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:19.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:34:0) after 1m0s
[13:09:19.219] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:34:0)
[13:09:19.219] Timer tick, asking Buildbucket for the build status
[13:09:19.342] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:19.342] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:35:0) after 1m0s
[13:10:19.853] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:35:0)
[13:10:19.853] Timer tick, asking Buildbucket for the build status
[13:10:19.962] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:19.962] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:36:0) after 1m0s
[13:11:19.995] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:36:0)
[13:11:19.995] Timer tick, asking Buildbucket for the build status
[13:11:20.420] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:20.420] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:37:0) after 1m0s
[13:12:20.696] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:37:0)
[13:12:20.696] Timer tick, asking Buildbucket for the build status
[13:12:21.774] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:21.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:38:0) after 1m0s
[13:13:21.867] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:38:0)
[13:13:21.867] Timer tick, asking Buildbucket for the build status
[13:13:22.893] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:22.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:39:0) after 1m0s
[13:14:23.073] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:39:0)
[13:14:23.073] Timer tick, asking Buildbucket for the build status
[13:14:25.292] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:25.292] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:40:0) after 1m0s
[13:15:25.378] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:40:0)
[13:15:25.378] Timer tick, asking Buildbucket for the build status
[13:15:27.449] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:27.449] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:41:0) after 1m0s
[13:16:27.607] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:41:0)
[13:16:27.607] Timer tick, asking Buildbucket for the build status
[13:16:28.686] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:28.686] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:42:0) after 1m0s
[13:17:28.743] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:42:0)
[13:17:28.743] Timer tick, asking Buildbucket for the build status
[13:17:31.281] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:31.281] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:43:0) after 1m0s
[13:18:31.411] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:43:0)
[13:18:31.411] Timer tick, asking Buildbucket for the build status
[13:18:31.570] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:31.571] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:44:0) after 1m0s
[13:19:31.709] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:44:0)
[13:19:31.709] Timer tick, asking Buildbucket for the build status
[13:19:32.169] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:32.169] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:45:0) after 1m0s
[13:20:32.280] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:45:0)
[13:20:32.280] Timer tick, asking Buildbucket for the build status
[13:20:34.646] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:34.646] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:46:0) after 1m0s
[13:21:34.762] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:46:0)
[13:21:34.762] Timer tick, asking Buildbucket for the build status
[13:21:35.608] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:35.608] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:47:0) after 1m0s
[13:22:35.924] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:47:0)
[13:22:35.924] Timer tick, asking Buildbucket for the build status
[13:22:36.193] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:36.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:48:0) after 1m0s
[13:23:36.328] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:48:0)
[13:23:36.328] Timer tick, asking Buildbucket for the build status
[13:23:37.299] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:37.299] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:49:0) after 1m0s
[13:24:37.427] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:49:0)
[13:24:37.427] Timer tick, asking Buildbucket for the build status
[13:24:39.352] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:39.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:50:0) after 1m0s
[13:25:39.505] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:50:0)
[13:25:39.505] Timer tick, asking Buildbucket for the build status
[13:25:39.624] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:39.624] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:51:0) after 1m0s
[13:26:39.776] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:51:0)
[13:26:39.776] Timer tick, asking Buildbucket for the build status
[13:26:41.582] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:41.582] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:52:0) after 1m0s
[13:27:41.716] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:52:0)
[13:27:41.716] Timer tick, asking Buildbucket for the build status
[13:27:42.064] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:42.064] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:53:0) after 1m0s
[13:28:42.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:53:0)
[13:28:42.273] Timer tick, asking Buildbucket for the build status
[13:28:43.067] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:43.067] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:54:0) after 1m0s
[13:29:43.108] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:54:0)
[13:29:43.108] Timer tick, asking Buildbucket for the build status
[13:29:43.462] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:43.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:55:0) after 1m0s
[13:30:43.492] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:55:0)
[13:30:43.492] Timer tick, asking Buildbucket for the build status
[13:30:44.013] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:44.013] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:56:0) after 1m0s
[13:31:44.066] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:56:0)
[13:31:44.066] Timer tick, asking Buildbucket for the build status
[13:31:45.197] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:45.197] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:57:0) after 1m0s
[13:32:45.266] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:57:0)
[13:32:45.266] Timer tick, asking Buildbucket for the build status
[13:32:47.086] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:47.086] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:58:0) after 1m0s
[13:33:47.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:58:0)
[13:33:47.228] Timer tick, asking Buildbucket for the build status
[13:33:47.361] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:47.361] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:59:0) after 1m0s
[13:34:47.449] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:59:0)
[13:34:47.449] Timer tick, asking Buildbucket for the build status
[13:34:48.301] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:48.301] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:60:0) after 1m0s
[13:35:48.437] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:60:0)
[13:35:48.437] Timer tick, asking Buildbucket for the build status
[13:35:48.611] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:48.611] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:61:0) after 1m0s
[13:36:48.654] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:61:0)
[13:36:48.654] Timer tick, asking Buildbucket for the build status
[13:36:49.297] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:49.297] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:62:0) after 1m0s
[13:37:49.357] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:62:0)
[13:37:49.358] Timer tick, asking Buildbucket for the build status
[13:37:49.477] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:49.477] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:63:0) after 1m0s
[13:38:49.498] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:63:0)
[13:38:49.498] Timer tick, asking Buildbucket for the build status
[13:38:50.347] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:50.347] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:64:0) after 1m0s
[13:39:50.381] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:64:0)
[13:39:50.381] Timer tick, asking Buildbucket for the build status
[13:39:50.528] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:50.528] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:65:0) after 1m0s
[13:40:50.559] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:65:0)
[13:40:50.559] Timer tick, asking Buildbucket for the build status
[13:40:51.352] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:51.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:66:0) after 1m0s
[13:41:51.337] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:66:0)
[13:41:51.337] Timer tick, asking Buildbucket for the build status
[13:41:51.804] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:51.804] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:67:0) after 1m0s
[13:42:51.855] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:67:0)
[13:42:51.855] Timer tick, asking Buildbucket for the build status
[13:42:53.591] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:53.591] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:68:0) after 1m0s
[13:43:53.636] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:68:0)
[13:43:53.636] Timer tick, asking Buildbucket for the build status
[13:43:53.985] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:53.985] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:69:0) after 1m0s
[13:44:54.058] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:69:0)
[13:44:54.058] Timer tick, asking Buildbucket for the build status
[13:44:54.428] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:54.428] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:70:0) after 1m0s
[13:45:54.517] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:70:0)
[13:45:54.517] Timer tick, asking Buildbucket for the build status
[13:45:55.162] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:55.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:71:0) after 1m0s
[13:46:55.270] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:71:0)
[13:46:55.270] Timer tick, asking Buildbucket for the build status
[13:46:55.778] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:55.778] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:72:0) after 1m0s
[13:47:55.797] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:72:0)
[13:47:55.797] Timer tick, asking Buildbucket for the build status
[13:47:55.910] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:55.910] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:73:0) after 1m0s
[13:48:55.908] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:73:0)
[13:48:55.908] Timer tick, asking Buildbucket for the build status
[13:48:58.608] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:58.608] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:74:0) after 1m0s
[13:49:58.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:74:0)
[13:49:58.705] Timer tick, asking Buildbucket for the build status
[13:49:59.278] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:59.278] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:75:0) after 1m0s
[13:50:59.307] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:75:0)
[13:50:59.307] Timer tick, asking Buildbucket for the build status
[13:50:59.641] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:59.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:76:0) after 1m0s
[13:51:59.866] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:76:0)
[13:51:59.866] Timer tick, asking Buildbucket for the build status
[13:52:00.308] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:00.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:77:0) after 1m0s
[13:53:00.371] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:77:0)
[13:53:00.371] Timer tick, asking Buildbucket for the build status
[13:53:01.451] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:01.451] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:78:0) after 1m0s
[13:54:02.814] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:78:0)
[13:54:02.814] Timer tick, asking Buildbucket for the build status
[13:54:04.630] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:04.630] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:79:0) after 1m0s
[13:55:04.743] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:79:0)
[13:55:04.743] Timer tick, asking Buildbucket for the build status
[13:55:05.011] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:05.011] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:80:0) after 1m0s
[13:56:05.039] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:80:0)
[13:56:05.039] Timer tick, asking Buildbucket for the build status
[13:56:05.444] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:05.444] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:81:0) after 1m0s
[13:57:05.543] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:81:0)
[13:57:05.543] Timer tick, asking Buildbucket for the build status
[13:57:06.102] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:06.102] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:82:0) after 1m0s
[13:58:06.167] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:82:0)
[13:58:06.167] Timer tick, asking Buildbucket for the build status
[13:58:06.378] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:06.378] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:83:0) after 1m0s
[13:59:06.539] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:83:0)
[13:59:06.539] Timer tick, asking Buildbucket for the build status
[13:59:07.201] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:07.201] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:84:0) after 1m0s
[14:00:07.258] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:84:0)
[14:00:07.258] Timer tick, asking Buildbucket for the build status
[14:00:09.150] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:09.150] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:85:0) after 1m0s
[14:01:09.181] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:85:0)
[14:01:09.181] Timer tick, asking Buildbucket for the build status
[14:01:10.830] Build 8912123849901039360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:10.830] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077553912308444128:86:0) after 1m0s
[14:01:56.247] Received PubSub notification, asking Buildbucket for the build status
[14:01:57.951] Build 8912123849901039360: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:01:57.952] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1559138513867485",
"created_by": "project:chromium",
"created_ts": "1559133401925935",
"id": "8912123849901039360",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"893892534f0c2879765b83231ccd443384d2f6de\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$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-7052\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 13600, \"got_angle_revision\": \"1ad4771e88a56bedaddc5c0009d908ab954cbca5\", \"got_dawn_revision\": \"26d3cf08c209c662a6e2298c301272e2eb8246e4\", \"got_nacl_revision\": \"0ddc033406886a709b901e0c312872529f9705e8\", \"got_revision\": \"893892534f0c2879765b83231ccd443384d2f6de\", \"got_revision_cp\": \"refs/heads/master@{#664240}\", \"got_swarming_client_revision\": \"779c4f0f8488c64587b75dbb001d18c3c0c4cda9\", \"got_v8_revision\": \"bd353500dde4d2666a46d5faf49afee50a5f27b2\", \"got_v8_revision_cp\": \"refs/heads/7.6.308@{#1}\", \"got_webrtc_revision\": \"98266a4af1828b99807d98f12d3a56f8a37ec5be\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28097}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"893892534f0c2879765b83231ccd443384d2f6de\"}, \"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\"], \"gpu\": [\"none\"], \"id\": [\"windows-deterministic-0-7052\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4303-bae210e\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1559133407074003",
"status": "COMPLETED",
"status_changed_ts": "1559138515296064",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13600",
"builder:Windows deterministic",
"buildset:commit/git/893892534f0c2879765b83231ccd443384d2f6de",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/893892534f0c2879765b83231ccd443384d2f6de",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077553912308444128",
"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/8912123849901039360/+/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:451c6941a56a3a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1559138515358412",
"url": "https://ci.chromium.org/b/8912123849901039360",
"utcnow_ts": "1559138517927621"
}
}
[14:01:57.952] Invocation finished in 1h25m17.706750124s with status SUCCEEDED