[02:17:28.631] New invocation is queued and will start shortly
[02:17:29.808] Starting the invocation (attempt 1)
[02:17:29.856] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:17:29.856] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:17:29.856] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9062010190397720416",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"bde499ee3b1989f4408c8fefbe043a7d4ddd6e75\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9062010190397720416",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/bde499ee3b1989f4408c8fefbe043a7d4ddd6e75",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bde499ee3b1989f4408c8fefbe043a7d4ddd6e75",
"gitiles_ref:refs/heads/master"
]
}
[02:17:31.414] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1573957050400973",
"id": "8896580127878106480",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bde499ee3b1989f4408c8fefbe043a7d4ddd6e75\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1573957050964300",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/15223",
"builder:Windows deterministic",
"buildset:commit/git/bde499ee3b1989f4408c8fefbe043a7d4ddd6e75",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bde499ee3b1989f4408c8fefbe043a7d4ddd6e75",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9062010190397720416",
"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/8896580127878106480/+/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": "1573957050964824",
"url": "https://ci.chromium.org/b/8896580127878106480",
"utcnow_ts": "1573957051402861"
}
}
[02:17:31.414] Task URL: https://ci.chromium.org/b/8896580127878106480
[02:17:31.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:2:0) after 1m0s
[02:17:34.096] Received PubSub notification, asking Buildbucket for the build status
[02:17:34.439] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:31.518] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:2:0)
[02:18:31.518] Timer tick, asking Buildbucket for the build status
[02:18:31.709] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:31.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:4:0) after 1m0s
[02:19:31.887] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:4:0)
[02:19:31.887] Timer tick, asking Buildbucket for the build status
[02:19:32.335] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:32.335] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:5:0) after 1m0s
[02:20:32.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:5:0)
[02:20:32.467] Timer tick, asking Buildbucket for the build status
[02:20:32.874] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:32.874] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:6:0) after 1m0s
[02:21:33.119] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:6:0)
[02:21:33.119] Timer tick, asking Buildbucket for the build status
[02:21:33.326] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:33.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:7:0) after 1m0s
[02:22:33.387] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:7:0)
[02:22:33.387] Timer tick, asking Buildbucket for the build status
[02:22:33.534] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:33.534] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:8:0) after 1m0s
[02:23:33.612] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:8:0)
[02:23:33.612] Timer tick, asking Buildbucket for the build status
[02:23:33.803] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:33.803] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:9:0) after 1m0s
[02:24:33.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:9:0)
[02:24:33.815] Timer tick, asking Buildbucket for the build status
[02:24:34.239] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:34.239] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:10:0) after 1m0s
[02:25:34.381] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:10:0)
[02:25:34.381] Timer tick, asking Buildbucket for the build status
[02:25:34.545] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:34.545] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:11:0) after 1m0s
[02:26:34.700] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:11:0)
[02:26:34.700] Timer tick, asking Buildbucket for the build status
[02:26:34.906] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:34.906] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:12:0) after 1m0s
[02:27:35.034] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:12:0)
[02:27:35.034] Timer tick, asking Buildbucket for the build status
[02:27:35.596] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:35.596] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:13:0) after 1m0s
[02:28:35.808] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:13:0)
[02:28:35.808] Timer tick, asking Buildbucket for the build status
[02:28:35.989] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:35.989] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:14:0) after 1m0s
[02:29:36.126] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:14:0)
[02:29:36.126] Timer tick, asking Buildbucket for the build status
[02:29:37.128] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:37.128] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:15:0) after 1m0s
[02:30:37.174] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:15:0)
[02:30:37.174] Timer tick, asking Buildbucket for the build status
[02:30:38.640] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:38.640] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:16:0) after 1m0s
[02:31:38.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:16:0)
[02:31:38.721] Timer tick, asking Buildbucket for the build status
[02:31:39.620] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:39.620] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:17:0) after 1m0s
[02:32:39.642] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:17:0)
[02:32:39.642] Timer tick, asking Buildbucket for the build status
[02:32:40.525] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:40.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:18:0) after 1m0s
[02:33:40.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:18:0)
[02:33:40.637] Timer tick, asking Buildbucket for the build status
[02:33:40.890] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:40.890] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:19:0) after 1m0s
[02:34:40.975] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:19:0)
[02:34:40.975] Timer tick, asking Buildbucket for the build status
[02:34:41.126] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:41.126] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:20:0) after 1m0s
[02:35:41.293] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:20:0)
[02:35:41.293] Timer tick, asking Buildbucket for the build status
[02:35:41.726] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:41.726] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:21:0) after 1m0s
[02:36:41.816] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:21:0)
[02:36:41.816] Timer tick, asking Buildbucket for the build status
[02:36:41.976] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:41.976] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:22:0) after 1m0s
[02:37:42.088] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:22:0)
[02:37:42.088] Timer tick, asking Buildbucket for the build status
[02:37:42.277] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:42.277] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:23:0) after 1m0s
[02:38:42.471] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:23:0)
[02:38:42.471] Timer tick, asking Buildbucket for the build status
[02:38:42.719] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:42.719] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:24:0) after 1m0s
[02:39:42.799] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:24:0)
[02:39:42.799] Timer tick, asking Buildbucket for the build status
[02:39:43.034] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:43.034] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:25:0) after 1m0s
[02:40:43.101] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:25:0)
[02:40:43.101] Timer tick, asking Buildbucket for the build status
[02:40:43.899] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:43.899] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:26:0) after 1m0s
[02:41:44.152] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:26:0)
[02:41:44.152] Timer tick, asking Buildbucket for the build status
[02:41:44.349] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:44.349] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:27:0) after 1m0s
[02:42:44.389] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:27:0)
[02:42:44.389] Timer tick, asking Buildbucket for the build status
[02:42:44.996] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:44.996] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:28:0) after 1m0s
[02:43:45.072] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:28:0)
[02:43:45.072] Timer tick, asking Buildbucket for the build status
[02:43:45.335] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:45.335] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:29:0) after 1m0s
[02:44:45.376] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:29:0)
[02:44:45.376] Timer tick, asking Buildbucket for the build status
[02:44:46.471] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:46.471] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:30:0) after 1m0s
[02:45:46.716] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:30:0)
[02:45:46.716] Timer tick, asking Buildbucket for the build status
[02:45:47.122] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:47.122] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:31:0) after 1m0s
[02:46:47.140] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:31:0)
[02:46:47.140] Timer tick, asking Buildbucket for the build status
[02:46:47.892] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:47.892] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:32:0) after 1m0s
[02:47:47.903] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:32:0)
[02:47:47.903] Timer tick, asking Buildbucket for the build status
[02:47:48.053] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:48.053] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:33:0) after 1m0s
[02:48:48.186] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:33:0)
[02:48:48.186] Timer tick, asking Buildbucket for the build status
[02:48:48.349] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:48.349] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:34:0) after 1m0s
[02:49:48.395] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:34:0)
[02:49:48.395] Timer tick, asking Buildbucket for the build status
[02:49:49.217] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:49.217] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:35:0) after 1m0s
[02:50:49.247] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:35:0)
[02:50:49.247] Timer tick, asking Buildbucket for the build status
[02:50:49.798] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:49.798] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:36:0) after 1m0s
[02:51:49.872] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:36:0)
[02:51:49.872] Timer tick, asking Buildbucket for the build status
[02:51:50.013] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:50.013] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:37:0) after 1m0s
[02:52:50.102] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:37:0)
[02:52:50.102] Timer tick, asking Buildbucket for the build status
[02:52:50.264] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:50.264] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:38:0) after 1m0s
[02:53:50.290] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:38:0)
[02:53:50.290] Timer tick, asking Buildbucket for the build status
[02:53:50.759] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:50.759] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:39:0) after 1m0s
[02:54:51.538] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:39:0)
[02:54:51.538] Timer tick, asking Buildbucket for the build status
[02:54:52.149] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:52.149] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:40:0) after 1m0s
[02:55:52.298] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:40:0)
[02:55:52.298] Timer tick, asking Buildbucket for the build status
[02:55:52.447] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:52.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:41:0) after 1m0s
[02:56:52.589] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:41:0)
[02:56:52.589] Timer tick, asking Buildbucket for the build status
[02:56:53.507] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:53.507] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:42:0) after 1m0s
[02:57:53.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:42:0)
[02:57:53.757] Timer tick, asking Buildbucket for the build status
[02:57:55.997] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:55.997] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:43:0) after 1m0s
[02:58:56.067] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:43:0)
[02:58:56.067] Timer tick, asking Buildbucket for the build status
[02:58:56.329] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:56.329] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:44:0) after 1m0s
[02:59:56.376] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:44:0)
[02:59:56.376] Timer tick, asking Buildbucket for the build status
[02:59:56.824] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:56.824] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:45:0) after 1m0s
[03:00:56.926] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:45:0)
[03:00:56.926] Timer tick, asking Buildbucket for the build status
[03:00:57.335] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:57.335] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:46:0) after 1m0s
[03:01:57.364] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:46:0)
[03:01:57.364] Timer tick, asking Buildbucket for the build status
[03:01:57.772] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:57.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:47:0) after 1m0s
[03:02:59.073] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:47:0)
[03:02:59.073] Timer tick, asking Buildbucket for the build status
[03:02:59.220] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:59.220] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:48:0) after 1m0s
[03:03:59.355] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:48:0)
[03:03:59.355] Timer tick, asking Buildbucket for the build status
[03:03:59.512] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:59.512] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:49:0) after 1m0s
[03:04:59.680] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:49:0)
[03:04:59.680] Timer tick, asking Buildbucket for the build status
[03:04:59.837] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:59.837] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:50:0) after 1m0s
[03:05:59.841] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:50:0)
[03:05:59.841] Timer tick, asking Buildbucket for the build status
[03:06:00.063] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:00.063] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:51:0) after 1m0s
[03:07:01.365] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:51:0)
[03:07:01.365] Timer tick, asking Buildbucket for the build status
[03:07:03.864] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:03.864] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:52:0) after 1m0s
[03:08:04.143] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:52:0)
[03:08:04.143] Timer tick, asking Buildbucket for the build status
[03:08:05.321] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:05.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:53:0) after 1m0s
[03:09:05.631] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:53:0)
[03:09:05.631] Timer tick, asking Buildbucket for the build status
[03:09:06.031] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:06.031] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:54:0) after 1m0s
[03:10:06.192] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:54:0)
[03:10:06.192] Timer tick, asking Buildbucket for the build status
[03:10:06.738] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:06.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:55:0) after 1m0s
[03:11:06.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:55:0)
[03:11:06.886] Timer tick, asking Buildbucket for the build status
[03:11:07.359] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:07.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:56:0) after 1m0s
[03:12:07.643] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:56:0)
[03:12:07.643] Timer tick, asking Buildbucket for the build status
[03:12:08.144] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:08.144] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:57:0) after 1m0s
[03:13:08.499] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:57:0)
[03:13:08.499] Timer tick, asking Buildbucket for the build status
[03:13:08.835] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:08.835] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:58:0) after 1m0s
[03:14:09.039] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:58:0)
[03:14:09.039] Timer tick, asking Buildbucket for the build status
[03:14:09.856] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:09.856] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:59:0) after 1m0s
[03:15:09.933] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:59:0)
[03:15:09.933] Timer tick, asking Buildbucket for the build status
[03:15:10.235] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:10.235] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:60:0) after 1m0s
[03:16:10.423] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:60:0)
[03:16:10.423] Timer tick, asking Buildbucket for the build status
[03:16:10.956] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:10.956] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:61:0) after 1m0s
[03:17:11.166] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:61:0)
[03:17:11.166] Timer tick, asking Buildbucket for the build status
[03:17:11.323] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:11.323] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:62:0) after 1m0s
[03:18:11.779] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:62:0)
[03:18:11.779] Timer tick, asking Buildbucket for the build status
[03:18:11.948] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:11.948] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:63:0) after 1m0s
[03:19:12.186] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:63:0)
[03:19:12.186] Timer tick, asking Buildbucket for the build status
[03:19:12.374] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:12.374] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:64:0) after 1m0s
[03:20:12.419] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:64:0)
[03:20:12.419] Timer tick, asking Buildbucket for the build status
[03:20:13.234] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:13.234] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:65:0) after 1m0s
[03:21:13.304] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:65:0)
[03:21:13.304] Timer tick, asking Buildbucket for the build status
[03:21:14.591] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:14.591] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:66:0) after 1m0s
[03:22:14.703] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:66:0)
[03:22:14.704] Timer tick, asking Buildbucket for the build status
[03:22:14.871] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:14.871] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:67:0) after 1m0s
[03:23:14.914] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:67:0)
[03:23:14.914] Timer tick, asking Buildbucket for the build status
[03:23:15.681] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:15.681] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:68:0) after 1m0s
[03:24:15.771] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:68:0)
[03:24:15.771] Timer tick, asking Buildbucket for the build status
[03:24:16.546] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:16.546] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:69:0) after 1m0s
[03:25:16.575] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:69:0)
[03:25:16.575] Timer tick, asking Buildbucket for the build status
[03:25:17.194] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:17.194] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:70:0) after 1m0s
[03:26:17.219] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:70:0)
[03:26:17.219] Timer tick, asking Buildbucket for the build status
[03:26:17.442] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:17.442] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:71:0) after 1m0s
[03:27:17.618] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:71:0)
[03:27:17.621] Timer tick, asking Buildbucket for the build status
[03:27:18.390] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:18.390] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:72:0) after 1m0s
[03:28:18.520] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:72:0)
[03:28:18.520] Timer tick, asking Buildbucket for the build status
[03:28:18.652] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:18.652] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:73:0) after 1m0s
[03:29:19.097] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:73:0)
[03:29:19.097] Timer tick, asking Buildbucket for the build status
[03:29:19.250] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:19.250] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:74:0) after 1m0s
[03:30:19.348] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:74:0)
[03:30:19.348] Timer tick, asking Buildbucket for the build status
[03:30:19.610] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:19.610] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:75:0) after 1m0s
[03:31:19.756] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:75:0)
[03:31:19.756] Timer tick, asking Buildbucket for the build status
[03:31:19.902] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:19.902] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:76:0) after 1m0s
[03:32:19.928] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:76:0)
[03:32:19.928] Timer tick, asking Buildbucket for the build status
[03:32:20.402] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:20.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:77:0) after 1m0s
[03:33:20.395] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:77:0)
[03:33:20.395] Timer tick, asking Buildbucket for the build status
[03:33:21.981] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:21.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:78:0) after 1m0s
[03:34:22.122] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:78:0)
[03:34:22.122] Timer tick, asking Buildbucket for the build status
[03:34:23.298] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:23.298] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:79:0) after 1m0s
[03:35:23.465] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:79:0)
[03:35:23.465] Timer tick, asking Buildbucket for the build status
[03:35:24.418] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:24.418] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:80:0) after 1m0s
[03:36:24.449] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:80:0)
[03:36:24.450] Timer tick, asking Buildbucket for the build status
[03:36:24.722] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:24.722] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:81:0) after 1m0s
[03:37:24.755] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:81:0)
[03:37:24.755] Timer tick, asking Buildbucket for the build status
[03:37:24.940] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:24.940] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:82:0) after 1m0s
[03:38:24.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:82:0)
[03:38:24.963] Timer tick, asking Buildbucket for the build status
[03:38:25.353] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:25.353] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:83:0) after 1m0s
[03:39:25.461] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:83:0)
[03:39:25.461] Timer tick, asking Buildbucket for the build status
[03:39:25.810] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:25.810] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:84:0) after 1m0s
[03:40:25.825] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:84:0)
[03:40:25.825] Timer tick, asking Buildbucket for the build status
[03:40:26.106] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:26.106] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:85:0) after 1m0s
[03:41:26.141] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:85:0)
[03:41:26.141] Timer tick, asking Buildbucket for the build status
[03:41:26.721] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:26.721] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:86:0) after 1m0s
[03:42:26.803] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:86:0)
[03:42:26.803] Timer tick, asking Buildbucket for the build status
[03:42:27.079] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:27.079] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:87:0) after 1m0s
[03:43:27.073] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:87:0)
[03:43:27.073] Timer tick, asking Buildbucket for the build status
[03:43:27.356] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:27.356] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:88:0) after 1m0s
[03:44:27.385] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:88:0)
[03:44:27.385] Timer tick, asking Buildbucket for the build status
[03:44:28.621] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:28.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:89:0) after 1m0s
[03:45:28.881] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:89:0)
[03:45:28.881] Timer tick, asking Buildbucket for the build status
[03:45:29.223] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:29.223] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:90:0) after 1m0s
[03:46:29.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:90:0)
[03:46:29.377] Timer tick, asking Buildbucket for the build status
[03:46:29.772] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:29.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:91:0) after 1m0s
[03:47:29.996] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:91:0)
[03:47:29.996] Timer tick, asking Buildbucket for the build status
[03:47:30.213] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:30.213] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:92:0) after 1m0s
[03:48:30.240] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:92:0)
[03:48:30.240] Timer tick, asking Buildbucket for the build status
[03:48:30.415] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:30.415] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:93:0) after 1m0s
[03:49:30.460] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:93:0)
[03:49:30.460] Timer tick, asking Buildbucket for the build status
[03:49:30.895] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:30.895] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:94:0) after 1m0s
[03:50:30.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:94:0)
[03:50:30.982] Timer tick, asking Buildbucket for the build status
[03:50:33.201] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:33.201] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:95:0) after 1m0s
[03:51:33.231] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:95:0)
[03:51:33.231] Timer tick, asking Buildbucket for the build status
[03:51:33.372] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:33.372] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:96:0) after 1m0s
[03:52:33.420] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:96:0)
[03:52:33.420] Timer tick, asking Buildbucket for the build status
[03:52:33.558] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:33.558] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:97:0) after 1m0s
[03:53:33.542] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:97:0)
[03:53:33.542] Timer tick, asking Buildbucket for the build status
[03:53:33.668] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:33.668] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:98:0) after 1m0s
[03:54:33.762] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:98:0)
[03:54:33.762] Timer tick, asking Buildbucket for the build status
[03:54:34.258] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:34.258] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:99:0) after 1m0s
[03:55:34.395] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:99:0)
[03:55:34.395] Timer tick, asking Buildbucket for the build status
[03:55:34.591] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:34.591] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:100:0) after 1m0s
[03:56:34.615] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:100:0)
[03:56:34.615] Timer tick, asking Buildbucket for the build status
[03:56:34.745] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:34.745] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:101:0) after 1m0s
[03:57:34.818] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:101:0)
[03:57:34.818] Timer tick, asking Buildbucket for the build status
[03:57:35.926] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:35.926] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:102:0) after 1m0s
[03:58:35.945] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:102:0)
[03:58:35.945] Timer tick, asking Buildbucket for the build status
[03:58:37.000] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:37.000] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:103:0) after 1m0s
[03:59:37.079] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:103:0)
[03:59:37.079] Timer tick, asking Buildbucket for the build status
[03:59:38.068] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:38.068] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:104:0) after 1m0s
[04:00:38.518] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:104:0)
[04:00:38.518] Timer tick, asking Buildbucket for the build status
[04:00:38.689] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:38.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:105:0) after 1m0s
[04:01:38.780] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:105:0)
[04:01:38.780] Timer tick, asking Buildbucket for the build status
[04:01:39.202] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:39.202] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:106:0) after 1m0s
[04:02:39.251] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:106:0)
[04:02:39.251] Timer tick, asking Buildbucket for the build status
[04:02:39.957] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:39.957] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:107:0) after 1m0s
[04:03:40.005] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:107:0)
[04:03:40.005] Timer tick, asking Buildbucket for the build status
[04:03:41.144] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:41.144] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:108:0) after 1m0s
[04:04:41.297] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:108:0)
[04:04:41.297] Timer tick, asking Buildbucket for the build status
[04:04:41.453] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:41.453] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:109:0) after 1m0s
[04:05:41.440] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:109:0)
[04:05:41.440] Timer tick, asking Buildbucket for the build status
[04:05:41.635] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:41.635] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:110:0) after 1m0s
[04:06:41.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:110:0)
[04:06:41.989] Timer tick, asking Buildbucket for the build status
[04:06:42.625] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:42.626] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:111:0) after 1m0s
[04:07:42.863] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:111:0)
[04:07:42.863] Timer tick, asking Buildbucket for the build status
[04:07:44.338] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:44.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:112:0) after 1m0s
[04:08:44.529] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:112:0)
[04:08:44.529] Timer tick, asking Buildbucket for the build status
[04:08:44.745] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:44.745] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:113:0) after 1m0s
[04:09:44.867] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:113:0)
[04:09:44.867] Timer tick, asking Buildbucket for the build status
[04:09:45.013] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:45.013] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:114:0) after 1m0s
[04:10:45.234] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:114:0)
[04:10:45.234] Timer tick, asking Buildbucket for the build status
[04:10:46.200] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:46.200] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:115:0) after 1m0s
[04:11:46.229] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:115:0)
[04:11:46.229] Timer tick, asking Buildbucket for the build status
[04:11:46.384] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:46.384] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:116:0) after 1m0s
[04:12:46.416] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:116:0)
[04:12:46.416] Timer tick, asking Buildbucket for the build status
[04:12:46.679] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:46.679] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:117:0) after 1m0s
[04:13:46.712] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:117:0)
[04:13:46.712] Timer tick, asking Buildbucket for the build status
[04:13:47.006] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:47.006] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:118:0) after 1m0s
[04:14:47.125] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:118:0)
[04:14:47.125] Timer tick, asking Buildbucket for the build status
[04:14:47.321] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:47.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:119:0) after 1m0s
[04:15:47.665] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:119:0)
[04:15:47.665] Timer tick, asking Buildbucket for the build status
[04:15:47.824] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:47.824] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:120:0) after 1m0s
[04:16:48.248] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:120:0)
[04:16:48.248] Timer tick, asking Buildbucket for the build status
[04:16:49.003] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:49.003] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:121:0) after 1m0s
[04:17:49.354] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:121:0)
[04:17:49.354] Timer tick, asking Buildbucket for the build status
[04:17:51.117] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:51.117] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:122:0) after 1m0s
[04:18:51.193] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:122:0)
[04:18:51.193] Timer tick, asking Buildbucket for the build status
[04:18:51.421] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:51.421] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:123:0) after 1m0s
[04:19:51.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:123:0)
[04:19:51.467] Timer tick, asking Buildbucket for the build status
[04:19:51.820] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:51.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:124:0) after 1m0s
[04:20:52.115] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:124:0)
[04:20:52.115] Timer tick, asking Buildbucket for the build status
[04:20:53.156] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:53.156] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:125:0) after 1m0s
[04:21:53.358] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:125:0)
[04:21:53.358] Timer tick, asking Buildbucket for the build status
[04:21:53.976] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:53.976] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:126:0) after 1m0s
[04:22:54.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:126:0)
[04:22:54.194] Timer tick, asking Buildbucket for the build status
[04:22:54.352] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:54.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:127:0) after 1m0s
[04:23:54.605] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:127:0)
[04:23:54.605] Timer tick, asking Buildbucket for the build status
[04:23:55.103] Build 8896580127878106480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:55.103] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062010190397720416:128:0) after 1m0s
[04:24:11.077] Received PubSub notification, asking Buildbucket for the build status
[04:24:11.184] Build 8896580127878106480: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:24:11.184] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1573964650301777",
"created_by": "project:chromium",
"created_ts": "1573957050400973",
"id": "8896580127878106480",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bde499ee3b1989f4408c8fefbe043a7d4ddd6e75\"}}",
"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-t6xr\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 15223, \"got_angle_revision\": \"51b5a3222b60d5f523d04b5a86eeb28278880db4\", \"got_dawn_revision\": \"a930e599e17c14e0a72fe7403aab69d791885da8\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"bde499ee3b1989f4408c8fefbe043a7d4ddd6e75\", \"got_revision_cp\": \"refs/heads/master@{#716042}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"aba1020d39145285f8d8df82d6f0b83d0b0e5386\", \"got_v8_revision\": \"295649fd927eeaaaf8ec10d68b45602e89e94223\", \"got_v8_revision_cp\": \"refs/heads/8.0.284@{#1}\", \"got_webrtc_revision\": \"fd0e32a87a4e6a9a6cfeb02fbdcddd6edf4ac9c8\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29804}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bde499ee3b1989f4408c8fefbe043a7d4ddd6e75\"}, \"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-t6xr\"], \"image\": [\"chrome-win10-1703-19102400-d35fedf5a0d\"], \"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\": [\"4632-c6c17d9\"], \"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": "1573957053297474",
"status": "COMPLETED",
"status_changed_ts": "1573964650668044",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/15223",
"builder:Windows deterministic",
"buildset:commit/git/bde499ee3b1989f4408c8fefbe043a7d4ddd6e75",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bde499ee3b1989f4408c8fefbe043a7d4ddd6e75",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9062010190397720416",
"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/8896580127878106480/+/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:488ff82c2db57610",
"user_agent:luci-scheduler"
],
"updated_ts": "1573964650676257",
"url": "https://ci.chromium.org/b/8896580127878106480",
"utcnow_ts": "1573964651177209"
}
}
[04:24:11.185] Invocation finished in 2h6m42.571626309s with status SUCCEEDED