[07:41:52.300] New invocation is queued and will start shortly
[07:41:53.618] Starting the invocation (attempt 1)
[07:41:53.663] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:41:53.663] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:41:53.663] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9087085140954155536",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"ece05de5985abbfd2b0d1ad5ecce0d774316336c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9087085140954155536",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/ece05de5985abbfd2b0d1ad5ecce0d774316336c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ece05de5985abbfd2b0d1ad5ecce0d774316336c",
"gitiles_ref:refs/heads/master"
]
}
[07:41:54.914] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550043713798009",
"id": "8921655078719009440",
"lease_expiration_ts": "1550162514625592",
"lease_key": "257220617",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"ece05de5985abbfd2b0d1ad5ecce0d774316336c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1550043714780782",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11951",
"builder:Windows deterministic",
"buildset:commit/git/ece05de5985abbfd2b0d1ad5ecce0d774316336c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ece05de5985abbfd2b0d1ad5ecce0d774316336c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087085140954155536",
"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/8921655078719009440/+/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:42fe9f9b46aec510",
"user_agent:luci-scheduler"
],
"updated_ts": "1550043714780883",
"url": "https://ci.chromium.org/b/8921655078719009440",
"utcnow_ts": "1550043714904894"
}
}
[07:41:54.914] Task URL: https://ci.chromium.org/b/8921655078719009440
[07:41:54.914] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:2:0) after 1m0s
[07:41:56.287] Received PubSub notification, asking Buildbucket for the build status
[07:41:56.376] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:54.931] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:2:0)
[07:42:54.931] Timer tick, asking Buildbucket for the build status
[07:42:54.992] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:54.992] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:4:0) after 1m0s
[07:43:55.009] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:4:0)
[07:43:55.009] Timer tick, asking Buildbucket for the build status
[07:43:55.118] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:55.118] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:5:0) after 1m0s
[07:44:55.141] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:5:0)
[07:44:55.141] Timer tick, asking Buildbucket for the build status
[07:44:55.187] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:55.187] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:6:0) after 1m0s
[07:45:55.203] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:6:0)
[07:45:55.203] Timer tick, asking Buildbucket for the build status
[07:45:55.262] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:55.262] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:7:0) after 1m0s
[07:46:55.281] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:7:0)
[07:46:55.281] Timer tick, asking Buildbucket for the build status
[07:46:55.325] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:55.325] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:8:0) after 1m0s
[07:47:55.349] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:8:0)
[07:47:55.349] Timer tick, asking Buildbucket for the build status
[07:47:55.411] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:55.411] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:9:0) after 1m0s
[07:48:55.430] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:9:0)
[07:48:55.430] Timer tick, asking Buildbucket for the build status
[07:48:55.482] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:55.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:10:0) after 1m0s
[07:49:55.502] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:10:0)
[07:49:55.502] Timer tick, asking Buildbucket for the build status
[07:49:55.568] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:55.568] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:11:0) after 1m0s
[07:50:55.587] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:11:0)
[07:50:55.587] Timer tick, asking Buildbucket for the build status
[07:50:55.638] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:55.638] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:12:0) after 1m0s
[07:51:55.664] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:12:0)
[07:51:55.664] Timer tick, asking Buildbucket for the build status
[07:51:55.753] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:55.753] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:13:0) after 1m0s
[07:52:55.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:13:0)
[07:52:55.773] Timer tick, asking Buildbucket for the build status
[07:52:55.875] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:55.875] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:14:0) after 1m0s
[07:53:55.894] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:14:0)
[07:53:55.894] Timer tick, asking Buildbucket for the build status
[07:53:56.045] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:56.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:15:0) after 1m0s
[07:54:56.064] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:15:0)
[07:54:56.064] Timer tick, asking Buildbucket for the build status
[07:54:56.228] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:56.228] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:16:0) after 1m0s
[07:55:56.247] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:16:0)
[07:55:56.247] Timer tick, asking Buildbucket for the build status
[07:55:56.390] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:56.390] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:17:0) after 1m0s
[07:56:56.418] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:17:0)
[07:56:56.418] Timer tick, asking Buildbucket for the build status
[07:56:56.491] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:56.491] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:18:0) after 1m0s
[07:57:56.510] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:18:0)
[07:57:56.510] Timer tick, asking Buildbucket for the build status
[07:57:56.583] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:56.583] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:19:0) after 1m0s
[07:58:56.601] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:19:0)
[07:58:56.601] Timer tick, asking Buildbucket for the build status
[07:58:56.718] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:56.718] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:20:0) after 1m0s
[07:59:56.741] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:20:0)
[07:59:56.741] Timer tick, asking Buildbucket for the build status
[07:59:56.892] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:56.892] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:21:0) after 1m0s
[08:00:56.993] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:21:0)
[08:00:56.993] Timer tick, asking Buildbucket for the build status
[08:00:57.135] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:57.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:22:0) after 1m0s
[08:01:57.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:22:0)
[08:01:57.155] Timer tick, asking Buildbucket for the build status
[08:01:57.255] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:57.255] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:23:0) after 1m0s
[08:02:57.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:23:0)
[08:02:57.277] Timer tick, asking Buildbucket for the build status
[08:02:57.339] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:57.339] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:24:0) after 1m0s
[08:03:57.356] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:24:0)
[08:03:57.356] Timer tick, asking Buildbucket for the build status
[08:03:57.411] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:57.411] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:25:0) after 1m0s
[08:04:57.433] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:25:0)
[08:04:57.433] Timer tick, asking Buildbucket for the build status
[08:04:57.779] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:57.779] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:26:0) after 1m0s
[08:05:57.797] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:26:0)
[08:05:57.797] Timer tick, asking Buildbucket for the build status
[08:05:57.847] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:57.847] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:27:0) after 1m0s
[08:06:57.865] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:27:0)
[08:06:57.865] Timer tick, asking Buildbucket for the build status
[08:06:58.012] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:58.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:28:0) after 1m0s
[08:07:58.067] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:28:0)
[08:07:58.067] Timer tick, asking Buildbucket for the build status
[08:07:58.137] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:58.137] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:29:0) after 1m0s
[08:08:58.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:29:0)
[08:08:58.183] Timer tick, asking Buildbucket for the build status
[08:08:58.352] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:58.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:30:0) after 1m0s
[08:09:58.428] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:30:0)
[08:09:58.428] Timer tick, asking Buildbucket for the build status
[08:09:58.482] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:58.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:31:0) after 1m0s
[08:10:58.518] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:31:0)
[08:10:58.518] Timer tick, asking Buildbucket for the build status
[08:10:58.670] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:58.670] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:32:0) after 1m0s
[08:11:58.710] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:32:0)
[08:11:58.710] Timer tick, asking Buildbucket for the build status
[08:11:58.794] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:58.794] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:33:0) after 1m0s
[08:12:58.813] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:33:0)
[08:12:58.813] Timer tick, asking Buildbucket for the build status
[08:12:59.169] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:59.169] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:34:0) after 1m0s
[08:13:59.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:34:0)
[08:13:59.197] Timer tick, asking Buildbucket for the build status
[08:13:59.452] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:59.452] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:35:0) after 1m0s
[08:14:59.475] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:35:0)
[08:14:59.475] Timer tick, asking Buildbucket for the build status
[08:14:59.545] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:59.545] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:36:0) after 1m0s
[08:15:59.571] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:36:0)
[08:15:59.571] Timer tick, asking Buildbucket for the build status
[08:15:59.679] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:59.679] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:37:0) after 1m0s
[08:16:59.715] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:37:0)
[08:16:59.715] Timer tick, asking Buildbucket for the build status
[08:16:59.975] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:59.975] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:38:0) after 1m0s
[08:18:00.084] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:38:0)
[08:18:00.084] Timer tick, asking Buildbucket for the build status
[08:18:00.147] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:00.147] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:39:0) after 1m0s
[08:19:00.217] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:39:0)
[08:19:00.217] Timer tick, asking Buildbucket for the build status
[08:19:00.284] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:00.284] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:40:0) after 1m0s
[08:20:00.324] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:40:0)
[08:20:00.324] Timer tick, asking Buildbucket for the build status
[08:20:00.456] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:00.456] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:41:0) after 1m0s
[08:21:00.479] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:41:0)
[08:21:00.479] Timer tick, asking Buildbucket for the build status
[08:21:00.631] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:00.631] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:42:0) after 1m0s
[08:22:00.692] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:42:0)
[08:22:00.692] Timer tick, asking Buildbucket for the build status
[08:22:00.801] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:00.802] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:43:0) after 1m0s
[08:23:00.820] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:43:0)
[08:23:00.820] Timer tick, asking Buildbucket for the build status
[08:23:01.032] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:01.032] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:44:0) after 1m0s
[08:24:01.065] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:44:0)
[08:24:01.065] Timer tick, asking Buildbucket for the build status
[08:24:01.120] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:01.120] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:45:0) after 1m0s
[08:25:01.143] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:45:0)
[08:25:01.143] Timer tick, asking Buildbucket for the build status
[08:25:01.214] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:01.214] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:46:0) after 1m0s
[08:26:01.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:46:0)
[08:26:01.232] Timer tick, asking Buildbucket for the build status
[08:26:01.564] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:01.564] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:47:0) after 1m0s
[08:27:01.601] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:47:0)
[08:27:01.601] Timer tick, asking Buildbucket for the build status
[08:27:01.860] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:01.860] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:48:0) after 1m0s
[08:28:01.880] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:48:0)
[08:28:01.880] Timer tick, asking Buildbucket for the build status
[08:28:02.105] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:02.105] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:49:0) after 1m0s
[08:29:02.168] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:49:0)
[08:29:02.168] Timer tick, asking Buildbucket for the build status
[08:29:02.252] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:02.252] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:50:0) after 1m0s
[08:30:02.296] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:50:0)
[08:30:02.296] Timer tick, asking Buildbucket for the build status
[08:30:02.481] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:02.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:51:0) after 1m0s
[08:31:02.507] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:51:0)
[08:31:02.507] Timer tick, asking Buildbucket for the build status
[08:31:02.674] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:02.674] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:52:0) after 1m0s
[08:32:02.695] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:52:0)
[08:32:02.695] Timer tick, asking Buildbucket for the build status
[08:32:02.747] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:02.747] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:53:0) after 1m0s
[08:33:02.768] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:53:0)
[08:33:02.769] Timer tick, asking Buildbucket for the build status
[08:33:02.930] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:02.931] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:54:0) after 1m0s
[08:34:02.954] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:54:0)
[08:34:02.954] Timer tick, asking Buildbucket for the build status
[08:34:03.016] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:03.016] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:55:0) after 1m0s
[08:35:03.043] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:55:0)
[08:35:03.043] Timer tick, asking Buildbucket for the build status
[08:35:03.109] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:03.109] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:56:0) after 1m0s
[08:36:03.145] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:56:0)
[08:36:03.145] Timer tick, asking Buildbucket for the build status
[08:36:03.207] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:03.207] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:57:0) after 1m0s
[08:37:03.247] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:57:0)
[08:37:03.247] Timer tick, asking Buildbucket for the build status
[08:37:03.973] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:03.973] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:58:0) after 1m0s
[08:38:04.034] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:58:0)
[08:38:04.034] Timer tick, asking Buildbucket for the build status
[08:38:05.489] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:05.489] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:59:0) after 1m0s
[08:39:05.525] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:59:0)
[08:39:05.525] Timer tick, asking Buildbucket for the build status
[08:39:05.573] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:05.573] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:60:0) after 1m0s
[08:40:05.594] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:60:0)
[08:40:05.594] Timer tick, asking Buildbucket for the build status
[08:40:05.813] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:05.813] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:61:0) after 1m0s
[08:41:05.840] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:61:0)
[08:41:05.840] Timer tick, asking Buildbucket for the build status
[08:41:05.915] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:05.915] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:62:0) after 1m0s
[08:42:05.943] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:62:0)
[08:42:05.943] Timer tick, asking Buildbucket for the build status
[08:42:06.190] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:06.190] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:63:0) after 1m0s
[08:43:06.214] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:63:0)
[08:43:06.214] Timer tick, asking Buildbucket for the build status
[08:43:06.460] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:06.460] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:64:0) after 1m0s
[08:44:06.483] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:64:0)
[08:44:06.483] Timer tick, asking Buildbucket for the build status
[08:44:06.531] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:06.531] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:65:0) after 1m0s
[08:45:06.610] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:65:0)
[08:45:06.610] Timer tick, asking Buildbucket for the build status
[08:45:06.772] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:06.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:66:0) after 1m0s
[08:46:06.850] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:66:0)
[08:46:06.850] Timer tick, asking Buildbucket for the build status
[08:46:06.906] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:06.906] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:67:0) after 1m0s
[08:47:06.925] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:67:0)
[08:47:06.925] Timer tick, asking Buildbucket for the build status
[08:47:07.072] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:07.072] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:68:0) after 1m0s
[08:48:07.099] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:68:0)
[08:48:07.100] Timer tick, asking Buildbucket for the build status
[08:48:07.233] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:07.233] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:69:0) after 1m0s
[08:49:07.255] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:69:0)
[08:49:07.255] Timer tick, asking Buildbucket for the build status
[08:49:07.633] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:07.633] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:70:0) after 1m0s
[08:50:07.672] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:70:0)
[08:50:07.672] Timer tick, asking Buildbucket for the build status
[08:50:07.778] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:07.778] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:71:0) after 1m0s
[08:51:07.854] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:71:0)
[08:51:07.854] Timer tick, asking Buildbucket for the build status
[08:51:07.964] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:07.964] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:72:0) after 1m0s
[08:52:07.991] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:72:0)
[08:52:07.991] Timer tick, asking Buildbucket for the build status
[08:52:08.071] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:08.071] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:73:0) after 1m0s
[08:53:08.101] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:73:0)
[08:53:08.101] Timer tick, asking Buildbucket for the build status
[08:53:08.496] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:08.496] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:74:0) after 1m0s
[08:54:08.518] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:74:0)
[08:54:08.518] Timer tick, asking Buildbucket for the build status
[08:54:08.568] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:08.568] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:75:0) after 1m0s
[08:55:08.612] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:75:0)
[08:55:08.612] Timer tick, asking Buildbucket for the build status
[08:55:08.680] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:08.680] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:76:0) after 1m0s
[08:56:08.709] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:76:0)
[08:56:08.709] Timer tick, asking Buildbucket for the build status
[08:56:08.914] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:08.914] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:77:0) after 1m0s
[08:57:08.937] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:77:0)
[08:57:08.937] Timer tick, asking Buildbucket for the build status
[08:57:08.982] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:08.982] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:78:0) after 1m0s
[08:58:09.037] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:78:0)
[08:58:09.037] Timer tick, asking Buildbucket for the build status
[08:58:09.140] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:09.140] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:79:0) after 1m0s
[08:59:09.176] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:79:0)
[08:59:09.176] Timer tick, asking Buildbucket for the build status
[08:59:09.234] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:09.234] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:80:0) after 1m0s
[09:00:09.252] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:80:0)
[09:00:09.252] Timer tick, asking Buildbucket for the build status
[09:00:09.322] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:09.322] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:81:0) after 1m0s
[09:01:09.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:81:0)
[09:01:09.345] Timer tick, asking Buildbucket for the build status
[09:01:09.504] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:09.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:82:0) after 1m0s
[09:02:09.524] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:82:0)
[09:02:09.524] Timer tick, asking Buildbucket for the build status
[09:02:09.766] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:09.766] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:83:0) after 1m0s
[09:03:09.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:83:0)
[09:03:09.792] Timer tick, asking Buildbucket for the build status
[09:03:10.066] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:10.066] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:84:0) after 1m0s
[09:04:10.088] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:84:0)
[09:04:10.088] Timer tick, asking Buildbucket for the build status
[09:04:10.192] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:10.192] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:85:0) after 1m0s
[09:05:10.219] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:85:0)
[09:05:10.219] Timer tick, asking Buildbucket for the build status
[09:05:10.274] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:10.274] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:86:0) after 1m0s
[09:06:10.299] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:86:0)
[09:06:10.299] Timer tick, asking Buildbucket for the build status
[09:06:10.366] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:10.366] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:87:0) after 1m0s
[09:07:10.424] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:87:0)
[09:07:10.424] Timer tick, asking Buildbucket for the build status
[09:07:10.466] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:10.466] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:88:0) after 1m0s
[09:08:10.490] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:88:0)
[09:08:10.490] Timer tick, asking Buildbucket for the build status
[09:08:10.565] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:10.565] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:89:0) after 1m0s
[09:09:10.587] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:89:0)
[09:09:10.587] Timer tick, asking Buildbucket for the build status
[09:09:10.676] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:10.676] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:90:0) after 1m0s
[09:10:10.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:90:0)
[09:10:10.708] Timer tick, asking Buildbucket for the build status
[09:10:10.807] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:10.807] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:91:0) after 1m0s
[09:11:10.830] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:91:0)
[09:11:10.830] Timer tick, asking Buildbucket for the build status
[09:11:10.894] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:10.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:92:0) after 1m0s
[09:12:10.917] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:92:0)
[09:12:10.917] Timer tick, asking Buildbucket for the build status
[09:12:10.978] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:10.978] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:93:0) after 1m0s
[09:13:11.001] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:93:0)
[09:13:11.001] Timer tick, asking Buildbucket for the build status
[09:13:11.237] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:11.238] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:94:0) after 1m0s
[09:14:11.259] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:94:0)
[09:14:11.259] Timer tick, asking Buildbucket for the build status
[09:14:11.334] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:11.334] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:95:0) after 1m0s
[09:15:11.359] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:95:0)
[09:15:11.359] Timer tick, asking Buildbucket for the build status
[09:15:11.419] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:11.419] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:96:0) after 1m0s
[09:16:11.443] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:96:0)
[09:16:11.443] Timer tick, asking Buildbucket for the build status
[09:16:11.525] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:11.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:97:0) after 1m0s
[09:17:11.550] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:97:0)
[09:17:11.550] Timer tick, asking Buildbucket for the build status
[09:17:11.608] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:11.608] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:98:0) after 1m0s
[09:18:11.701] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:98:0)
[09:18:11.701] Timer tick, asking Buildbucket for the build status
[09:18:11.974] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:11.974] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:99:0) after 1m0s
[09:19:12.006] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:99:0)
[09:19:12.006] Timer tick, asking Buildbucket for the build status
[09:19:12.093] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:12.093] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:100:0) after 1m0s
[09:20:12.125] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:100:0)
[09:20:12.125] Timer tick, asking Buildbucket for the build status
[09:20:12.194] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:12.194] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:101:0) after 1m0s
[09:21:12.217] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:101:0)
[09:21:12.217] Timer tick, asking Buildbucket for the build status
[09:21:12.565] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:12.565] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:102:0) after 1m0s
[09:22:12.610] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:102:0)
[09:22:12.610] Timer tick, asking Buildbucket for the build status
[09:22:12.702] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:12.702] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:103:0) after 1m0s
[09:23:12.724] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:103:0)
[09:23:12.724] Timer tick, asking Buildbucket for the build status
[09:23:12.880] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:12.880] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:104:0) after 1m0s
[09:24:12.902] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:104:0)
[09:24:12.902] Timer tick, asking Buildbucket for the build status
[09:24:12.943] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:12.943] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:105:0) after 1m0s
[09:25:12.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:105:0)
[09:25:12.982] Timer tick, asking Buildbucket for the build status
[09:25:13.037] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:13.038] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:106:0) after 1m0s
[09:26:13.058] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:106:0)
[09:26:13.058] Timer tick, asking Buildbucket for the build status
[09:26:13.125] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:13.125] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:107:0) after 1m0s
[09:27:13.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:107:0)
[09:27:13.154] Timer tick, asking Buildbucket for the build status
[09:27:13.208] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:13.208] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:108:0) after 1m0s
[09:28:13.229] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:108:0)
[09:28:13.229] Timer tick, asking Buildbucket for the build status
[09:28:13.451] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:13.451] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:109:0) after 1m0s
[09:29:13.486] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:109:0)
[09:29:13.486] Timer tick, asking Buildbucket for the build status
[09:29:13.538] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:13.538] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:110:0) after 1m0s
[09:30:13.561] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:110:0)
[09:30:13.561] Timer tick, asking Buildbucket for the build status
[09:30:13.682] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:13.682] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:111:0) after 1m0s
[09:31:13.703] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:111:0)
[09:31:13.703] Timer tick, asking Buildbucket for the build status
[09:31:13.799] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:13.799] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:112:0) after 1m0s
[09:32:13.882] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:112:0)
[09:32:13.882] Timer tick, asking Buildbucket for the build status
[09:32:14.084] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:14.084] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:113:0) after 1m0s
[09:33:14.107] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:113:0)
[09:33:14.107] Timer tick, asking Buildbucket for the build status
[09:33:14.162] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:14.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:114:0) after 1m0s
[09:34:14.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:114:0)
[09:34:14.197] Timer tick, asking Buildbucket for the build status
[09:34:14.299] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:14.299] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:115:0) after 1m0s
[09:35:14.322] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:115:0)
[09:35:14.322] Timer tick, asking Buildbucket for the build status
[09:35:14.372] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:14.372] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:116:0) after 1m0s
[09:36:14.393] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:116:0)
[09:36:14.393] Timer tick, asking Buildbucket for the build status
[09:36:14.460] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:14.460] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:117:0) after 1m0s
[09:37:14.500] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:117:0)
[09:37:14.500] Timer tick, asking Buildbucket for the build status
[09:37:14.557] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:14.557] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:118:0) after 1m0s
[09:38:14.580] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:118:0)
[09:38:14.580] Timer tick, asking Buildbucket for the build status
[09:38:14.759] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:14.759] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:119:0) after 1m0s
[09:39:14.788] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:119:0)
[09:39:14.788] Timer tick, asking Buildbucket for the build status
[09:39:14.843] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:14.843] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:120:0) after 1m0s
[09:40:14.874] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:120:0)
[09:40:14.874] Timer tick, asking Buildbucket for the build status
[09:40:15.086] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:15.086] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:121:0) after 1m0s
[09:41:15.110] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:121:0)
[09:41:15.110] Timer tick, asking Buildbucket for the build status
[09:41:15.212] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:15.212] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:122:0) after 1m0s
[09:42:15.233] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:122:0)
[09:42:15.233] Timer tick, asking Buildbucket for the build status
[09:42:15.279] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:15.279] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:123:0) after 1m0s
[09:43:15.319] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:123:0)
[09:43:15.319] Timer tick, asking Buildbucket for the build status
[09:43:15.363] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:15.363] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:124:0) after 1m0s
[09:44:15.483] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:124:0)
[09:44:15.483] Timer tick, asking Buildbucket for the build status
[09:44:15.535] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:15.535] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:125:0) after 1m0s
[09:45:15.560] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:125:0)
[09:45:15.560] Timer tick, asking Buildbucket for the build status
[09:45:15.680] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:15.680] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:126:0) after 1m0s
[09:46:15.718] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:126:0)
[09:46:15.718] Timer tick, asking Buildbucket for the build status
[09:46:15.796] Build 8921655078719009440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:15.796] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087085140954155536:127:0) after 1m0s
[09:46:37.922] Received PubSub notification, asking Buildbucket for the build status
[09:46:37.998] Build 8921655078719009440: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:46:37.998] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1550051196839481",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550043713798009",
"id": "8921655078719009440",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"ece05de5985abbfd2b0d1ad5ecce0d774316336c\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"swarm2173-c4\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4085-c81638b\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"got_nacl_revision\": \"ff15c51d20785fa51c0ef03ccb480b87299acbeb\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"ece05de5985abbfd2b0d1ad5ecce0d774316336c\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"bb5696bcd3b7a9ba5cd6a74cd1e9665bc74deb2c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26626}\", \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"got_revision_cp\": \"refs/heads/master@{#631595}\", \"branch\": \"refs/heads/master\", \"revision\": \"ece05de5985abbfd2b0d1ad5ecce0d774316336c\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"f13c2cd9ee41f4ca572232a4e397b05449474632\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"983e446921946734fe47217c345a8fe2f079319d\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1550043713798009, \"tags\": [\"build_address:luci.chromium.ci/Windows deterministic/11951\", \"builder:Windows deterministic\", \"buildset:commit/git/ece05de5985abbfd2b0d1ad5ecce0d774316336c\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ece05de5985abbfd2b0d1ad5ecce0d774316336c\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9087085140954155536\", \"scheduler_job_id:chromium/Windows deterministic\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8921655078719009440\"}}, \"got_v8_revision\": \"91594a65bbcc29d1691d68ec8273dd01f3bb7341\", \"got_v8_revision_cp\": \"refs/heads/7.4.152@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 11951, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Windows deterministic\", \"key\": \"builder\"}, {\"value\": \"commit/git/ece05de5985abbfd2b0d1ad5ecce0d774316336c\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/ece05de5985abbfd2b0d1ad5ecce0d774316336c\", \"key\": \"buildset\"}, {\"value\": \"9087085140954155536\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"chromium/Windows deterministic\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"chromium\", \"builder\": \"Windows deterministic\", \"bucket\": \"ci\"}, \"number\": 11951, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"2acf9ddf085124300131f0a719a64564504c97a6\"}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8921655078719009440\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"ece05de5985abbfd2b0d1ad5ecce0d774316336c\"}}, \"createTime\": \"2019-02-13T07:41:53.798009Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1550043715601305",
"status": "COMPLETED",
"status_changed_ts": "1550051197585196",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11951",
"builder:Windows deterministic",
"buildset:commit/git/ece05de5985abbfd2b0d1ad5ecce0d774316336c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ece05de5985abbfd2b0d1ad5ecce0d774316336c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087085140954155536",
"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/8921655078719009440/+/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:42fe9f9b46aec510",
"user_agent:luci-scheduler"
],
"updated_ts": "1550051197586550",
"url": "https://ci.chromium.org/b/8921655078719009440",
"utcnow_ts": "1550051197986417"
}
}
[09:46:37.998] Invocation finished in 2h4m45.714384518s with status SUCCEEDED