[02:40:40.774] New invocation is queued and will start shortly
[02:40:41.888] Starting the invocation (attempt 1)
[02:40:41.936] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:40:41.936] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:40:41.936] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9084657972230393360",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"9335367f98053cb119698f23e2dfed7b5bec8aa9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9084657972230393360",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/9335367f98053cb119698f23e2dfed7b5bec8aa9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9335367f98053cb119698f23e2dfed7b5bec8aa9",
"gitiles_ref:refs/heads/master"
]
}
[02:40:43.044] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552358442076480",
"id": "8919227910200283968",
"lease_expiration_ts": "1552477242755534",
"lease_key": "818762569",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"9335367f98053cb119698f23e2dfed7b5bec8aa9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552358442918704",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12392",
"builder:Windows deterministic",
"buildset:commit/git/9335367f98053cb119698f23e2dfed7b5bec8aa9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9335367f98053cb119698f23e2dfed7b5bec8aa9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084657972230393360",
"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/8919227910200283968/+/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:4388978e12b23510",
"user_agent:luci-scheduler"
],
"updated_ts": "1552358442918784",
"url": "https://ci.chromium.org/b/8919227910200283968",
"utcnow_ts": "1552358443035769"
}
}
[02:40:43.044] Task URL: https://ci.chromium.org/b/8919227910200283968
[02:40:43.044] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:2:0) after 1m0s
[02:40:44.199] Received PubSub notification, asking Buildbucket for the build status
[02:40:44.302] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:43.064] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:2:0)
[02:41:43.064] Timer tick, asking Buildbucket for the build status
[02:41:43.115] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:43.115] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:4:0) after 1m0s
[02:42:43.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:4:0)
[02:42:43.087] Timer tick, asking Buildbucket for the build status
[02:42:43.267] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:43.267] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:5:0) after 1m0s
[02:43:43.317] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:5:0)
[02:43:43.317] Timer tick, asking Buildbucket for the build status
[02:43:43.387] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:43.387] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:6:0) after 1m0s
[02:44:43.383] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:6:0)
[02:44:43.383] Timer tick, asking Buildbucket for the build status
[02:44:43.419] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:43.419] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:7:0) after 1m0s
[02:45:43.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:7:0)
[02:45:43.397] Timer tick, asking Buildbucket for the build status
[02:45:43.461] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:43.461] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:8:0) after 1m0s
[02:46:43.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:8:0)
[02:46:43.470] Timer tick, asking Buildbucket for the build status
[02:46:43.518] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:43.518] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:9:0) after 1m0s
[02:47:43.527] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:9:0)
[02:47:43.527] Timer tick, asking Buildbucket for the build status
[02:47:43.686] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:43.686] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:10:0) after 1m0s
[02:48:43.665] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:10:0)
[02:48:43.665] Timer tick, asking Buildbucket for the build status
[02:48:43.762] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:43.762] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:11:0) after 1m0s
[02:49:43.783] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:11:0)
[02:49:43.783] Timer tick, asking Buildbucket for the build status
[02:49:43.949] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:43.949] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:12:0) after 1m0s
[02:50:43.936] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:12:0)
[02:50:43.936] Timer tick, asking Buildbucket for the build status
[02:50:44.012] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:44.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:13:0) after 1m0s
[02:51:43.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:13:0)
[02:51:43.989] Timer tick, asking Buildbucket for the build status
[02:51:44.066] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:44.066] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:14:0) after 1m0s
[02:52:44.058] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:14:0)
[02:52:44.058] Timer tick, asking Buildbucket for the build status
[02:52:44.115] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:44.115] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:15:0) after 1m0s
[02:53:44.121] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:15:0)
[02:53:44.121] Timer tick, asking Buildbucket for the build status
[02:53:44.402] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:44.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:16:0) after 1m0s
[02:54:44.409] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:16:0)
[02:54:44.409] Timer tick, asking Buildbucket for the build status
[02:54:44.690] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:44.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:17:0) after 1m0s
[02:55:44.718] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:17:0)
[02:55:44.718] Timer tick, asking Buildbucket for the build status
[02:55:44.792] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:44.792] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:18:0) after 1m0s
[02:56:44.798] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:18:0)
[02:56:44.798] Timer tick, asking Buildbucket for the build status
[02:56:44.904] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:44.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:19:0) after 1m0s
[02:57:44.925] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:19:0)
[02:57:44.925] Timer tick, asking Buildbucket for the build status
[02:57:44.984] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:44.984] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:20:0) after 1m0s
[02:58:44.992] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:20:0)
[02:58:44.992] Timer tick, asking Buildbucket for the build status
[02:58:45.162] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:45.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:21:0) after 1m0s
[02:59:45.176] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:21:0)
[02:59:45.176] Timer tick, asking Buildbucket for the build status
[02:59:45.360] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:45.360] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:22:0) after 1m0s
[03:00:45.378] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:22:0)
[03:00:45.378] Timer tick, asking Buildbucket for the build status
[03:00:45.418] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:45.418] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:23:0) after 1m0s
[03:01:45.412] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:23:0)
[03:01:45.412] Timer tick, asking Buildbucket for the build status
[03:01:45.786] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:45.786] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:24:0) after 1m0s
[03:02:45.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:24:0)
[03:02:45.792] Timer tick, asking Buildbucket for the build status
[03:02:45.850] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:45.850] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:25:0) after 1m0s
[03:03:45.872] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:25:0)
[03:03:45.872] Timer tick, asking Buildbucket for the build status
[03:03:45.959] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:45.959] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:26:0) after 1m0s
[03:04:45.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:26:0)
[03:04:45.982] Timer tick, asking Buildbucket for the build status
[03:04:46.187] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:46.187] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:27:0) after 1m0s
[03:05:46.224] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:27:0)
[03:05:46.224] Timer tick, asking Buildbucket for the build status
[03:05:46.266] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:46.266] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:28:0) after 1m0s
[03:06:46.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:28:0)
[03:06:46.273] Timer tick, asking Buildbucket for the build status
[03:06:46.392] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:46.392] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:29:0) after 1m0s
[03:07:46.374] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:29:0)
[03:07:46.374] Timer tick, asking Buildbucket for the build status
[03:07:46.442] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:46.442] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:30:0) after 1m0s
[03:08:46.456] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:30:0)
[03:08:46.456] Timer tick, asking Buildbucket for the build status
[03:08:46.581] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:46.581] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:31:0) after 1m0s
[03:09:46.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:31:0)
[03:09:46.602] Timer tick, asking Buildbucket for the build status
[03:09:46.695] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:46.695] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:32:0) after 1m0s
[03:10:46.682] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:32:0)
[03:10:46.682] Timer tick, asking Buildbucket for the build status
[03:10:46.744] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:46.744] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:33:0) after 1m0s
[03:11:46.833] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:33:0)
[03:11:46.833] Timer tick, asking Buildbucket for the build status
[03:11:46.962] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:46.962] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:34:0) after 1m0s
[03:12:46.985] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:34:0)
[03:12:46.985] Timer tick, asking Buildbucket for the build status
[03:12:47.024] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:47.024] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:35:0) after 1m0s
[03:13:47.099] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:35:0)
[03:13:47.099] Timer tick, asking Buildbucket for the build status
[03:13:47.193] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:47.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:36:0) after 1m0s
[03:14:47.219] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:36:0)
[03:14:47.219] Timer tick, asking Buildbucket for the build status
[03:14:48.229] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:48.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:37:0) after 1m0s
[03:15:48.251] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:37:0)
[03:15:48.251] Timer tick, asking Buildbucket for the build status
[03:15:48.413] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:48.413] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:38:0) after 1m0s
[03:16:48.431] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:38:0)
[03:16:48.432] Timer tick, asking Buildbucket for the build status
[03:16:48.544] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:48.544] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:39:0) after 1m0s
[03:17:48.558] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:39:0)
[03:17:48.558] Timer tick, asking Buildbucket for the build status
[03:17:48.642] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:48.642] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:40:0) after 1m0s
[03:18:48.663] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:40:0)
[03:18:48.663] Timer tick, asking Buildbucket for the build status
[03:18:48.697] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:48.697] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:41:0) after 1m0s
[03:19:48.693] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:41:0)
[03:19:48.693] Timer tick, asking Buildbucket for the build status
[03:19:48.762] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:48.762] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:42:0) after 1m0s
[03:20:48.781] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:42:0)
[03:20:48.781] Timer tick, asking Buildbucket for the build status
[03:20:49.215] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:49.215] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:43:0) after 1m0s
[03:21:49.248] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:43:0)
[03:21:49.248] Timer tick, asking Buildbucket for the build status
[03:21:49.389] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:49.389] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:44:0) after 1m0s
[03:22:49.378] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:44:0)
[03:22:49.378] Timer tick, asking Buildbucket for the build status
[03:22:49.492] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:49.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:45:0) after 1m0s
[03:23:49.524] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:45:0)
[03:23:49.524] Timer tick, asking Buildbucket for the build status
[03:23:49.589] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:49.589] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:46:0) after 1m0s
[03:24:49.601] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:46:0)
[03:24:49.601] Timer tick, asking Buildbucket for the build status
[03:24:49.763] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:49.763] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:47:0) after 1m0s
[03:25:49.783] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:47:0)
[03:25:49.783] Timer tick, asking Buildbucket for the build status
[03:25:49.822] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:49.822] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:48:0) after 1m0s
[03:26:49.850] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:48:0)
[03:26:49.850] Timer tick, asking Buildbucket for the build status
[03:26:49.887] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:49.887] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:49:0) after 1m0s
[03:27:49.879] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:49:0)
[03:27:49.879] Timer tick, asking Buildbucket for the build status
[03:27:49.972] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:49.972] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:50:0) after 1m0s
[03:28:49.993] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:50:0)
[03:28:49.993] Timer tick, asking Buildbucket for the build status
[03:28:50.048] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:50.048] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:51:0) after 1m0s
[03:29:50.052] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:51:0)
[03:29:50.052] Timer tick, asking Buildbucket for the build status
[03:29:50.099] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:50.099] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:52:0) after 1m0s
[03:30:50.114] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:52:0)
[03:30:50.114] Timer tick, asking Buildbucket for the build status
[03:30:50.146] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:50.146] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:53:0) after 1m0s
[03:31:50.171] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:53:0)
[03:31:50.171] Timer tick, asking Buildbucket for the build status
[03:31:50.203] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:50.203] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:54:0) after 1m0s
[03:32:50.214] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:54:0)
[03:32:50.214] Timer tick, asking Buildbucket for the build status
[03:32:50.269] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:50.269] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:55:0) after 1m0s
[03:33:50.292] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:55:0)
[03:33:50.292] Timer tick, asking Buildbucket for the build status
[03:33:50.331] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:50.331] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:56:0) after 1m0s
[03:34:50.311] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:56:0)
[03:34:50.311] Timer tick, asking Buildbucket for the build status
[03:34:50.490] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:50.490] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:57:0) after 1m0s
[03:35:50.466] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:57:0)
[03:35:50.466] Timer tick, asking Buildbucket for the build status
[03:35:50.518] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:50.518] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:58:0) after 1m0s
[03:36:50.538] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:58:0)
[03:36:50.538] Timer tick, asking Buildbucket for the build status
[03:36:50.591] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:50.591] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:59:0) after 1m0s
[03:37:50.601] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:59:0)
[03:37:50.601] Timer tick, asking Buildbucket for the build status
[03:37:50.645] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:50.645] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:60:0) after 1m0s
[03:38:50.666] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:60:0)
[03:38:50.666] Timer tick, asking Buildbucket for the build status
[03:38:50.780] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:50.780] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:61:0) after 1m0s
[03:39:50.812] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:61:0)
[03:39:50.812] Timer tick, asking Buildbucket for the build status
[03:39:50.995] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:50.995] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:62:0) after 1m0s
[03:40:51.018] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:62:0)
[03:40:51.018] Timer tick, asking Buildbucket for the build status
[03:40:51.089] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:51.089] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:63:0) after 1m0s
[03:41:51.111] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:63:0)
[03:41:51.111] Timer tick, asking Buildbucket for the build status
[03:41:51.281] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:51.281] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:64:0) after 1m0s
[03:42:51.289] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:64:0)
[03:42:51.289] Timer tick, asking Buildbucket for the build status
[03:42:51.461] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:51.461] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:65:0) after 1m0s
[03:43:51.484] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:65:0)
[03:43:51.484] Timer tick, asking Buildbucket for the build status
[03:43:51.530] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:51.530] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:66:0) after 1m0s
[03:44:51.546] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:66:0)
[03:44:51.547] Timer tick, asking Buildbucket for the build status
[03:44:53.100] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:53.100] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:67:0) after 1m0s
[03:45:53.108] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:67:0)
[03:45:53.108] Timer tick, asking Buildbucket for the build status
[03:45:53.146] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:53.146] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:68:0) after 1m0s
[03:46:53.171] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:68:0)
[03:46:53.171] Timer tick, asking Buildbucket for the build status
[03:46:53.213] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:53.213] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:69:0) after 1m0s
[03:47:53.219] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:69:0)
[03:47:53.219] Timer tick, asking Buildbucket for the build status
[03:47:53.254] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:53.254] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:70:0) after 1m0s
[03:48:53.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:70:0)
[03:48:53.277] Timer tick, asking Buildbucket for the build status
[03:48:53.389] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:53.389] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:71:0) after 1m0s
[03:49:53.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:71:0)
[03:49:53.377] Timer tick, asking Buildbucket for the build status
[03:49:53.458] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:53.458] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:72:0) after 1m0s
[03:50:53.438] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:72:0)
[03:50:53.438] Timer tick, asking Buildbucket for the build status
[03:50:53.539] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:53.539] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:73:0) after 1m0s
[03:51:53.535] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:73:0)
[03:51:53.535] Timer tick, asking Buildbucket for the build status
[03:51:53.612] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:53.612] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:74:0) after 1m0s
[03:52:53.610] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:74:0)
[03:52:53.610] Timer tick, asking Buildbucket for the build status
[03:52:53.701] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:53.701] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:75:0) after 1m0s
[03:53:53.722] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:75:0)
[03:53:53.722] Timer tick, asking Buildbucket for the build status
[03:53:53.867] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:53.867] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:76:0) after 1m0s
[03:54:53.870] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:76:0)
[03:54:53.870] Timer tick, asking Buildbucket for the build status
[03:54:53.932] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:53.932] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:77:0) after 1m0s
[03:55:53.926] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:77:0)
[03:55:53.926] Timer tick, asking Buildbucket for the build status
[03:55:53.962] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:53.962] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:78:0) after 1m0s
[03:56:54.012] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:78:0)
[03:56:54.012] Timer tick, asking Buildbucket for the build status
[03:56:54.065] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:54.065] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:79:0) after 1m0s
[03:57:54.095] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:79:0)
[03:57:54.095] Timer tick, asking Buildbucket for the build status
[03:57:54.187] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:54.187] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:80:0) after 1m0s
[03:58:54.217] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:80:0)
[03:58:54.217] Timer tick, asking Buildbucket for the build status
[03:58:54.361] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:54.361] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:81:0) after 1m0s
[03:59:54.368] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:81:0)
[03:59:54.368] Timer tick, asking Buildbucket for the build status
[03:59:54.408] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:54.408] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:82:0) after 1m0s
[04:00:54.409] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:82:0)
[04:00:54.409] Timer tick, asking Buildbucket for the build status
[04:00:54.790] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:54.790] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:83:0) after 1m0s
[04:01:54.802] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:83:0)
[04:01:54.802] Timer tick, asking Buildbucket for the build status
[04:01:54.843] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:54.843] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:84:0) after 1m0s
[04:02:54.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:84:0)
[04:02:54.856] Timer tick, asking Buildbucket for the build status
[04:02:54.890] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:54.890] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:85:0) after 1m0s
[04:03:54.914] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:85:0)
[04:03:54.914] Timer tick, asking Buildbucket for the build status
[04:03:54.958] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:54.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:86:0) after 1m0s
[04:04:54.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:86:0)
[04:04:54.982] Timer tick, asking Buildbucket for the build status
[04:04:55.086] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:55.086] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:87:0) after 1m0s
[04:05:55.108] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:87:0)
[04:05:55.108] Timer tick, asking Buildbucket for the build status
[04:05:55.192] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:55.192] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:88:0) after 1m0s
[04:06:55.204] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:88:0)
[04:06:55.204] Timer tick, asking Buildbucket for the build status
[04:06:55.346] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:55.346] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:89:0) after 1m0s
[04:07:55.366] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:89:0)
[04:07:55.366] Timer tick, asking Buildbucket for the build status
[04:07:55.450] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:55.450] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:90:0) after 1m0s
[04:08:55.444] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:90:0)
[04:08:55.444] Timer tick, asking Buildbucket for the build status
[04:08:55.503] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:55.503] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:91:0) after 1m0s
[04:09:55.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:91:0)
[04:09:55.495] Timer tick, asking Buildbucket for the build status
[04:09:55.537] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:55.537] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:92:0) after 1m0s
[04:10:55.573] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:92:0)
[04:10:55.573] Timer tick, asking Buildbucket for the build status
[04:10:55.632] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:55.632] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:93:0) after 1m0s
[04:11:55.641] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:93:0)
[04:11:55.641] Timer tick, asking Buildbucket for the build status
[04:11:55.793] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:55.793] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:94:0) after 1m0s
[04:12:55.764] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:94:0)
[04:12:55.764] Timer tick, asking Buildbucket for the build status
[04:12:55.813] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:55.813] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:95:0) after 1m0s
[04:13:55.850] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:95:0)
[04:13:55.850] Timer tick, asking Buildbucket for the build status
[04:13:55.977] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:55.977] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:96:0) after 1m0s
[04:14:55.999] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:96:0)
[04:14:55.999] Timer tick, asking Buildbucket for the build status
[04:14:56.100] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:56.100] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:97:0) after 1m0s
[04:15:56.096] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:97:0)
[04:15:56.096] Timer tick, asking Buildbucket for the build status
[04:15:56.177] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:56.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:98:0) after 1m0s
[04:16:56.213] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:98:0)
[04:16:56.213] Timer tick, asking Buildbucket for the build status
[04:16:56.373] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:56.373] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:99:0) after 1m0s
[04:17:56.388] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:99:0)
[04:17:56.388] Timer tick, asking Buildbucket for the build status
[04:17:56.470] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:56.471] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:100:0) after 1m0s
[04:18:56.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:100:0)
[04:18:56.470] Timer tick, asking Buildbucket for the build status
[04:18:56.552] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:56.552] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:101:0) after 1m0s
[04:19:56.575] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:101:0)
[04:19:56.575] Timer tick, asking Buildbucket for the build status
[04:19:56.685] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:56.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:102:0) after 1m0s
[04:20:56.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:102:0)
[04:20:56.708] Timer tick, asking Buildbucket for the build status
[04:20:56.789] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:56.789] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:103:0) after 1m0s
[04:21:56.794] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:103:0)
[04:21:56.794] Timer tick, asking Buildbucket for the build status
[04:21:56.851] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:56.851] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:104:0) after 1m0s
[04:22:56.852] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:104:0)
[04:22:56.853] Timer tick, asking Buildbucket for the build status
[04:22:56.899] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:56.899] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:105:0) after 1m0s
[04:23:56.887] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:105:0)
[04:23:56.887] Timer tick, asking Buildbucket for the build status
[04:23:56.923] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:56.923] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:106:0) after 1m0s
[04:24:56.898] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:106:0)
[04:24:56.898] Timer tick, asking Buildbucket for the build status
[04:24:56.991] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:56.991] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:107:0) after 1m0s
[04:25:56.977] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:107:0)
[04:25:56.977] Timer tick, asking Buildbucket for the build status
[04:25:57.018] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:57.018] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:108:0) after 1m0s
[04:26:57.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:108:0)
[04:26:57.045] Timer tick, asking Buildbucket for the build status
[04:26:57.091] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:57.091] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:109:0) after 1m0s
[04:27:57.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:109:0)
[04:27:57.117] Timer tick, asking Buildbucket for the build status
[04:27:57.158] Build 8919227910200283968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:57.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084657972230393360:110:0) after 1m0s
[04:28:44.496] Received PubSub notification, asking Buildbucket for the build status
[04:28:44.584] Build 8919227910200283968: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:28:44.584] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1552364923204365",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552358442076480",
"id": "8919227910200283968",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"9335367f98053cb119698f23e2dfed7b5bec8aa9\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"win10-727f49a0-us-west1-b-kb2j\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4158-1053c58\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"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}, \"got_nacl_revision\": \"1ed9878b8eb68c1e0d0268754dafcf83e747fa8f\", \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"a5c0ba186149569832548bc3ea01494562fbcab7\", \"got_revision\": \"9335367f98053cb119698f23e2dfed7b5bec8aa9\", \"got_swarming_client_revision\": \"7a61cf37d6a0163f0ec02d495289a1d038e62457\", \"recipe\": \"swarming/deterministic_build\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"49c9dfe3b832a5dafefff299552ea68b83c9cd8a\", \"got_v8_revision\": \"844fc6c8246c3d623dda28af5056819ab79c87cd\", \"got_revision_cp\": \"refs/heads/master@{#639781}\", \"got_dawn_revision\": \"1e1c13ed87bae248e044fdd6fec9c5ab7efccf41\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27058}\", \"buildnumber\": 12392, \"path_config\": \"generic\", \"bot_id\": \"win10-727f49a0-us-west1-b-kb2j\", \"got_v8_revision_cp\": \"refs/heads/7.5.15@{#1}\", \"revision\": \"9335367f98053cb119698f23e2dfed7b5bec8aa9\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1552358443481631",
"status": "COMPLETED",
"status_changed_ts": "1552364924151053",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12392",
"builder:Windows deterministic",
"buildset:commit/git/9335367f98053cb119698f23e2dfed7b5bec8aa9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9335367f98053cb119698f23e2dfed7b5bec8aa9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084657972230393360",
"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/8919227910200283968/+/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:4388978e12b23510",
"user_agent:luci-scheduler"
],
"updated_ts": "1552364924160674",
"url": "https://ci.chromium.org/b/8919227910200283968",
"utcnow_ts": "1552364924572186"
}
}
[04:28:44.584] Invocation finished in 1h48m3.827973052s with status SUCCEEDED