[01:27:04.086] New invocation is queued and will start shortly
[01:27:05.285] Starting the invocation (attempt 1)
[01:27:05.334] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:27:05.334] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:27:05.334] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9085024991330167648",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"0400f3abcb28bdce97ae34213c96f943918acdea\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9085024991330167648",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/0400f3abcb28bdce97ae34213c96f943918acdea",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0400f3abcb28bdce97ae34213c96f943918acdea",
"gitiles_ref:refs/heads/master"
]
}
[01:27:06.453] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552008425429445",
"id": "8919594929255514880",
"lease_expiration_ts": "1552127226043130",
"lease_key": "1814258327",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0400f3abcb28bdce97ae34213c96f943918acdea\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552008426191755",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12341",
"builder:Windows deterministic",
"buildset:commit/git/0400f3abcb28bdce97ae34213c96f943918acdea",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0400f3abcb28bdce97ae34213c96f943918acdea",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085024991330167648",
"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/8919594929255514880/+/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:4373bab97021ab10",
"user_agent:luci-scheduler"
],
"updated_ts": "1552008426191888",
"url": "https://ci.chromium.org/b/8919594929255514880",
"utcnow_ts": "1552008426444865"
}
}
[01:27:06.453] Task URL: https://ci.chromium.org/b/8919594929255514880
[01:27:06.453] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:2:0) after 1m0s
[01:27:08.875] Received PubSub notification, asking Buildbucket for the build status
[01:27:08.959] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:06.444] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:2:0)
[01:28:06.444] Timer tick, asking Buildbucket for the build status
[01:28:06.498] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:06.498] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:4:0) after 1m0s
[01:29:06.513] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:4:0)
[01:29:06.513] Timer tick, asking Buildbucket for the build status
[01:29:06.598] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:06.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:5:0) after 1m0s
[01:30:06.593] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:5:0)
[01:30:06.593] Timer tick, asking Buildbucket for the build status
[01:30:06.674] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:06.674] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:6:0) after 1m0s
[01:31:06.695] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:6:0)
[01:31:06.695] Timer tick, asking Buildbucket for the build status
[01:31:06.737] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:06.737] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:7:0) after 1m0s
[01:32:06.738] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:7:0)
[01:32:06.738] Timer tick, asking Buildbucket for the build status
[01:32:06.775] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:06.776] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:8:0) after 1m0s
[01:33:06.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:8:0)
[01:33:06.792] Timer tick, asking Buildbucket for the build status
[01:33:06.837] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:06.837] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:9:0) after 1m0s
[01:34:06.859] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:9:0)
[01:34:06.859] Timer tick, asking Buildbucket for the build status
[01:34:06.981] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:06.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:10:0) after 1m0s
[01:35:06.987] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:10:0)
[01:35:06.987] Timer tick, asking Buildbucket for the build status
[01:35:07.154] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:07.154] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:11:0) after 1m0s
[01:36:07.179] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:11:0)
[01:36:07.179] Timer tick, asking Buildbucket for the build status
[01:36:07.587] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:07.587] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:12:0) after 1m0s
[01:37:07.574] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:12:0)
[01:37:07.574] Timer tick, asking Buildbucket for the build status
[01:37:07.658] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:07.658] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:13:0) after 1m0s
[01:38:07.677] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:13:0)
[01:38:07.677] Timer tick, asking Buildbucket for the build status
[01:38:07.956] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:07.956] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:14:0) after 1m0s
[01:39:07.981] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:14:0)
[01:39:07.981] Timer tick, asking Buildbucket for the build status
[01:39:08.465] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:08.465] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:15:0) after 1m0s
[01:40:08.489] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:15:0)
[01:40:08.489] Timer tick, asking Buildbucket for the build status
[01:40:08.772] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:08.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:16:0) after 1m0s
[01:41:08.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:16:0)
[01:41:08.773] Timer tick, asking Buildbucket for the build status
[01:41:08.945] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:08.945] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:17:0) after 1m0s
[01:42:08.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:17:0)
[01:42:08.982] Timer tick, asking Buildbucket for the build status
[01:42:09.068] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:09.068] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:18:0) after 1m0s
[01:43:09.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:18:0)
[01:43:09.083] Timer tick, asking Buildbucket for the build status
[01:43:09.375] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:09.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:19:0) after 1m0s
[01:44:09.369] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:19:0)
[01:44:09.369] Timer tick, asking Buildbucket for the build status
[01:44:10.000] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:10.000] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:20:0) after 1m0s
[01:45:09.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:20:0)
[01:45:09.989] Timer tick, asking Buildbucket for the build status
[01:45:10.092] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:10.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:21:0) after 1m0s
[01:46:10.148] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:21:0)
[01:46:10.148] Timer tick, asking Buildbucket for the build status
[01:46:10.274] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:10.274] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:22:0) after 1m0s
[01:47:10.276] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:22:0)
[01:47:10.276] Timer tick, asking Buildbucket for the build status
[01:47:10.455] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:10.455] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:23:0) after 1m0s
[01:48:10.469] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:23:0)
[01:48:10.469] Timer tick, asking Buildbucket for the build status
[01:48:10.657] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:10.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:24:0) after 1m0s
[01:49:10.691] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:24:0)
[01:49:10.691] Timer tick, asking Buildbucket for the build status
[01:49:10.765] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:10.765] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:25:0) after 1m0s
[01:50:10.801] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:25:0)
[01:50:10.801] Timer tick, asking Buildbucket for the build status
[01:50:10.853] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:10.853] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:26:0) after 1m0s
[01:51:10.867] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:26:0)
[01:51:10.867] Timer tick, asking Buildbucket for the build status
[01:51:10.982] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:10.982] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:27:0) after 1m0s
[01:52:10.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:27:0)
[01:52:10.968] Timer tick, asking Buildbucket for the build status
[01:52:11.007] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:11.007] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:28:0) after 1m0s
[01:53:11.036] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:28:0)
[01:53:11.036] Timer tick, asking Buildbucket for the build status
[01:53:11.260] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:11.260] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:29:0) after 1m0s
[01:54:11.290] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:29:0)
[01:54:11.290] Timer tick, asking Buildbucket for the build status
[01:54:11.359] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:11.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:30:0) after 1m0s
[01:55:11.391] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:30:0)
[01:55:11.391] Timer tick, asking Buildbucket for the build status
[01:55:11.473] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:11.473] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:31:0) after 1m0s
[01:56:11.474] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:31:0)
[01:56:11.474] Timer tick, asking Buildbucket for the build status
[01:56:11.564] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:11.564] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:32:0) after 1m0s
[01:57:11.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:32:0)
[01:57:11.579] Timer tick, asking Buildbucket for the build status
[01:57:11.761] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:11.761] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:33:0) after 1m0s
[01:58:11.783] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:33:0)
[01:58:11.783] Timer tick, asking Buildbucket for the build status
[01:58:12.158] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:12.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:34:0) after 1m0s
[01:59:12.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:34:0)
[01:59:12.197] Timer tick, asking Buildbucket for the build status
[01:59:12.444] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:12.444] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:35:0) after 1m0s
[02:00:12.490] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:35:0)
[02:00:12.490] Timer tick, asking Buildbucket for the build status
[02:00:12.756] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:12.756] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:36:0) after 1m0s
[02:01:12.777] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:36:0)
[02:01:12.777] Timer tick, asking Buildbucket for the build status
[02:01:13.036] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:13.036] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:37:0) after 1m0s
[02:02:13.059] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:37:0)
[02:02:13.059] Timer tick, asking Buildbucket for the build status
[02:02:13.274] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:13.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:38:0) after 1m0s
[02:03:13.310] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:38:0)
[02:03:13.310] Timer tick, asking Buildbucket for the build status
[02:03:13.353] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:13.353] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:39:0) after 1m0s
[02:04:13.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:39:0)
[02:04:13.345] Timer tick, asking Buildbucket for the build status
[02:04:15.843] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:15.843] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:40:0) after 1m0s
[02:05:15.882] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:40:0)
[02:05:15.882] Timer tick, asking Buildbucket for the build status
[02:05:15.957] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:15.957] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:41:0) after 1m0s
[02:06:15.971] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:41:0)
[02:06:15.971] Timer tick, asking Buildbucket for the build status
[02:06:16.178] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:16.178] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:42:0) after 1m0s
[02:07:16.169] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:42:0)
[02:07:16.169] Timer tick, asking Buildbucket for the build status
[02:07:16.274] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:16.274] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:43:0) after 1m0s
[02:08:16.276] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:43:0)
[02:08:16.276] Timer tick, asking Buildbucket for the build status
[02:08:16.465] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:16.465] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:44:0) after 1m0s
[02:09:16.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:44:0)
[02:09:16.519] Timer tick, asking Buildbucket for the build status
[02:09:16.566] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:16.566] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:45:0) after 1m0s
[02:10:16.572] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:45:0)
[02:10:16.572] Timer tick, asking Buildbucket for the build status
[02:10:16.671] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:16.671] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:46:0) after 1m0s
[02:11:16.690] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:46:0)
[02:11:16.690] Timer tick, asking Buildbucket for the build status
[02:11:16.746] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:16.746] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:47:0) after 1m0s
[02:12:16.777] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:47:0)
[02:12:16.777] Timer tick, asking Buildbucket for the build status
[02:12:16.858] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:16.858] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:48:0) after 1m0s
[02:13:16.879] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:48:0)
[02:13:16.879] Timer tick, asking Buildbucket for the build status
[02:13:16.950] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:16.950] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:49:0) after 1m0s
[02:14:16.965] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:49:0)
[02:14:16.965] Timer tick, asking Buildbucket for the build status
[02:14:17.366] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:17.366] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:50:0) after 1m0s
[02:15:17.375] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:50:0)
[02:15:17.375] Timer tick, asking Buildbucket for the build status
[02:15:17.451] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:17.451] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:51:0) after 1m0s
[02:16:17.501] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:51:0)
[02:16:17.501] Timer tick, asking Buildbucket for the build status
[02:16:17.793] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:17.794] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:52:0) after 1m0s
[02:17:17.776] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:52:0)
[02:17:17.776] Timer tick, asking Buildbucket for the build status
[02:17:17.874] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:17.874] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:53:0) after 1m0s
[02:18:17.875] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:53:0)
[02:18:17.875] Timer tick, asking Buildbucket for the build status
[02:18:17.957] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:17.957] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:54:0) after 1m0s
[02:19:17.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:54:0)
[02:19:17.980] Timer tick, asking Buildbucket for the build status
[02:19:18.088] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:18.089] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:55:0) after 1m0s
[02:20:18.077] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:55:0)
[02:20:18.077] Timer tick, asking Buildbucket for the build status
[02:20:18.290] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:18.290] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:56:0) after 1m0s
[02:21:18.266] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:56:0)
[02:21:18.266] Timer tick, asking Buildbucket for the build status
[02:21:18.369] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:18.369] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:57:0) after 1m0s
[02:22:18.374] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:57:0)
[02:22:18.374] Timer tick, asking Buildbucket for the build status
[02:22:18.473] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:18.473] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:58:0) after 1m0s
[02:23:18.492] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:58:0)
[02:23:18.493] Timer tick, asking Buildbucket for the build status
[02:23:18.555] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:18.555] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:59:0) after 1m0s
[02:24:18.558] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:59:0)
[02:24:18.558] Timer tick, asking Buildbucket for the build status
[02:24:18.595] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:18.596] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:60:0) after 1m0s
[02:25:18.587] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:60:0)
[02:25:18.587] Timer tick, asking Buildbucket for the build status
[02:25:18.624] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:18.624] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:61:0) after 1m0s
[02:26:18.653] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:61:0)
[02:26:18.653] Timer tick, asking Buildbucket for the build status
[02:26:18.866] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:18.866] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:62:0) after 1m0s
[02:27:18.923] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:62:0)
[02:27:18.923] Timer tick, asking Buildbucket for the build status
[02:27:18.955] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:18.955] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:63:0) after 1m0s
[02:28:18.975] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:63:0)
[02:28:18.975] Timer tick, asking Buildbucket for the build status
[02:28:19.014] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:19.014] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:64:0) after 1m0s
[02:29:18.996] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:64:0)
[02:29:18.996] Timer tick, asking Buildbucket for the build status
[02:29:19.087] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:19.087] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:65:0) after 1m0s
[02:30:19.092] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:65:0)
[02:30:19.092] Timer tick, asking Buildbucket for the build status
[02:30:19.210] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:19.210] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:66:0) after 1m0s
[02:31:19.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:66:0)
[02:31:19.202] Timer tick, asking Buildbucket for the build status
[02:31:19.241] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:19.241] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:67:0) after 1m0s
[02:32:19.263] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:67:0)
[02:32:19.263] Timer tick, asking Buildbucket for the build status
[02:32:19.452] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:19.452] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:68:0) after 1m0s
[02:33:19.427] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:68:0)
[02:33:19.427] Timer tick, asking Buildbucket for the build status
[02:33:19.769] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:19.769] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:69:0) after 1m0s
[02:34:19.770] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:69:0)
[02:34:19.770] Timer tick, asking Buildbucket for the build status
[02:34:20.051] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:20.051] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:70:0) after 1m0s
[02:35:20.074] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:70:0)
[02:35:20.074] Timer tick, asking Buildbucket for the build status
[02:35:20.157] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:20.157] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:71:0) after 1m0s
[02:36:20.179] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:71:0)
[02:36:20.179] Timer tick, asking Buildbucket for the build status
[02:36:20.302] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:20.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:72:0) after 1m0s
[02:37:20.325] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:72:0)
[02:37:20.325] Timer tick, asking Buildbucket for the build status
[02:37:20.555] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:20.555] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:73:0) after 1m0s
[02:38:20.577] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:73:0)
[02:38:20.577] Timer tick, asking Buildbucket for the build status
[02:38:20.661] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:20.661] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:74:0) after 1m0s
[02:39:20.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:74:0)
[02:39:20.683] Timer tick, asking Buildbucket for the build status
[02:39:20.781] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:20.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:75:0) after 1m0s
[02:40:20.768] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:75:0)
[02:40:20.768] Timer tick, asking Buildbucket for the build status
[02:40:20.863] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:20.863] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:76:0) after 1m0s
[02:41:20.873] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:76:0)
[02:41:20.873] Timer tick, asking Buildbucket for the build status
[02:41:21.188] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:21.188] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:77:0) after 1m0s
[02:42:21.201] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:77:0)
[02:42:21.201] Timer tick, asking Buildbucket for the build status
[02:42:21.298] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:21.298] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:78:0) after 1m0s
[02:43:21.321] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:78:0)
[02:43:21.321] Timer tick, asking Buildbucket for the build status
[02:43:21.365] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:21.365] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:79:0) after 1m0s
[02:44:21.391] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:79:0)
[02:44:21.391] Timer tick, asking Buildbucket for the build status
[02:44:21.481] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:21.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:80:0) after 1m0s
[02:45:21.480] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:80:0)
[02:45:21.480] Timer tick, asking Buildbucket for the build status
[02:45:21.563] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:21.563] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:81:0) after 1m0s
[02:46:21.553] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:81:0)
[02:46:21.553] Timer tick, asking Buildbucket for the build status
[02:46:21.595] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:21.595] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:82:0) after 1m0s
[02:47:21.617] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:82:0)
[02:47:21.617] Timer tick, asking Buildbucket for the build status
[02:47:21.805] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:21.805] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:83:0) after 1m0s
[02:48:21.828] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:83:0)
[02:48:21.828] Timer tick, asking Buildbucket for the build status
[02:48:21.876] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:21.876] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:84:0) after 1m0s
[02:49:21.872] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:84:0)
[02:49:21.872] Timer tick, asking Buildbucket for the build status
[02:49:21.954] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:21.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:85:0) after 1m0s
[02:50:21.974] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:85:0)
[02:50:21.974] Timer tick, asking Buildbucket for the build status
[02:50:22.051] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:22.051] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:86:0) after 1m0s
[02:51:22.086] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:86:0)
[02:51:22.086] Timer tick, asking Buildbucket for the build status
[02:51:22.131] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:22.131] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:87:0) after 1m0s
[02:52:22.108] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:87:0)
[02:52:22.108] Timer tick, asking Buildbucket for the build status
[02:52:22.143] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:22.144] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:88:0) after 1m0s
[02:53:22.167] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:88:0)
[02:53:22.167] Timer tick, asking Buildbucket for the build status
[02:53:22.207] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:22.207] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:89:0) after 1m0s
[02:54:22.225] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:89:0)
[02:54:22.225] Timer tick, asking Buildbucket for the build status
[02:54:22.291] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:22.291] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:90:0) after 1m0s
[02:55:22.299] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:90:0)
[02:55:22.299] Timer tick, asking Buildbucket for the build status
[02:55:22.342] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:22.342] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:91:0) after 1m0s
[02:56:22.419] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:91:0)
[02:56:22.419] Timer tick, asking Buildbucket for the build status
[02:56:22.497] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:22.498] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:92:0) after 1m0s
[02:57:22.480] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:92:0)
[02:57:22.480] Timer tick, asking Buildbucket for the build status
[02:57:22.616] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:22.616] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:93:0) after 1m0s
[02:58:22.594] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:93:0)
[02:58:22.594] Timer tick, asking Buildbucket for the build status
[02:58:22.696] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:22.696] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:94:0) after 1m0s
[02:59:22.692] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:94:0)
[02:59:22.692] Timer tick, asking Buildbucket for the build status
[02:59:22.755] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:22.755] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:95:0) after 1m0s
[03:00:22.783] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:95:0)
[03:00:22.783] Timer tick, asking Buildbucket for the build status
[03:00:22.822] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:22.822] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:96:0) after 1m0s
[03:01:22.866] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:96:0)
[03:01:22.866] Timer tick, asking Buildbucket for the build status
[03:01:23.009] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:23.009] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:97:0) after 1m0s
[03:02:23.034] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:97:0)
[03:02:23.034] Timer tick, asking Buildbucket for the build status
[03:02:23.112] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:23.112] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:98:0) after 1m0s
[03:03:23.155] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:98:0)
[03:03:23.155] Timer tick, asking Buildbucket for the build status
[03:03:23.243] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:23.243] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:99:0) after 1m0s
[03:04:23.269] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:99:0)
[03:04:23.269] Timer tick, asking Buildbucket for the build status
[03:04:23.360] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:23.360] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:100:0) after 1m0s
[03:05:23.387] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:100:0)
[03:05:23.387] Timer tick, asking Buildbucket for the build status
[03:05:23.488] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:23.488] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:101:0) after 1m0s
[03:06:23.505] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:101:0)
[03:06:23.505] Timer tick, asking Buildbucket for the build status
[03:06:23.540] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:23.540] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:102:0) after 1m0s
[03:07:23.526] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:102:0)
[03:07:23.526] Timer tick, asking Buildbucket for the build status
[03:07:23.568] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:23.568] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:103:0) after 1m0s
[03:08:23.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:103:0)
[03:08:23.591] Timer tick, asking Buildbucket for the build status
[03:08:23.859] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:23.859] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:104:0) after 1m0s
[03:09:23.884] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:104:0)
[03:09:23.884] Timer tick, asking Buildbucket for the build status
[03:09:23.931] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:23.931] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:105:0) after 1m0s
[03:10:24.019] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:105:0)
[03:10:24.019] Timer tick, asking Buildbucket for the build status
[03:10:24.089] Build 8919594929255514880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:24.090] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085024991330167648:106:0) after 1m0s
[03:10:51.139] Received PubSub notification, asking Buildbucket for the build status
[03:10:51.196] Build 8919594929255514880: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:10:51.196] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1552014650229876",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552008425429445",
"id": "8919594929255514880",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0400f3abcb28bdce97ae34213c96f943918acdea\"}}",
"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\"}, \"buildnumber\": 12341, \"got_nacl_revision\": \"1ed9878b8eb68c1e0d0268754dafcf83e747fa8f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26981}\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"got_swarming_client_revision\": \"7a61cf37d6a0163f0ec02d495289a1d038e62457\", \"got_revision_cp\": \"refs/heads/master@{#638862}\", \"got_webrtc_revision\": \"be7af9399ceb88171bf60b50419ff2dec8184fb9\", \"got_revision\": \"0400f3abcb28bdce97ae34213c96f943918acdea\", \"buildername\": \"Windows deterministic\", \"recipe\": \"swarming/deterministic_build\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"14126505b237ed3f56b7a3860fef43403e0accd9\", \"got_v8_revision\": \"3e8a733af17a7812eba188dad612be503bd45c57\", \"got_v8_revision_cp\": \"refs/heads/7.4.288@{#1}\", \"got_dawn_revision\": \"b6a80b321e8fa0b79d9a947656ea0ad649ed5a3c\", \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"bot_id\": \"win10-727f49a0-us-west1-b-kb2j\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"revision\": \"0400f3abcb28bdce97ae34213c96f943918acdea\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1552008428269836",
"status": "COMPLETED",
"status_changed_ts": "1552014650867644",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12341",
"builder:Windows deterministic",
"buildset:commit/git/0400f3abcb28bdce97ae34213c96f943918acdea",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0400f3abcb28bdce97ae34213c96f943918acdea",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085024991330167648",
"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/8919594929255514880/+/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:4373bab97021ab10",
"user_agent:luci-scheduler"
],
"updated_ts": "1552014650868782",
"url": "https://ci.chromium.org/b/8919594929255514880",
"utcnow_ts": "1552014651185308"
}
}
[03:10:51.196] Invocation finished in 1h43m47.128944193s with status SUCCEEDED