[07:15:32.497] New invocation is queued and will start shortly
[07:15:33.580] Starting the invocation (attempt 1)
[07:15:33.668] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:15:33.668] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:15:33.668] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9077574116027031344",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"cd4293b43c8875da993428160abcd71f045b2dae\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9077574116027031344",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/cd4293b43c8875da993428160abcd71f045b2dae",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cd4293b43c8875da993428160abcd71f045b2dae",
"gitiles_ref:refs/heads/master"
]
}
[07:15:34.834] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1559114133931515",
"id": "8912144053857417840",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"cd4293b43c8875da993428160abcd71f045b2dae\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1559114134583244",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13597",
"builder:Windows deterministic",
"buildset:commit/git/cd4293b43c8875da993428160abcd71f045b2dae",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cd4293b43c8875da993428160abcd71f045b2dae",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077574116027031344",
"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/8912144053857417840/+/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": "1559114134583333",
"url": "https://ci.chromium.org/b/8912144053857417840",
"utcnow_ts": "1559114134781006"
}
}
[07:15:34.834] Task URL: https://ci.chromium.org/b/8912144053857417840
[07:15:34.834] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:2:0) after 1m0s
[07:15:40.125] Received PubSub notification, asking Buildbucket for the build status
[07:15:40.500] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:34.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:2:0)
[07:16:34.968] Timer tick, asking Buildbucket for the build status
[07:16:36.412] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:36.412] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:4:0) after 1m0s
[07:17:36.464] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:4:0)
[07:17:36.464] Timer tick, asking Buildbucket for the build status
[07:17:37.055] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:37.055] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:5:0) after 1m0s
[07:18:37.085] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:5:0)
[07:18:37.085] Timer tick, asking Buildbucket for the build status
[07:18:37.818] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:37.818] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:6:0) after 1m0s
[07:19:37.912] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:6:0)
[07:19:37.912] Timer tick, asking Buildbucket for the build status
[07:19:38.331] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:38.331] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:7:0) after 1m0s
[07:20:38.550] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:7:0)
[07:20:38.550] Timer tick, asking Buildbucket for the build status
[07:20:40.536] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:40.536] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:8:0) after 1m0s
[07:21:40.729] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:8:0)
[07:21:40.729] Timer tick, asking Buildbucket for the build status
[07:21:42.162] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:42.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:9:0) after 1m0s
[07:22:42.270] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:9:0)
[07:22:42.270] Timer tick, asking Buildbucket for the build status
[07:22:42.537] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:42.537] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:10:0) after 1m0s
[07:23:42.923] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:10:0)
[07:23:42.923] Timer tick, asking Buildbucket for the build status
[07:23:43.872] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:43.872] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:11:0) after 1m0s
[07:24:44.018] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:11:0)
[07:24:44.018] Timer tick, asking Buildbucket for the build status
[07:24:44.618] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:44.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:12:0) after 1m0s
[07:25:44.726] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:12:0)
[07:25:44.726] Timer tick, asking Buildbucket for the build status
[07:25:45.065] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:45.065] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:13:0) after 1m0s
[07:26:45.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:13:0)
[07:26:45.467] Timer tick, asking Buildbucket for the build status
[07:26:46.191] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:46.191] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:14:0) after 1m0s
[07:27:46.363] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:14:0)
[07:27:46.363] Timer tick, asking Buildbucket for the build status
[07:27:47.692] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:47.692] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:15:0) after 1m0s
[07:28:47.749] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:15:0)
[07:28:47.749] Timer tick, asking Buildbucket for the build status
[07:28:48.003] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:48.003] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:16:0) after 1m0s
[07:29:48.022] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:16:0)
[07:29:48.022] Timer tick, asking Buildbucket for the build status
[07:29:48.246] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:48.246] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:17:0) after 1m0s
[07:30:48.346] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:17:0)
[07:30:48.346] Timer tick, asking Buildbucket for the build status
[07:30:48.556] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:48.556] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:18:0) after 1m0s
[07:31:48.626] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:18:0)
[07:31:48.626] Timer tick, asking Buildbucket for the build status
[07:31:51.752] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:51.752] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:19:0) after 1m0s
[07:32:51.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:19:0)
[07:32:51.792] Timer tick, asking Buildbucket for the build status
[07:32:52.083] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:52.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:20:0) after 1m0s
[07:33:53.251] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:20:0)
[07:33:53.251] Timer tick, asking Buildbucket for the build status
[07:33:53.715] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:53.715] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:21:0) after 1m0s
[07:34:53.846] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:21:0)
[07:34:53.846] Timer tick, asking Buildbucket for the build status
[07:34:54.025] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:54.025] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:22:0) after 1m0s
[07:35:54.155] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:22:0)
[07:35:54.155] Timer tick, asking Buildbucket for the build status
[07:35:54.627] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:54.627] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:23:0) after 1m0s
[07:36:54.734] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:23:0)
[07:36:54.734] Timer tick, asking Buildbucket for the build status
[07:36:55.152] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:55.152] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:24:0) after 1m0s
[07:37:55.180] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:24:0)
[07:37:55.180] Timer tick, asking Buildbucket for the build status
[07:37:55.410] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:55.410] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:25:0) after 1m0s
[07:38:55.410] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:25:0)
[07:38:55.410] Timer tick, asking Buildbucket for the build status
[07:38:56.159] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:56.159] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:26:0) after 1m0s
[07:39:56.278] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:26:0)
[07:39:56.278] Timer tick, asking Buildbucket for the build status
[07:39:57.358] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:57.358] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:27:0) after 1m0s
[07:40:57.382] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:27:0)
[07:40:57.382] Timer tick, asking Buildbucket for the build status
[07:40:57.719] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:57.719] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:28:0) after 1m0s
[07:41:57.746] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:28:0)
[07:41:57.746] Timer tick, asking Buildbucket for the build status
[07:41:58.188] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:58.188] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:29:0) after 1m0s
[07:42:58.209] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:29:0)
[07:42:58.209] Timer tick, asking Buildbucket for the build status
[07:42:58.321] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:58.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:30:0) after 1m0s
[07:43:58.376] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:30:0)
[07:43:58.376] Timer tick, asking Buildbucket for the build status
[07:43:58.881] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:58.882] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:31:0) after 1m0s
[07:44:58.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:31:0)
[07:44:58.910] Timer tick, asking Buildbucket for the build status
[07:44:59.013] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:59.013] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:32:0) after 1m0s
[07:45:59.512] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:32:0)
[07:45:59.512] Timer tick, asking Buildbucket for the build status
[07:45:59.739] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:59.739] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:33:0) after 1m0s
[07:46:59.979] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:33:0)
[07:46:59.979] Timer tick, asking Buildbucket for the build status
[07:47:05.220] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:05.220] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:34:0) after 1m0s
[07:48:05.362] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:34:0)
[07:48:05.362] Timer tick, asking Buildbucket for the build status
[07:48:06.824] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:06.824] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:35:0) after 1m0s
[07:49:07.206] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:35:0)
[07:49:07.206] Timer tick, asking Buildbucket for the build status
[07:49:07.355] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:07.355] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:36:0) after 1m0s
[07:50:07.431] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:36:0)
[07:50:07.431] Timer tick, asking Buildbucket for the build status
[07:50:10.959] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:10.959] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:37:0) after 1m0s
[07:51:11.015] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:37:0)
[07:51:11.015] Timer tick, asking Buildbucket for the build status
[07:51:13.113] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:13.113] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:38:0) after 1m0s
[07:52:13.299] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:38:0)
[07:52:13.299] Timer tick, asking Buildbucket for the build status
[07:52:13.497] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:13.497] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:39:0) after 1m0s
[07:53:13.712] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:39:0)
[07:53:13.712] Timer tick, asking Buildbucket for the build status
[07:53:13.832] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:13.832] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:40:0) after 1m0s
[07:54:14.149] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:40:0)
[07:54:14.149] Timer tick, asking Buildbucket for the build status
[07:54:14.392] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:14.392] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:41:0) after 1m0s
[07:55:14.415] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:41:0)
[07:55:14.415] Timer tick, asking Buildbucket for the build status
[07:55:14.688] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:14.688] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:42:0) after 1m0s
[07:56:14.824] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:42:0)
[07:56:14.824] Timer tick, asking Buildbucket for the build status
[07:56:15.204] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:15.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:43:0) after 1m0s
[07:57:15.255] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:43:0)
[07:57:15.255] Timer tick, asking Buildbucket for the build status
[07:57:15.579] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:15.579] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:44:0) after 1m0s
[07:58:15.604] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:44:0)
[07:58:15.604] Timer tick, asking Buildbucket for the build status
[07:58:16.329] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:16.329] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:45:0) after 1m0s
[07:59:16.496] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:45:0)
[07:59:16.496] Timer tick, asking Buildbucket for the build status
[07:59:17.146] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:17.146] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:46:0) after 1m0s
[08:00:17.161] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:46:0)
[08:00:17.161] Timer tick, asking Buildbucket for the build status
[08:00:18.548] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:18.548] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:47:0) after 1m0s
[08:01:18.610] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:47:0)
[08:01:18.610] Timer tick, asking Buildbucket for the build status
[08:01:20.448] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:20.448] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:48:0) after 1m0s
[08:02:20.469] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:48:0)
[08:02:20.469] Timer tick, asking Buildbucket for the build status
[08:02:20.805] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:20.805] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:49:0) after 1m0s
[08:03:20.826] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:49:0)
[08:03:20.826] Timer tick, asking Buildbucket for the build status
[08:03:23.992] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:23.992] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:50:0) after 1m0s
[08:04:24.010] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:50:0)
[08:04:24.010] Timer tick, asking Buildbucket for the build status
[08:04:24.245] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:24.245] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:51:0) after 1m0s
[08:05:24.290] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:51:0)
[08:05:24.290] Timer tick, asking Buildbucket for the build status
[08:05:24.527] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:24.527] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:52:0) after 1m0s
[08:06:24.693] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:52:0)
[08:06:24.693] Timer tick, asking Buildbucket for the build status
[08:06:25.743] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:25.743] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:53:0) after 1m0s
[08:07:27.732] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:53:0)
[08:07:27.732] Timer tick, asking Buildbucket for the build status
[08:07:28.525] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:28.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:54:0) after 1m0s
[08:08:28.586] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:54:0)
[08:08:28.586] Timer tick, asking Buildbucket for the build status
[08:08:28.861] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:28.861] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:55:0) after 1m0s
[08:09:29.359] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:55:0)
[08:09:29.359] Timer tick, asking Buildbucket for the build status
[08:09:29.465] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:29.465] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:56:0) after 1m0s
[08:10:29.724] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:56:0)
[08:10:29.724] Timer tick, asking Buildbucket for the build status
[08:10:31.452] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:31.452] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:57:0) after 1m0s
[08:11:31.469] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:57:0)
[08:11:31.469] Timer tick, asking Buildbucket for the build status
[08:11:33.512] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:33.512] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:58:0) after 1m0s
[08:12:33.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:58:0)
[08:12:33.599] Timer tick, asking Buildbucket for the build status
[08:12:34.215] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:34.215] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:59:0) after 1m0s
[08:13:34.236] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:59:0)
[08:13:34.236] Timer tick, asking Buildbucket for the build status
[08:13:36.273] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:36.273] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:60:0) after 1m0s
[08:14:36.294] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:60:0)
[08:14:36.294] Timer tick, asking Buildbucket for the build status
[08:14:36.466] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:36.466] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:61:0) after 1m0s
[08:15:36.521] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:61:0)
[08:15:36.521] Timer tick, asking Buildbucket for the build status
[08:15:37.227] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:37.227] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:62:0) after 1m0s
[08:16:37.266] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:62:0)
[08:16:37.266] Timer tick, asking Buildbucket for the build status
[08:16:37.419] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:37.419] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:63:0) after 1m0s
[08:17:37.426] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:63:0)
[08:17:37.426] Timer tick, asking Buildbucket for the build status
[08:17:37.985] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:37.985] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:64:0) after 1m0s
[08:18:38.163] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:64:0)
[08:18:38.163] Timer tick, asking Buildbucket for the build status
[08:18:38.518] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:38.518] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:65:0) after 1m0s
[08:19:38.530] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:65:0)
[08:19:38.530] Timer tick, asking Buildbucket for the build status
[08:19:38.834] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:38.834] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:66:0) after 1m0s
[08:20:38.871] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:66:0)
[08:20:38.871] Timer tick, asking Buildbucket for the build status
[08:20:39.174] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:39.174] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:67:0) after 1m0s
[08:21:39.184] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:67:0)
[08:21:39.184] Timer tick, asking Buildbucket for the build status
[08:21:39.617] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:39.617] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:68:0) after 1m0s
[08:22:39.675] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:68:0)
[08:22:39.676] Timer tick, asking Buildbucket for the build status
[08:22:39.907] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:39.907] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:69:0) after 1m0s
[08:23:39.951] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:69:0)
[08:23:39.951] Timer tick, asking Buildbucket for the build status
[08:23:41.328] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:41.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:70:0) after 1m0s
[08:24:41.384] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:70:0)
[08:24:41.384] Timer tick, asking Buildbucket for the build status
[08:24:41.559] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:41.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:71:0) after 1m0s
[08:25:41.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:71:0)
[08:25:41.602] Timer tick, asking Buildbucket for the build status
[08:25:41.848] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:41.848] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:72:0) after 1m0s
[08:26:41.883] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:72:0)
[08:26:41.883] Timer tick, asking Buildbucket for the build status
[08:26:42.068] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:42.068] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:73:0) after 1m0s
[08:27:42.172] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:73:0)
[08:27:42.172] Timer tick, asking Buildbucket for the build status
[08:27:42.492] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:42.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:74:0) after 1m0s
[08:28:42.502] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:74:0)
[08:28:42.502] Timer tick, asking Buildbucket for the build status
[08:28:43.322] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:43.322] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:75:0) after 1m0s
[08:29:43.343] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:75:0)
[08:29:43.343] Timer tick, asking Buildbucket for the build status
[08:29:43.470] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:43.470] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:76:0) after 1m0s
[08:30:43.484] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:76:0)
[08:30:43.484] Timer tick, asking Buildbucket for the build status
[08:30:43.831] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:43.831] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:77:0) after 1m0s
[08:31:43.902] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:77:0)
[08:31:43.902] Timer tick, asking Buildbucket for the build status
[08:31:44.200] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:44.200] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:78:0) after 1m0s
[08:32:44.210] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:78:0)
[08:32:44.210] Timer tick, asking Buildbucket for the build status
[08:32:44.784] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:44.784] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:79:0) after 1m0s
[08:33:44.843] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:79:0)
[08:33:44.843] Timer tick, asking Buildbucket for the build status
[08:33:45.773] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:45.773] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:80:0) after 1m0s
[08:34:45.798] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:80:0)
[08:34:45.798] Timer tick, asking Buildbucket for the build status
[08:34:46.841] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:46.841] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:81:0) after 1m0s
[08:35:46.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:81:0)
[08:35:46.869] Timer tick, asking Buildbucket for the build status
[08:35:48.542] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:48.542] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:82:0) after 1m0s
[08:36:48.914] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:82:0)
[08:36:48.914] Timer tick, asking Buildbucket for the build status
[08:36:49.226] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:49.226] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:83:0) after 1m0s
[08:37:49.482] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:83:0)
[08:37:49.482] Timer tick, asking Buildbucket for the build status
[08:37:49.601] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:49.601] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:84:0) after 1m0s
[08:38:49.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:84:0)
[08:38:49.905] Timer tick, asking Buildbucket for the build status
[08:38:50.028] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:50.028] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:85:0) after 1m0s
[08:39:50.116] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:85:0)
[08:39:50.116] Timer tick, asking Buildbucket for the build status
[08:39:52.310] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:52.310] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:86:0) after 1m0s
[08:40:52.346] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:86:0)
[08:40:52.346] Timer tick, asking Buildbucket for the build status
[08:40:52.513] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:52.513] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:87:0) after 1m0s
[08:41:52.632] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:87:0)
[08:41:52.632] Timer tick, asking Buildbucket for the build status
[08:41:53.071] Build 8912144053857417840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:53.071] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077574116027031344:88:0) after 1m0s
[08:42:03.092] Received PubSub notification, asking Buildbucket for the build status
[08:42:03.222] Build 8912144053857417840: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:42:03.222] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1559119322107371",
"created_by": "project:chromium",
"created_ts": "1559114133931515",
"id": "8912144053857417840",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"cd4293b43c8875da993428160abcd71f045b2dae\"}}",
"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\": 13597, \"got_angle_revision\": \"ded5f903261a8bee3481aad98d7ed47308b59563\", \"got_dawn_revision\": \"26d3cf08c209c662a6e2298c301272e2eb8246e4\", \"got_nacl_revision\": \"0ddc033406886a709b901e0c312872529f9705e8\", \"got_revision\": \"cd4293b43c8875da993428160abcd71f045b2dae\", \"got_revision_cp\": \"refs/heads/master@{#664152}\", \"got_swarming_client_revision\": \"779c4f0f8488c64587b75dbb001d18c3c0c4cda9\", \"got_v8_revision\": \"b7182085e080ffec649b05335dae2e6a7e52e0a5\", \"got_v8_revision_cp\": \"refs/heads/7.6.305@{#1}\", \"got_webrtc_revision\": \"e4470cd8b405e00bdd942ca7094780ff954bd43c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28095}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"cd4293b43c8875da993428160abcd71f045b2dae\"}, \"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": "1559114137910034",
"status": "COMPLETED",
"status_changed_ts": "1559119322502791",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13597",
"builder:Windows deterministic",
"buildset:commit/git/cd4293b43c8875da993428160abcd71f045b2dae",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cd4293b43c8875da993428160abcd71f045b2dae",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077574116027031344",
"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/8912144053857417840/+/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:451b433eed5e6d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1559119322507520",
"url": "https://ci.chromium.org/b/8912144053857417840",
"utcnow_ts": "1559119323210398"
}
}
[08:42:03.222] Invocation finished in 1h26m30.744067943s with status SUCCEEDED