[04:35:12.761] New invocation is queued and will start shortly
[04:35:14.000] Starting the invocation (attempt 1)
[04:35:14.079] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:35:14.080] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:35:14.080] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9061367346065803600",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"20ff88b225b1d19f93d570e531b523bf1915235e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9061367346065803600",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/20ff88b225b1d19f93d570e531b523bf1915235e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/20ff88b225b1d19f93d570e531b523bf1915235e",
"gitiles_ref:refs/heads/master"
]
}
[04:35:15.083] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1574570114342227",
"id": "8895937283741937552",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"20ff88b225b1d19f93d570e531b523bf1915235e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1574570114852128",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/15283",
"builder:Windows deterministic",
"buildset:commit/git/20ff88b225b1d19f93d570e531b523bf1915235e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/20ff88b225b1d19f93d570e531b523bf1915235e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061367346065803600",
"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/8895937283741937552/+/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": "1574570114852244",
"url": "https://ci.chromium.org/b/8895937283741937552",
"utcnow_ts": "1574570115064765"
}
}
[04:35:15.084] Task URL: https://ci.chromium.org/b/8895937283741937552
[04:35:15.084] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:2:0) after 1m0s
[04:35:19.164] Received PubSub notification, asking Buildbucket for the build status
[04:35:19.829] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:15.182] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:2:0)
[04:36:15.182] Timer tick, asking Buildbucket for the build status
[04:36:15.390] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:15.390] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:4:0) after 1m0s
[04:37:15.434] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:4:0)
[04:37:15.434] Timer tick, asking Buildbucket for the build status
[04:37:16.182] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:16.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:5:0) after 1m0s
[04:38:16.395] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:5:0)
[04:38:16.395] Timer tick, asking Buildbucket for the build status
[04:38:16.925] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:16.925] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:6:0) after 1m0s
[04:39:16.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:6:0)
[04:39:16.989] Timer tick, asking Buildbucket for the build status
[04:39:17.235] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:17.235] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:7:0) after 1m0s
[04:40:17.307] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:7:0)
[04:40:17.307] Timer tick, asking Buildbucket for the build status
[04:40:18.612] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:18.612] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:8:0) after 1m0s
[04:41:18.701] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:8:0)
[04:41:18.701] Timer tick, asking Buildbucket for the build status
[04:41:18.861] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:18.861] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:9:0) after 1m0s
[04:42:18.945] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:9:0)
[04:42:18.945] Timer tick, asking Buildbucket for the build status
[04:42:19.275] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:19.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:10:0) after 1m0s
[04:43:19.306] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:10:0)
[04:43:19.306] Timer tick, asking Buildbucket for the build status
[04:43:20.238] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:20.238] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:11:0) after 1m0s
[04:44:20.276] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:11:0)
[04:44:20.276] Timer tick, asking Buildbucket for the build status
[04:44:20.505] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:20.505] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:12:0) after 1m0s
[04:45:20.557] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:12:0)
[04:45:20.557] Timer tick, asking Buildbucket for the build status
[04:45:20.769] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:20.769] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:13:0) after 1m0s
[04:46:20.847] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:13:0)
[04:46:20.847] Timer tick, asking Buildbucket for the build status
[04:46:21.409] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:21.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:14:0) after 1m0s
[04:47:21.575] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:14:0)
[04:47:21.575] Timer tick, asking Buildbucket for the build status
[04:47:22.002] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:22.002] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:15:0) after 1m0s
[04:48:21.975] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:15:0)
[04:48:21.975] Timer tick, asking Buildbucket for the build status
[04:48:22.617] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:22.617] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:16:0) after 1m0s
[04:49:22.666] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:16:0)
[04:49:22.666] Timer tick, asking Buildbucket for the build status
[04:49:22.888] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:22.888] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:17:0) after 1m0s
[04:50:22.950] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:17:0)
[04:50:22.950] Timer tick, asking Buildbucket for the build status
[04:50:24.431] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:24.431] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:18:0) after 1m0s
[04:51:24.452] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:18:0)
[04:51:24.452] Timer tick, asking Buildbucket for the build status
[04:51:24.613] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:24.613] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:19:0) after 1m0s
[04:52:24.635] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:19:0)
[04:52:24.635] Timer tick, asking Buildbucket for the build status
[04:52:25.003] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:25.003] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:20:0) after 1m0s
[04:53:25.018] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:20:0)
[04:53:25.018] Timer tick, asking Buildbucket for the build status
[04:53:25.502] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:25.502] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:21:0) after 1m0s
[04:54:25.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:21:0)
[04:54:25.528] Timer tick, asking Buildbucket for the build status
[04:54:25.759] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:25.759] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:22:0) after 1m0s
[04:55:25.784] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:22:0)
[04:55:25.784] Timer tick, asking Buildbucket for the build status
[04:55:25.941] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:25.941] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:23:0) after 1m0s
[04:56:25.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:23:0)
[04:56:25.978] Timer tick, asking Buildbucket for the build status
[04:56:26.184] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:26.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:24:0) after 1m0s
[04:57:26.284] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:24:0)
[04:57:26.284] Timer tick, asking Buildbucket for the build status
[04:57:26.564] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:26.564] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:25:0) after 1m0s
[04:58:26.671] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:25:0)
[04:58:26.671] Timer tick, asking Buildbucket for the build status
[04:58:27.244] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:27.244] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:26:0) after 1m0s
[04:59:27.335] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:26:0)
[04:59:27.335] Timer tick, asking Buildbucket for the build status
[04:59:27.511] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:27.511] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:27:0) after 1m0s
[05:00:27.582] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:27:0)
[05:00:27.582] Timer tick, asking Buildbucket for the build status
[05:00:27.838] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:27.838] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:28:0) after 1m0s
[05:01:27.881] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:28:0)
[05:01:27.882] Timer tick, asking Buildbucket for the build status
[05:01:28.407] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:28.407] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:29:0) after 1m0s
[05:02:28.501] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:29:0)
[05:02:28.501] Timer tick, asking Buildbucket for the build status
[05:02:29.082] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:29.082] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:30:0) after 1m0s
[05:03:29.163] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:30:0)
[05:03:29.163] Timer tick, asking Buildbucket for the build status
[05:03:29.472] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:29.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:31:0) after 1m0s
[05:04:29.551] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:31:0)
[05:04:29.551] Timer tick, asking Buildbucket for the build status
[05:04:31.724] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:31.724] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:32:0) after 1m0s
[05:05:31.730] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:32:0)
[05:05:31.730] Timer tick, asking Buildbucket for the build status
[05:05:32.107] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:32.107] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:33:0) after 1m0s
[05:06:32.140] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:33:0)
[05:06:32.140] Timer tick, asking Buildbucket for the build status
[05:06:32.358] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:32.358] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:34:0) after 1m0s
[05:07:32.484] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:34:0)
[05:07:32.484] Timer tick, asking Buildbucket for the build status
[05:07:33.750] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:33.750] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:35:0) after 1m0s
[05:08:33.801] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:35:0)
[05:08:33.801] Timer tick, asking Buildbucket for the build status
[05:08:33.957] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:33.957] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:36:0) after 1m0s
[05:09:34.029] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:36:0)
[05:09:34.029] Timer tick, asking Buildbucket for the build status
[05:09:34.330] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:34.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:37:0) after 1m0s
[05:10:34.441] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:37:0)
[05:10:34.441] Timer tick, asking Buildbucket for the build status
[05:10:36.736] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:36.736] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:38:0) after 1m0s
[05:11:36.786] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:38:0)
[05:11:36.786] Timer tick, asking Buildbucket for the build status
[05:11:36.941] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:36.941] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:39:0) after 1m0s
[05:12:37.006] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:39:0)
[05:12:37.006] Timer tick, asking Buildbucket for the build status
[05:12:37.174] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:37.174] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:40:0) after 1m0s
[05:13:37.378] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:40:0)
[05:13:37.378] Timer tick, asking Buildbucket for the build status
[05:13:38.181] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:38.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:41:0) after 1m0s
[05:14:38.623] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:41:0)
[05:14:38.623] Timer tick, asking Buildbucket for the build status
[05:14:38.759] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:38.759] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:42:0) after 1m0s
[05:15:38.864] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:42:0)
[05:15:38.864] Timer tick, asking Buildbucket for the build status
[05:15:39.039] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:39.039] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:43:0) after 1m0s
[05:16:39.240] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:43:0)
[05:16:39.240] Timer tick, asking Buildbucket for the build status
[05:16:40.264] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:40.264] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:44:0) after 1m0s
[05:17:40.353] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:44:0)
[05:17:40.353] Timer tick, asking Buildbucket for the build status
[05:17:40.855] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:40.855] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:45:0) after 1m0s
[05:18:40.851] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:45:0)
[05:18:40.851] Timer tick, asking Buildbucket for the build status
[05:18:41.038] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:41.038] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:46:0) after 1m0s
[05:19:41.088] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:46:0)
[05:19:41.088] Timer tick, asking Buildbucket for the build status
[05:19:41.386] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:41.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:47:0) after 1m0s
[05:20:41.473] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:47:0)
[05:20:41.473] Timer tick, asking Buildbucket for the build status
[05:20:41.699] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:41.699] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:48:0) after 1m0s
[05:21:41.820] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:48:0)
[05:21:41.820] Timer tick, asking Buildbucket for the build status
[05:21:41.998] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:41.998] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:49:0) after 1m0s
[05:22:42.043] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:49:0)
[05:22:42.043] Timer tick, asking Buildbucket for the build status
[05:22:42.331] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:42.331] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:50:0) after 1m0s
[05:23:42.463] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:50:0)
[05:23:42.463] Timer tick, asking Buildbucket for the build status
[05:23:42.666] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:42.666] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:51:0) after 1m0s
[05:24:42.766] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:51:0)
[05:24:42.766] Timer tick, asking Buildbucket for the build status
[05:24:43.016] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:43.016] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:52:0) after 1m0s
[05:25:43.054] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:52:0)
[05:25:43.054] Timer tick, asking Buildbucket for the build status
[05:25:43.548] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:43.548] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:53:0) after 1m0s
[05:26:43.714] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:53:0)
[05:26:43.714] Timer tick, asking Buildbucket for the build status
[05:26:44.546] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:44.546] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:54:0) after 1m0s
[05:27:44.604] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:54:0)
[05:27:44.604] Timer tick, asking Buildbucket for the build status
[05:27:45.027] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:45.027] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:55:0) after 1m0s
[05:28:45.069] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:55:0)
[05:28:45.069] Timer tick, asking Buildbucket for the build status
[05:28:45.805] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:45.805] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:56:0) after 1m0s
[05:29:45.838] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:56:0)
[05:29:45.838] Timer tick, asking Buildbucket for the build status
[05:29:46.497] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:46.497] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:57:0) after 1m0s
[05:30:46.541] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:57:0)
[05:30:46.541] Timer tick, asking Buildbucket for the build status
[05:30:46.805] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:46.805] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:58:0) after 1m0s
[05:31:46.883] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:58:0)
[05:31:46.883] Timer tick, asking Buildbucket for the build status
[05:31:47.204] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:47.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:59:0) after 1m0s
[05:32:47.245] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:59:0)
[05:32:47.245] Timer tick, asking Buildbucket for the build status
[05:32:47.444] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:47.444] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:60:0) after 1m0s
[05:33:47.453] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:60:0)
[05:33:47.453] Timer tick, asking Buildbucket for the build status
[05:33:47.653] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:47.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:61:0) after 1m0s
[05:34:47.685] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:61:0)
[05:34:47.685] Timer tick, asking Buildbucket for the build status
[05:34:47.821] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:47.821] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:62:0) after 1m0s
[05:35:47.848] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:62:0)
[05:35:47.848] Timer tick, asking Buildbucket for the build status
[05:35:48.271] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:48.271] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:63:0) after 1m0s
[05:36:48.301] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:63:0)
[05:36:48.301] Timer tick, asking Buildbucket for the build status
[05:36:48.493] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:48.493] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:64:0) after 1m0s
[05:37:48.525] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:64:0)
[05:37:48.525] Timer tick, asking Buildbucket for the build status
[05:37:48.679] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:48.679] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:65:0) after 1m0s
[05:38:48.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:65:0)
[05:38:48.705] Timer tick, asking Buildbucket for the build status
[05:38:49.267] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:49.267] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:66:0) after 1m0s
[05:39:49.307] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:66:0)
[05:39:49.307] Timer tick, asking Buildbucket for the build status
[05:39:49.857] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:49.857] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:67:0) after 1m0s
[05:40:49.883] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:67:0)
[05:40:49.883] Timer tick, asking Buildbucket for the build status
[05:40:50.252] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:50.252] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:68:0) after 1m0s
[05:41:50.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:68:0)
[05:41:50.277] Timer tick, asking Buildbucket for the build status
[05:41:50.603] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:50.603] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:69:0) after 1m0s
[05:42:50.647] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:69:0)
[05:42:50.647] Timer tick, asking Buildbucket for the build status
[05:42:51.615] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:51.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:70:0) after 1m0s
[05:43:51.656] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:70:0)
[05:43:51.656] Timer tick, asking Buildbucket for the build status
[05:43:52.078] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:52.078] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:71:0) after 1m0s
[05:44:52.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:71:0)
[05:44:52.137] Timer tick, asking Buildbucket for the build status
[05:44:52.669] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:52.669] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:72:0) after 1m0s
[05:45:52.820] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:72:0)
[05:45:52.820] Timer tick, asking Buildbucket for the build status
[05:45:52.974] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:52.974] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:73:0) after 1m0s
[05:46:53.084] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:73:0)
[05:46:53.084] Timer tick, asking Buildbucket for the build status
[05:46:53.351] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:53.351] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:74:0) after 1m0s
[05:47:53.459] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:74:0)
[05:47:53.459] Timer tick, asking Buildbucket for the build status
[05:47:54.192] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:54.192] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:75:0) after 1m0s
[05:48:54.380] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:75:0)
[05:48:54.380] Timer tick, asking Buildbucket for the build status
[05:48:54.603] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:54.603] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:76:0) after 1m0s
[05:49:54.791] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:76:0)
[05:49:54.791] Timer tick, asking Buildbucket for the build status
[05:49:55.029] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:55.029] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:77:0) after 1m0s
[05:50:55.184] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:77:0)
[05:50:55.184] Timer tick, asking Buildbucket for the build status
[05:50:55.414] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:55.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:78:0) after 1m0s
[05:51:55.521] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:78:0)
[05:51:55.521] Timer tick, asking Buildbucket for the build status
[05:51:56.123] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:56.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:79:0) after 1m0s
[05:52:56.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:79:0)
[05:52:56.155] Timer tick, asking Buildbucket for the build status
[05:52:57.137] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:57.137] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:80:0) after 1m0s
[05:53:57.204] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:80:0)
[05:53:57.204] Timer tick, asking Buildbucket for the build status
[05:53:57.805] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:57.805] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:81:0) after 1m0s
[05:54:57.936] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:81:0)
[05:54:57.936] Timer tick, asking Buildbucket for the build status
[05:54:58.450] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:58.450] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:82:0) after 1m0s
[05:55:58.491] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:82:0)
[05:55:58.491] Timer tick, asking Buildbucket for the build status
[05:55:58.674] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:58.674] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:83:0) after 1m0s
[05:56:58.670] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:83:0)
[05:56:58.670] Timer tick, asking Buildbucket for the build status
[05:56:58.876] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:58.876] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:84:0) after 1m0s
[05:57:58.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:84:0)
[05:57:58.911] Timer tick, asking Buildbucket for the build status
[05:57:59.849] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:59.849] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:85:0) after 1m0s
[05:58:59.884] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:85:0)
[05:58:59.884] Timer tick, asking Buildbucket for the build status
[05:59:00.247] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:00.247] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:86:0) after 1m0s
[06:00:00.289] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:86:0)
[06:00:00.289] Timer tick, asking Buildbucket for the build status
[06:00:01.181] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:01.181] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:87:0) after 1m0s
[06:01:01.222] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:87:0)
[06:01:01.222] Timer tick, asking Buildbucket for the build status
[06:01:03.932] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:03.932] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:88:0) after 1m0s
[06:02:04.141] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:88:0)
[06:02:04.141] Timer tick, asking Buildbucket for the build status
[06:02:04.383] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:04.383] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:89:0) after 1m0s
[06:03:04.415] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:89:0)
[06:03:04.415] Timer tick, asking Buildbucket for the build status
[06:03:04.545] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:04.545] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:90:0) after 1m0s
[06:04:04.727] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:90:0)
[06:04:04.727] Timer tick, asking Buildbucket for the build status
[06:04:05.536] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:05.536] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:91:0) after 1m0s
[06:05:05.625] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:91:0)
[06:05:05.625] Timer tick, asking Buildbucket for the build status
[06:05:05.761] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:05.762] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:92:0) after 1m0s
[06:06:05.791] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:92:0)
[06:06:05.791] Timer tick, asking Buildbucket for the build status
[06:06:06.358] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:06.358] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:93:0) after 1m0s
[06:07:06.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:93:0)
[06:07:06.599] Timer tick, asking Buildbucket for the build status
[06:07:06.791] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:06.791] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:94:0) after 1m0s
[06:08:06.830] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:94:0)
[06:08:06.830] Timer tick, asking Buildbucket for the build status
[06:08:07.615] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:07.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:95:0) after 1m0s
[06:09:07.638] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:95:0)
[06:09:07.638] Timer tick, asking Buildbucket for the build status
[06:09:08.152] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:08.152] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:96:0) after 1m0s
[06:10:08.220] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:96:0)
[06:10:08.220] Timer tick, asking Buildbucket for the build status
[06:10:08.477] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:08.477] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:97:0) after 1m0s
[06:11:08.613] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:97:0)
[06:11:08.613] Timer tick, asking Buildbucket for the build status
[06:11:10.123] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:10.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:98:0) after 1m0s
[06:12:10.198] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:98:0)
[06:12:10.198] Timer tick, asking Buildbucket for the build status
[06:12:10.350] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:10.350] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:99:0) after 1m0s
[06:13:10.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:99:0)
[06:13:10.417] Timer tick, asking Buildbucket for the build status
[06:13:10.945] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:10.945] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:100:0) after 1m0s
[06:14:11.085] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:100:0)
[06:14:11.085] Timer tick, asking Buildbucket for the build status
[06:14:11.237] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:11.237] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:101:0) after 1m0s
[06:15:11.306] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:101:0)
[06:15:11.306] Timer tick, asking Buildbucket for the build status
[06:15:11.707] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:11.707] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:102:0) after 1m0s
[06:16:11.736] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:102:0)
[06:16:11.736] Timer tick, asking Buildbucket for the build status
[06:16:13.327] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:13.327] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:103:0) after 1m0s
[06:17:13.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:103:0)
[06:17:13.683] Timer tick, asking Buildbucket for the build status
[06:17:14.122] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:14.122] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:104:0) after 1m0s
[06:18:14.184] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:104:0)
[06:18:14.184] Timer tick, asking Buildbucket for the build status
[06:18:14.805] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:14.805] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:105:0) after 1m0s
[06:19:15.079] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:105:0)
[06:19:15.079] Timer tick, asking Buildbucket for the build status
[06:19:15.356] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:15.356] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:106:0) after 1m0s
[06:20:15.490] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:106:0)
[06:20:15.490] Timer tick, asking Buildbucket for the build status
[06:20:15.680] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:15.680] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:107:0) after 1m0s
[06:21:15.932] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:107:0)
[06:21:15.932] Timer tick, asking Buildbucket for the build status
[06:21:16.056] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:16.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:108:0) after 1m0s
[06:22:16.266] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:108:0)
[06:22:16.266] Timer tick, asking Buildbucket for the build status
[06:22:16.692] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:16.692] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:109:0) after 1m0s
[06:23:16.718] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:109:0)
[06:23:16.718] Timer tick, asking Buildbucket for the build status
[06:23:16.962] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:16.962] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:110:0) after 1m0s
[06:24:17.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:110:0)
[06:24:17.045] Timer tick, asking Buildbucket for the build status
[06:24:17.284] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:17.284] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:111:0) after 1m0s
[06:25:17.400] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:111:0)
[06:25:17.400] Timer tick, asking Buildbucket for the build status
[06:25:17.552] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:17.552] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:112:0) after 1m0s
[06:26:17.577] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:112:0)
[06:26:17.577] Timer tick, asking Buildbucket for the build status
[06:26:17.781] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:17.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:113:0) after 1m0s
[06:27:17.941] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:113:0)
[06:27:17.941] Timer tick, asking Buildbucket for the build status
[06:27:18.237] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:18.237] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:114:0) after 1m0s
[06:28:18.271] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:114:0)
[06:28:18.271] Timer tick, asking Buildbucket for the build status
[06:28:18.707] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:18.707] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:115:0) after 1m0s
[06:29:18.875] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:115:0)
[06:29:18.875] Timer tick, asking Buildbucket for the build status
[06:29:19.555] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:19.555] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:116:0) after 1m0s
[06:30:19.690] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:116:0)
[06:30:19.690] Timer tick, asking Buildbucket for the build status
[06:30:19.921] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:19.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:117:0) after 1m0s
[06:31:19.948] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:117:0)
[06:31:19.948] Timer tick, asking Buildbucket for the build status
[06:31:20.211] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:20.211] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:118:0) after 1m0s
[06:32:20.251] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:118:0)
[06:32:20.251] Timer tick, asking Buildbucket for the build status
[06:32:20.436] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:20.436] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:119:0) after 1m0s
[06:33:20.570] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:119:0)
[06:33:20.570] Timer tick, asking Buildbucket for the build status
[06:33:20.856] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:20.856] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:120:0) after 1m0s
[06:34:20.956] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:120:0)
[06:34:20.956] Timer tick, asking Buildbucket for the build status
[06:34:21.113] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:21.113] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:121:0) after 1m0s
[06:35:21.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:121:0)
[06:35:21.154] Timer tick, asking Buildbucket for the build status
[06:35:21.307] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:21.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:122:0) after 1m0s
[06:36:21.406] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:122:0)
[06:36:21.406] Timer tick, asking Buildbucket for the build status
[06:36:22.128] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:22.128] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:123:0) after 1m0s
[06:37:22.287] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:123:0)
[06:37:22.287] Timer tick, asking Buildbucket for the build status
[06:37:22.700] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:22.700] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:124:0) after 1m0s
[06:38:22.711] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:124:0)
[06:38:22.711] Timer tick, asking Buildbucket for the build status
[06:38:22.895] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:22.895] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:125:0) after 1m0s
[06:39:23.143] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:125:0)
[06:39:23.143] Timer tick, asking Buildbucket for the build status
[06:39:23.352] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:23.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:126:0) after 1m0s
[06:40:23.385] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:126:0)
[06:40:23.385] Timer tick, asking Buildbucket for the build status
[06:40:24.303] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:24.303] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:127:0) after 1m0s
[06:41:24.379] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:127:0)
[06:41:24.379] Timer tick, asking Buildbucket for the build status
[06:41:25.216] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:25.216] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:128:0) after 1m0s
[06:42:25.246] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:128:0)
[06:42:25.246] Timer tick, asking Buildbucket for the build status
[06:42:25.872] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:25.872] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:129:0) after 1m0s
[06:43:25.983] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:129:0)
[06:43:25.983] Timer tick, asking Buildbucket for the build status
[06:43:26.184] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:26.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:130:0) after 1m0s
[06:44:26.230] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:130:0)
[06:44:26.230] Timer tick, asking Buildbucket for the build status
[06:44:26.381] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:26.381] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:131:0) after 1m0s
[06:45:26.413] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:131:0)
[06:45:26.413] Timer tick, asking Buildbucket for the build status
[06:45:26.609] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:26.609] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:132:0) after 1m0s
[06:46:26.640] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:132:0)
[06:46:26.640] Timer tick, asking Buildbucket for the build status
[06:46:27.789] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:27.789] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:133:0) after 1m0s
[06:47:27.941] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:133:0)
[06:47:27.941] Timer tick, asking Buildbucket for the build status
[06:47:28.302] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:28.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:134:0) after 1m0s
[06:48:32.737] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:134:0)
[06:48:32.737] Timer tick, asking Buildbucket for the build status
[06:48:32.896] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:32.896] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:135:0) after 1m0s
[06:49:33.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:135:0)
[06:49:33.277] Timer tick, asking Buildbucket for the build status
[06:49:33.685] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:33.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:136:0) after 1m0s
[06:50:34.050] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:136:0)
[06:50:34.050] Timer tick, asking Buildbucket for the build status
[06:50:34.829] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:34.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:137:0) after 1m0s
[06:51:35.135] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:137:0)
[06:51:35.135] Timer tick, asking Buildbucket for the build status
[06:51:35.287] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:35.287] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:138:0) after 1m0s
[06:52:35.609] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:138:0)
[06:52:35.609] Timer tick, asking Buildbucket for the build status
[06:52:36.346] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:36.346] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:139:0) after 1m0s
[06:53:36.643] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:139:0)
[06:53:36.643] Timer tick, asking Buildbucket for the build status
[06:53:36.778] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:36.778] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:140:0) after 1m0s
[06:54:37.145] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:140:0)
[06:54:37.145] Timer tick, asking Buildbucket for the build status
[06:54:37.389] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:37.389] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:141:0) after 1m0s
[06:55:37.541] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:141:0)
[06:55:37.541] Timer tick, asking Buildbucket for the build status
[06:55:37.682] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:37.682] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:142:0) after 1m0s
[06:56:37.897] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:142:0)
[06:56:37.897] Timer tick, asking Buildbucket for the build status
[06:56:40.661] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:40.661] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:143:0) after 1m0s
[06:57:40.702] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:143:0)
[06:57:40.702] Timer tick, asking Buildbucket for the build status
[06:57:40.958] Build 8895937283741937552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:40.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061367346065803600:144:0) after 1m0s
[06:58:39.735] Received PubSub notification, asking Buildbucket for the build status
[06:58:40.649] Build 8895937283741937552: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:58:40.649] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1574578718862522",
"created_by": "project:chromium",
"created_ts": "1574570114342227",
"id": "8895937283741937552",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"20ff88b225b1d19f93d570e531b523bf1915235e\"}}",
"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-am2f\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 15283, \"got_angle_revision\": \"e9b68f332a30f60ba722c511b7335a38da6fa009\", \"got_dawn_revision\": \"8e9c3f663ccd270b8f047afe668e7cbc15e11bba\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"20ff88b225b1d19f93d570e531b523bf1915235e\", \"got_revision_cp\": \"refs/heads/master@{#718467}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"4584a9c2ccb9f2a7bc7cd3c55f5b95507901e035\", \"got_v8_revision\": \"30319dfe043b83a37b6ba1c601a838d6544d4ec6\", \"got_v8_revision_cp\": \"refs/heads/8.0.353@{#1}\", \"got_webrtc_revision\": \"e43b53126600d247fbfde8b5c29b22ee22e59c86\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29873}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"20ff88b225b1d19f93d570e531b523bf1915235e\"}, \"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-am2f\"], \"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\": [\"4651-c74e250\"], \"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": "1574570118035264",
"status": "COMPLETED",
"status_changed_ts": "1574578719266700",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/15283",
"builder:Windows deterministic",
"buildset:commit/git/20ff88b225b1d19f93d570e531b523bf1915235e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/20ff88b225b1d19f93d570e531b523bf1915235e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061367346065803600",
"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/8895937283741937552/+/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:48b482c87b136310",
"user_agent:luci-scheduler"
],
"updated_ts": "1574578719273605",
"url": "https://ci.chromium.org/b/8895937283741937552",
"utcnow_ts": "1574578720641849"
}
}
[06:58:40.650] Invocation finished in 2h23m27.908032774s with status SUCCEEDED