[19:10:50.292] New invocation is queued and will start shortly
[19:10:54.515] Starting the invocation (attempt 1)
[19:10:54.548] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:10:54.548] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:10:54.548] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9046182563079753088",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5f68ddff55349f628ae14acf59dd94267d79ad36\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9046182563079753088",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/5f68ddff55349f628ae14acf59dd94267d79ad36",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5f68ddff55349f628ae14acf59dd94267d79ad36",
"gitiles_ref:refs/heads/master"
]
}
[19:10:55.284] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1589051454636099",
"id": "8880752497862232848",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5f68ddff55349f628ae14acf59dd94267d79ad36\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1589051455151797",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/16453",
"builder:Windows deterministic",
"buildset:commit/git/5f68ddff55349f628ae14acf59dd94267d79ad36",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5f68ddff55349f628ae14acf59dd94267d79ad36",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9046182563079753088",
"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/8880752497862232848/+/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": "1589051455151967",
"url": "https://ci.chromium.org/b/8880752497862232848",
"utcnow_ts": "1589051455275233"
}
}
[19:10:55.284] Task URL: https://ci.chromium.org/b/8880752497862232848
[19:10:55.284] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:2:0) after 1m0s
[19:12:14.749] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:2:0)
[19:12:14.749] Timer tick, asking Buildbucket for the build status
[19:12:15.038] Build 8880752497862232848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:12:15.038] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:3:0) after 1m0s
[19:12:45.015] Received PubSub notification, asking Buildbucket for the build status
[19:12:45.144] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:15.276] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:3:0)
[19:13:15.276] Timer tick, asking Buildbucket for the build status
[19:13:15.387] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:15.387] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:5:0) after 1m0s
[19:14:15.629] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:5:0)
[19:14:15.629] Timer tick, asking Buildbucket for the build status
[19:14:15.755] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:15.755] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:6:0) after 1m0s
[19:15:15.822] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:6:0)
[19:15:15.822] Timer tick, asking Buildbucket for the build status
[19:15:15.963] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:15.963] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:7:0) after 1m0s
[19:16:16.123] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:7:0)
[19:16:16.123] Timer tick, asking Buildbucket for the build status
[19:16:16.457] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:16.457] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:8:0) after 1m0s
[19:17:16.682] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:8:0)
[19:17:16.682] Timer tick, asking Buildbucket for the build status
[19:17:16.806] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:16.806] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:9:0) after 1m0s
[19:18:16.967] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:9:0)
[19:18:16.967] Timer tick, asking Buildbucket for the build status
[19:18:17.142] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:17.142] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:10:0) after 1m0s
[19:19:17.340] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:10:0)
[19:19:17.340] Timer tick, asking Buildbucket for the build status
[19:19:17.459] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:17.459] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:11:0) after 1m0s
[19:20:17.552] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:11:0)
[19:20:17.552] Timer tick, asking Buildbucket for the build status
[19:20:17.696] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:17.696] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:12:0) after 1m0s
[19:21:17.819] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:12:0)
[19:21:17.819] Timer tick, asking Buildbucket for the build status
[19:21:18.269] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:18.269] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:13:0) after 1m0s
[19:22:18.335] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:13:0)
[19:22:18.335] Timer tick, asking Buildbucket for the build status
[19:22:18.530] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:18.530] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:14:0) after 1m0s
[19:23:18.554] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:14:0)
[19:23:18.554] Timer tick, asking Buildbucket for the build status
[19:23:19.023] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:19.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:15:0) after 1m0s
[19:24:19.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:15:0)
[19:24:19.004] Timer tick, asking Buildbucket for the build status
[19:24:19.131] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:19.131] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:16:0) after 1m0s
[19:25:19.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:16:0)
[19:25:19.154] Timer tick, asking Buildbucket for the build status
[19:25:19.447] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:19.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:17:0) after 1m0s
[19:26:19.469] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:17:0)
[19:26:19.469] Timer tick, asking Buildbucket for the build status
[19:26:19.593] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:19.593] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:18:0) after 1m0s
[19:27:19.654] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:18:0)
[19:27:19.654] Timer tick, asking Buildbucket for the build status
[19:27:19.985] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:19.985] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:19:0) after 1m0s
[19:28:20.062] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:19:0)
[19:28:20.062] Timer tick, asking Buildbucket for the build status
[19:28:20.531] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:20.531] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:20:0) after 1m0s
[19:29:20.723] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:20:0)
[19:29:20.723] Timer tick, asking Buildbucket for the build status
[19:29:20.879] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:20.879] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:21:0) after 1m0s
[19:30:21.001] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:21:0)
[19:30:21.001] Timer tick, asking Buildbucket for the build status
[19:30:21.374] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:21.374] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:22:0) after 1m0s
[19:31:21.414] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:22:0)
[19:31:21.414] Timer tick, asking Buildbucket for the build status
[19:31:22.727] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:22.727] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:23:0) after 1m0s
[19:32:22.752] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:23:0)
[19:32:22.752] Timer tick, asking Buildbucket for the build status
[19:32:22.877] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:22.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:24:0) after 1m0s
[19:33:22.908] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:24:0)
[19:33:22.908] Timer tick, asking Buildbucket for the build status
[19:33:23.157] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:23.157] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:25:0) after 1m0s
[19:34:23.207] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:25:0)
[19:34:23.207] Timer tick, asking Buildbucket for the build status
[19:34:23.639] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:23.639] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:26:0) after 1m0s
[19:35:23.749] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:26:0)
[19:35:23.749] Timer tick, asking Buildbucket for the build status
[19:35:23.920] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:23.920] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:27:0) after 1m0s
[19:36:24.079] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:27:0)
[19:36:24.079] Timer tick, asking Buildbucket for the build status
[19:36:24.539] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:24.539] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:28:0) after 1m0s
[19:37:24.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:28:0)
[19:37:24.705] Timer tick, asking Buildbucket for the build status
[19:37:24.992] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:24.992] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:29:0) after 1m0s
[19:38:25.132] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:29:0)
[19:38:25.132] Timer tick, asking Buildbucket for the build status
[19:38:25.544] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:25.544] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:30:0) after 1m0s
[19:39:25.678] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:30:0)
[19:39:25.678] Timer tick, asking Buildbucket for the build status
[19:39:25.788] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:25.788] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:31:0) after 1m0s
[19:40:25.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:31:0)
[19:40:25.815] Timer tick, asking Buildbucket for the build status
[19:40:25.925] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:25.925] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:32:0) after 1m0s
[19:41:25.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:32:0)
[19:41:25.980] Timer tick, asking Buildbucket for the build status
[19:41:26.100] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:26.100] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:33:0) after 1m0s
[19:42:26.174] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:33:0)
[19:42:26.174] Timer tick, asking Buildbucket for the build status
[19:42:26.298] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:26.298] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:34:0) after 1m0s
[19:43:26.545] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:34:0)
[19:43:26.545] Timer tick, asking Buildbucket for the build status
[19:43:26.671] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:26.671] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:35:0) after 1m0s
[19:44:26.830] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:35:0)
[19:44:26.830] Timer tick, asking Buildbucket for the build status
[19:44:26.950] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:26.950] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:36:0) after 1m0s
[19:45:26.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:36:0)
[19:45:26.972] Timer tick, asking Buildbucket for the build status
[19:45:27.143] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:27.144] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:37:0) after 1m0s
[19:46:27.136] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:37:0)
[19:46:27.136] Timer tick, asking Buildbucket for the build status
[19:46:27.462] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:27.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:38:0) after 1m0s
[19:47:27.608] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:38:0)
[19:47:27.608] Timer tick, asking Buildbucket for the build status
[19:47:27.836] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:27.836] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:39:0) after 1m0s
[19:48:27.863] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:39:0)
[19:48:27.864] Timer tick, asking Buildbucket for the build status
[19:48:28.284] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:28.284] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:40:0) after 1m0s
[19:49:28.305] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:40:0)
[19:49:28.305] Timer tick, asking Buildbucket for the build status
[19:49:28.604] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:28.604] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:41:0) after 1m0s
[19:50:28.605] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:41:0)
[19:50:28.605] Timer tick, asking Buildbucket for the build status
[19:50:28.744] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:28.744] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:42:0) after 1m0s
[19:51:28.776] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:42:0)
[19:51:28.776] Timer tick, asking Buildbucket for the build status
[19:51:28.883] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:28.883] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:43:0) after 1m0s
[19:52:28.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:43:0)
[19:52:28.910] Timer tick, asking Buildbucket for the build status
[19:52:29.050] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:29.050] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:44:0) after 1m0s
[19:53:29.068] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:44:0)
[19:53:29.068] Timer tick, asking Buildbucket for the build status
[19:53:29.277] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:29.278] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:45:0) after 1m0s
[19:54:29.355] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:45:0)
[19:54:29.355] Timer tick, asking Buildbucket for the build status
[19:54:29.582] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:29.582] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:46:0) after 1m0s
[19:55:29.662] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:46:0)
[19:55:29.662] Timer tick, asking Buildbucket for the build status
[19:55:30.019] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:30.019] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:47:0) after 1m0s
[19:56:30.051] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:47:0)
[19:56:30.052] Timer tick, asking Buildbucket for the build status
[19:56:30.168] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:30.169] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:48:0) after 1m0s
[19:57:30.248] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:48:0)
[19:57:30.248] Timer tick, asking Buildbucket for the build status
[19:57:30.361] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:30.361] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:49:0) after 1m0s
[19:58:30.446] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:49:0)
[19:58:30.446] Timer tick, asking Buildbucket for the build status
[19:58:30.627] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:30.627] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:50:0) after 1m0s
[19:59:30.795] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:50:0)
[19:59:30.795] Timer tick, asking Buildbucket for the build status
[19:59:31.022] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:31.022] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:51:0) after 1m0s
[20:00:31.035] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:51:0)
[20:00:31.035] Timer tick, asking Buildbucket for the build status
[20:00:31.246] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:31.246] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:52:0) after 1m0s
[20:01:31.293] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:52:0)
[20:01:31.293] Timer tick, asking Buildbucket for the build status
[20:01:31.830] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:31.830] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:53:0) after 1m0s
[20:02:31.855] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:53:0)
[20:02:31.855] Timer tick, asking Buildbucket for the build status
[20:02:32.058] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:32.058] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:54:0) after 1m0s
[20:03:32.118] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:54:0)
[20:03:32.118] Timer tick, asking Buildbucket for the build status
[20:03:32.494] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:32.494] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:55:0) after 1m0s
[20:04:32.529] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:55:0)
[20:04:32.529] Timer tick, asking Buildbucket for the build status
[20:04:32.728] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:32.728] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:56:0) after 1m0s
[20:05:32.818] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:56:0)
[20:05:32.818] Timer tick, asking Buildbucket for the build status
[20:05:33.056] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:33.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:57:0) after 1m0s
[20:06:33.144] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:57:0)
[20:06:33.144] Timer tick, asking Buildbucket for the build status
[20:06:33.299] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:33.299] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:58:0) after 1m0s
[20:07:33.361] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:58:0)
[20:07:33.361] Timer tick, asking Buildbucket for the build status
[20:07:34.318] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:34.318] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:59:0) after 1m0s
[20:08:34.451] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:59:0)
[20:08:34.451] Timer tick, asking Buildbucket for the build status
[20:08:34.763] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:34.763] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:60:0) after 1m0s
[20:09:34.814] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:60:0)
[20:09:34.814] Timer tick, asking Buildbucket for the build status
[20:09:35.275] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:35.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:61:0) after 1m0s
[20:10:35.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:61:0)
[20:10:35.519] Timer tick, asking Buildbucket for the build status
[20:10:35.787] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:35.787] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:62:0) after 1m0s
[20:11:35.866] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:62:0)
[20:11:35.866] Timer tick, asking Buildbucket for the build status
[20:11:36.116] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:36.116] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:63:0) after 1m0s
[20:12:36.233] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:63:0)
[20:12:36.233] Timer tick, asking Buildbucket for the build status
[20:12:36.554] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:36.554] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:64:0) after 1m0s
[20:13:36.631] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:64:0)
[20:13:36.631] Timer tick, asking Buildbucket for the build status
[20:13:37.180] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:37.180] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:65:0) after 1m0s
[20:14:37.211] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:65:0)
[20:14:37.211] Timer tick, asking Buildbucket for the build status
[20:14:37.591] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:37.591] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:66:0) after 1m0s
[20:15:37.623] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:66:0)
[20:15:37.623] Timer tick, asking Buildbucket for the build status
[20:15:38.164] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:38.164] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:67:0) after 1m0s
[20:16:38.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:67:0)
[20:16:38.197] Timer tick, asking Buildbucket for the build status
[20:16:38.455] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:38.455] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:68:0) after 1m0s
[20:17:38.496] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:68:0)
[20:17:38.496] Timer tick, asking Buildbucket for the build status
[20:17:38.877] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:38.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:69:0) after 1m0s
[20:18:38.902] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:69:0)
[20:18:38.902] Timer tick, asking Buildbucket for the build status
[20:18:38.999] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:38.999] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:70:0) after 1m0s
[20:19:39.022] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:70:0)
[20:19:39.022] Timer tick, asking Buildbucket for the build status
[20:19:39.917] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:39.917] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:71:0) after 1m0s
[20:20:39.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:71:0)
[20:20:39.963] Timer tick, asking Buildbucket for the build status
[20:20:40.610] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:40.610] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:72:0) after 1m0s
[20:21:40.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:72:0)
[20:21:40.645] Timer tick, asking Buildbucket for the build status
[20:21:40.870] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:40.870] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:73:0) after 1m0s
[20:22:40.899] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:73:0)
[20:22:40.899] Timer tick, asking Buildbucket for the build status
[20:22:41.337] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:41.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:74:0) after 1m0s
[20:23:41.369] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:74:0)
[20:23:41.369] Timer tick, asking Buildbucket for the build status
[20:23:41.685] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:41.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:75:0) after 1m0s
[20:24:41.761] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:75:0)
[20:24:41.761] Timer tick, asking Buildbucket for the build status
[20:24:42.155] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:42.155] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:76:0) after 1m0s
[20:25:42.255] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:76:0)
[20:25:42.255] Timer tick, asking Buildbucket for the build status
[20:25:42.476] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:42.476] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:77:0) after 1m0s
[20:26:42.505] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:77:0)
[20:26:42.505] Timer tick, asking Buildbucket for the build status
[20:26:42.635] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:42.635] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:78:0) after 1m0s
[20:27:42.659] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:78:0)
[20:27:42.659] Timer tick, asking Buildbucket for the build status
[20:27:43.019] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:43.019] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:79:0) after 1m0s
[20:28:43.067] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:79:0)
[20:28:43.067] Timer tick, asking Buildbucket for the build status
[20:28:43.237] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:43.237] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:80:0) after 1m0s
[20:29:43.314] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:80:0)
[20:29:43.314] Timer tick, asking Buildbucket for the build status
[20:29:43.525] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:43.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:81:0) after 1m0s
[20:30:43.523] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:81:0)
[20:30:43.523] Timer tick, asking Buildbucket for the build status
[20:30:44.305] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:44.305] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:82:0) after 1m0s
[20:31:44.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:82:0)
[20:31:44.344] Timer tick, asking Buildbucket for the build status
[20:31:44.503] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:44.503] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:83:0) after 1m0s
[20:32:44.538] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:83:0)
[20:32:44.538] Timer tick, asking Buildbucket for the build status
[20:32:44.700] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:44.700] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:84:0) after 1m0s
[20:33:44.769] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:84:0)
[20:33:44.769] Timer tick, asking Buildbucket for the build status
[20:33:44.905] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:44.905] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:85:0) after 1m0s
[20:34:44.935] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:85:0)
[20:34:44.935] Timer tick, asking Buildbucket for the build status
[20:34:45.104] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:45.104] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:86:0) after 1m0s
[20:35:45.195] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:86:0)
[20:35:45.195] Timer tick, asking Buildbucket for the build status
[20:35:45.462] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:45.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:87:0) after 1m0s
[20:36:45.521] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:87:0)
[20:36:45.521] Timer tick, asking Buildbucket for the build status
[20:36:45.830] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:45.830] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:88:0) after 1m0s
[20:37:45.861] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:88:0)
[20:37:45.861] Timer tick, asking Buildbucket for the build status
[20:37:46.142] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:46.142] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:89:0) after 1m0s
[20:38:46.181] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:89:0)
[20:38:46.181] Timer tick, asking Buildbucket for the build status
[20:38:46.341] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:46.341] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:90:0) after 1m0s
[20:39:46.368] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:90:0)
[20:39:46.368] Timer tick, asking Buildbucket for the build status
[20:39:46.749] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:46.749] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:91:0) after 1m0s
[20:40:46.784] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:91:0)
[20:40:46.784] Timer tick, asking Buildbucket for the build status
[20:40:46.901] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:46.901] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:92:0) after 1m0s
[20:41:46.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:92:0)
[20:41:46.929] Timer tick, asking Buildbucket for the build status
[20:41:47.259] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:47.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:93:0) after 1m0s
[20:42:47.291] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:93:0)
[20:42:47.291] Timer tick, asking Buildbucket for the build status
[20:42:47.409] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:47.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:94:0) after 1m0s
[20:43:47.437] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:94:0)
[20:43:47.437] Timer tick, asking Buildbucket for the build status
[20:43:47.738] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:47.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:95:0) after 1m0s
[20:44:47.812] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:95:0)
[20:44:47.812] Timer tick, asking Buildbucket for the build status
[20:44:48.063] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:48.063] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:96:0) after 1m0s
[20:45:48.841] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:96:0)
[20:45:48.841] Timer tick, asking Buildbucket for the build status
[20:45:49.265] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:49.265] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:97:0) after 1m0s
[20:46:49.473] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:97:0)
[20:46:49.473] Timer tick, asking Buildbucket for the build status
[20:46:49.738] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:49.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:98:0) after 1m0s
[20:47:50.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:98:0)
[20:47:50.083] Timer tick, asking Buildbucket for the build status
[20:47:50.360] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:50.360] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:99:0) after 1m0s
[20:48:50.566] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:99:0)
[20:48:50.566] Timer tick, asking Buildbucket for the build status
[20:48:51.006] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:51.006] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:100:0) after 1m0s
[20:49:51.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:100:0)
[20:49:51.345] Timer tick, asking Buildbucket for the build status
[20:49:51.489] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:51.489] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:101:0) after 1m0s
[20:50:51.616] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:101:0)
[20:50:51.617] Timer tick, asking Buildbucket for the build status
[20:50:51.763] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:51.763] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:102:0) after 1m0s
[20:51:51.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:102:0)
[20:51:51.989] Timer tick, asking Buildbucket for the build status
[20:51:52.289] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:52.289] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:103:0) after 1m0s
[20:52:52.363] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:103:0)
[20:52:52.363] Timer tick, asking Buildbucket for the build status
[20:52:52.883] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:52.883] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:104:0) after 1m0s
[20:53:52.901] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:104:0)
[20:53:52.901] Timer tick, asking Buildbucket for the build status
[20:53:53.160] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:53.160] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:105:0) after 1m0s
[20:54:53.266] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:105:0)
[20:54:53.266] Timer tick, asking Buildbucket for the build status
[20:54:53.422] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:53.422] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:106:0) after 1m0s
[20:55:55.915] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:106:0)
[20:55:55.915] Timer tick, asking Buildbucket for the build status
[20:55:56.259] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:56.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:107:0) after 1m0s
[20:56:56.354] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:107:0)
[20:56:56.354] Timer tick, asking Buildbucket for the build status
[20:56:57.205] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:57.205] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:108:0) after 1m0s
[20:57:57.338] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:108:0)
[20:57:57.338] Timer tick, asking Buildbucket for the build status
[20:57:57.859] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:57.859] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:109:0) after 1m0s
[20:58:57.900] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:109:0)
[20:58:57.900] Timer tick, asking Buildbucket for the build status
[20:58:58.092] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:58.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:110:0) after 1m0s
[20:59:58.146] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:110:0)
[20:59:58.146] Timer tick, asking Buildbucket for the build status
[20:59:58.279] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:58.279] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:111:0) after 1m0s
[21:00:58.409] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:111:0)
[21:00:58.409] Timer tick, asking Buildbucket for the build status
[21:00:58.687] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:58.687] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:112:0) after 1m0s
[21:01:58.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:112:0)
[21:01:58.705] Timer tick, asking Buildbucket for the build status
[21:01:58.918] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:58.918] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:113:0) after 1m0s
[21:02:58.953] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:113:0)
[21:02:58.953] Timer tick, asking Buildbucket for the build status
[21:02:59.369] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:59.369] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:114:0) after 1m0s
[21:03:59.446] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:114:0)
[21:03:59.446] Timer tick, asking Buildbucket for the build status
[21:04:00.435] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:00.435] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:115:0) after 1m0s
[21:05:00.523] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:115:0)
[21:05:00.523] Timer tick, asking Buildbucket for the build status
[21:05:00.628] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:00.628] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:116:0) after 1m0s
[21:06:01.638] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:116:0)
[21:06:01.638] Timer tick, asking Buildbucket for the build status
[21:06:01.802] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:01.802] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:117:0) after 1m0s
[21:07:01.926] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:117:0)
[21:07:01.926] Timer tick, asking Buildbucket for the build status
[21:07:02.054] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:02.054] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:118:0) after 1m0s
[21:08:02.132] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:118:0)
[21:08:02.132] Timer tick, asking Buildbucket for the build status
[21:08:02.284] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:02.284] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:119:0) after 1m0s
[21:09:02.326] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:119:0)
[21:09:02.326] Timer tick, asking Buildbucket for the build status
[21:09:02.870] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:02.870] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:120:0) after 1m0s
[21:10:02.953] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:120:0)
[21:10:02.953] Timer tick, asking Buildbucket for the build status
[21:10:03.081] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:03.081] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:121:0) after 1m0s
[21:11:03.124] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:121:0)
[21:11:03.124] Timer tick, asking Buildbucket for the build status
[21:11:03.700] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:03.700] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:122:0) after 1m0s
[21:12:03.748] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:122:0)
[21:12:03.748] Timer tick, asking Buildbucket for the build status
[21:12:05.118] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:05.118] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:123:0) after 1m0s
[21:13:05.172] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:123:0)
[21:13:05.172] Timer tick, asking Buildbucket for the build status
[21:13:05.288] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:05.288] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:124:0) after 1m0s
[21:14:05.407] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:124:0)
[21:14:05.407] Timer tick, asking Buildbucket for the build status
[21:14:05.747] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:05.747] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:125:0) after 1m0s
[21:15:05.790] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:125:0)
[21:15:05.790] Timer tick, asking Buildbucket for the build status
[21:15:07.199] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:07.199] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:126:0) after 1m0s
[21:16:07.208] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:126:0)
[21:16:07.208] Timer tick, asking Buildbucket for the build status
[21:16:07.327] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:07.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:127:0) after 1m0s
[21:17:07.338] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:127:0)
[21:17:07.338] Timer tick, asking Buildbucket for the build status
[21:17:07.487] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:07.487] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:128:0) after 1m0s
[21:18:07.530] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:128:0)
[21:18:07.530] Timer tick, asking Buildbucket for the build status
[21:18:07.760] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:07.760] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:129:0) after 1m0s
[21:19:07.788] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:129:0)
[21:19:07.788] Timer tick, asking Buildbucket for the build status
[21:19:07.996] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:07.996] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:130:0) after 1m0s
[21:20:08.077] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:130:0)
[21:20:08.077] Timer tick, asking Buildbucket for the build status
[21:20:08.258] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:08.258] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:131:0) after 1m0s
[21:21:08.300] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:131:0)
[21:21:08.300] Timer tick, asking Buildbucket for the build status
[21:21:08.829] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:08.830] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:132:0) after 1m0s
[21:22:08.836] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:132:0)
[21:22:08.836] Timer tick, asking Buildbucket for the build status
[21:22:09.104] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:09.104] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:133:0) after 1m0s
[21:23:09.144] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:133:0)
[21:23:09.145] Timer tick, asking Buildbucket for the build status
[21:23:09.504] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:09.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:134:0) after 1m0s
[21:24:09.596] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:134:0)
[21:24:09.596] Timer tick, asking Buildbucket for the build status
[21:24:09.757] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:09.758] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:135:0) after 1m0s
[21:25:09.840] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:135:0)
[21:25:09.840] Timer tick, asking Buildbucket for the build status
[21:25:10.100] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:10.100] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:136:0) after 1m0s
[21:26:10.136] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:136:0)
[21:26:10.136] Timer tick, asking Buildbucket for the build status
[21:26:10.555] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:10.555] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:137:0) after 1m0s
[21:27:10.604] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:137:0)
[21:27:10.604] Timer tick, asking Buildbucket for the build status
[21:27:10.773] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:10.773] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:138:0) after 1m0s
[21:28:10.824] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:138:0)
[21:28:10.824] Timer tick, asking Buildbucket for the build status
[21:28:11.155] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:11.155] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:139:0) after 1m0s
[21:29:11.187] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:139:0)
[21:29:11.187] Timer tick, asking Buildbucket for the build status
[21:29:11.536] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:11.536] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:140:0) after 1m0s
[21:30:11.569] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:140:0)
[21:30:11.569] Timer tick, asking Buildbucket for the build status
[21:30:12.169] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:12.169] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:141:0) after 1m0s
[21:31:12.219] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:141:0)
[21:31:12.219] Timer tick, asking Buildbucket for the build status
[21:31:13.013] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:13.014] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:142:0) after 1m0s
[21:32:13.114] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:142:0)
[21:32:13.114] Timer tick, asking Buildbucket for the build status
[21:32:13.382] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:13.382] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:143:0) after 1m0s
[21:33:13.407] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:143:0)
[21:33:13.407] Timer tick, asking Buildbucket for the build status
[21:33:13.573] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:13.573] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:144:0) after 1m0s
[21:34:13.698] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:144:0)
[21:34:13.698] Timer tick, asking Buildbucket for the build status
[21:34:13.855] Build 8880752497862232848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:13.856] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9046182563079753088:145:0) after 1m0s
[21:35:11.802] Received PubSub notification, asking Buildbucket for the build status
[21:35:11.924] Build 8880752497862232848: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:35:11.924] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1589060110877587",
"created_by": "project:chromium",
"created_ts": "1589051454636099",
"id": "8880752497862232848",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5f68ddff55349f628ae14acf59dd94267d79ad36\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"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-ugql\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 16453, \"got_angle_revision\": \"9f32304208deb75106cd641e8cb09af93f8df860\", \"got_dawn_revision\": \"562255a94346d1121c433abfa2abe39aee135804\", \"got_nacl_revision\": \"98e6f0f2dc32172835122736bbc317335d8d20e4\", \"got_revision\": \"5f68ddff55349f628ae14acf59dd94267d79ad36\", \"got_revision_cp\": \"refs/heads/master@{#767118}\", \"got_swarming_client_revision\": \"160b445a44e0daacf6f3f8570ca2707ec451f374\", \"got_swiftshader_revision\": \"7b87548e5a639efd25edb2939198077c033dfb6a\", \"got_v8_revision\": \"797cb87973ec7abb6376738a0455e7d943932c27\", \"got_v8_revision_cp\": \"refs/heads/8.4.331@{#1}\", \"got_webrtc_revision\": \"33d81a05ebd4d4e3e50be08d64ed944c2f8d42fd\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31192}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5f68ddff55349f628ae14acf59dd94267d79ad36\"}, \"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-ugql\"], \"image\": [\"chrome-win10-1703-20042500-5e6af220c3f\"], \"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\": [\"5050-4c62a25\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1589051564321193",
"status": "COMPLETED",
"status_changed_ts": "1589060111495201",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/16453",
"builder:Windows deterministic",
"buildset:commit/git/5f68ddff55349f628ae14acf59dd94267d79ad36",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5f68ddff55349f628ae14acf59dd94267d79ad36",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9046182563079753088",
"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/8880752497862232848/+/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:4c13aa7fb2baa010",
"user_agent:luci-scheduler"
],
"updated_ts": "1589060111499299",
"url": "https://ci.chromium.org/b/8880752497862232848",
"utcnow_ts": "1589060111909428"
}
}
[21:35:11.924] Invocation finished in 2h24m21.648671124s with status SUCCEEDED