[01:42:22.151] New invocation is queued and will start shortly
[01:42:23.432] Starting the invocation (attempt 1)
[01:42:23.466] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:42:23.466] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:42:23.466] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9087741937660181072",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"e0d750f185d07d718ac624baeeed882caaecae70\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9087741937660181072",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/e0d750f185d07d718ac624baeeed882caaecae70",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e0d750f185d07d718ac624baeeed882caaecae70",
"gitiles_ref:refs/heads/master"
]
}
[01:42:24.732] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1549417343571186",
"id": "8922311875506367664",
"lease_expiration_ts": "1549536144407365",
"lease_key": "528085763",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e0d750f185d07d718ac624baeeed882caaecae70\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1549417344520000",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11858",
"builder:Windows deterministic",
"buildset:commit/git/e0d750f185d07d718ac624baeeed882caaecae70",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e0d750f185d07d718ac624baeeed882caaecae70",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087741937660181072",
"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/8922311875506367664/+/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:42d949f4aaa7ef10",
"user_agent:luci-scheduler"
],
"updated_ts": "1549417344520115",
"url": "https://ci.chromium.org/b/8922311875506367664",
"utcnow_ts": "1549417344722209"
}
}
[01:42:24.732] Task URL: https://ci.chromium.org/b/8922311875506367664
[01:42:24.732] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:2:0) after 1m0s
[01:42:28.579] Received PubSub notification, asking Buildbucket for the build status
[01:42:28.628] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:24.758] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:2:0)
[01:43:24.758] Timer tick, asking Buildbucket for the build status
[01:43:24.855] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:24.855] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:4:0) after 1m0s
[01:44:24.914] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:4:0)
[01:44:24.914] Timer tick, asking Buildbucket for the build status
[01:44:25.009] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:25.009] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:5:0) after 1m0s
[01:45:25.024] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:5:0)
[01:45:25.024] Timer tick, asking Buildbucket for the build status
[01:45:25.162] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:25.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:6:0) after 1m0s
[01:46:25.199] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:6:0)
[01:46:25.199] Timer tick, asking Buildbucket for the build status
[01:46:25.266] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:25.266] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:7:0) after 1m0s
[01:47:25.291] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:7:0)
[01:47:25.291] Timer tick, asking Buildbucket for the build status
[01:47:25.339] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:25.339] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:8:0) after 1m0s
[01:48:25.358] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:8:0)
[01:48:25.358] Timer tick, asking Buildbucket for the build status
[01:48:25.421] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:25.421] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:9:0) after 1m0s
[01:49:25.443] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:9:0)
[01:49:25.443] Timer tick, asking Buildbucket for the build status
[01:49:25.487] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:25.487] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:10:0) after 1m0s
[01:50:25.508] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:10:0)
[01:50:25.508] Timer tick, asking Buildbucket for the build status
[01:50:25.574] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:25.574] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:11:0) after 1m0s
[01:51:25.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:11:0)
[01:51:25.598] Timer tick, asking Buildbucket for the build status
[01:51:25.644] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:25.644] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:12:0) after 1m0s
[01:52:25.675] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:12:0)
[01:52:25.675] Timer tick, asking Buildbucket for the build status
[01:52:25.870] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:25.870] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:13:0) after 1m0s
[01:53:25.891] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:13:0)
[01:53:25.891] Timer tick, asking Buildbucket for the build status
[01:53:26.125] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:26.125] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:14:0) after 1m0s
[01:54:26.213] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:14:0)
[01:54:26.213] Timer tick, asking Buildbucket for the build status
[01:54:26.291] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:26.291] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:15:0) after 1m0s
[01:55:26.317] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:15:0)
[01:55:26.317] Timer tick, asking Buildbucket for the build status
[01:55:26.413] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:26.413] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:16:0) after 1m0s
[01:56:26.465] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:16:0)
[01:56:26.465] Timer tick, asking Buildbucket for the build status
[01:56:26.508] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:26.508] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:17:0) after 1m0s
[01:57:26.530] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:17:0)
[01:57:26.530] Timer tick, asking Buildbucket for the build status
[01:57:26.597] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:26.597] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:18:0) after 1m0s
[01:58:26.623] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:18:0)
[01:58:26.623] Timer tick, asking Buildbucket for the build status
[01:58:26.683] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:26.683] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:19:0) after 1m0s
[01:59:26.720] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:19:0)
[01:59:26.720] Timer tick, asking Buildbucket for the build status
[01:59:26.825] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:26.825] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:20:0) after 1m0s
[02:00:26.847] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:20:0)
[02:00:26.847] Timer tick, asking Buildbucket for the build status
[02:00:27.014] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:27.014] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:21:0) after 1m0s
[02:01:27.037] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:21:0)
[02:01:27.037] Timer tick, asking Buildbucket for the build status
[02:01:27.112] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:27.113] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:22:0) after 1m0s
[02:02:27.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:22:0)
[02:02:27.137] Timer tick, asking Buildbucket for the build status
[02:02:27.183] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:27.183] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:23:0) after 1m0s
[02:03:27.229] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:23:0)
[02:03:27.229] Timer tick, asking Buildbucket for the build status
[02:03:27.279] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:27.279] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:24:0) after 1m0s
[02:04:27.300] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:24:0)
[02:04:27.300] Timer tick, asking Buildbucket for the build status
[02:04:27.368] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:27.368] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:25:0) after 1m0s
[02:05:27.425] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:25:0)
[02:05:27.425] Timer tick, asking Buildbucket for the build status
[02:05:27.591] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:27.591] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:26:0) after 1m0s
[02:06:27.709] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:26:0)
[02:06:27.709] Timer tick, asking Buildbucket for the build status
[02:06:27.774] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:27.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:27:0) after 1m0s
[02:07:27.796] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:27:0)
[02:07:27.796] Timer tick, asking Buildbucket for the build status
[02:07:27.897] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:27.897] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:28:0) after 1m0s
[02:08:27.955] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:28:0)
[02:08:27.955] Timer tick, asking Buildbucket for the build status
[02:08:28.027] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:28.027] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:29:0) after 1m0s
[02:09:28.050] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:29:0)
[02:09:28.050] Timer tick, asking Buildbucket for the build status
[02:09:28.103] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:28.103] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:30:0) after 1m0s
[02:10:28.128] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:30:0)
[02:10:28.128] Timer tick, asking Buildbucket for the build status
[02:10:28.206] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:28.206] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:31:0) after 1m0s
[02:11:28.227] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:31:0)
[02:11:28.227] Timer tick, asking Buildbucket for the build status
[02:11:28.292] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:28.292] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:32:0) after 1m0s
[02:12:28.320] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:32:0)
[02:12:28.320] Timer tick, asking Buildbucket for the build status
[02:12:28.407] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:28.407] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:33:0) after 1m0s
[02:13:28.434] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:33:0)
[02:13:28.434] Timer tick, asking Buildbucket for the build status
[02:13:28.492] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:28.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:34:0) after 1m0s
[02:14:28.556] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:34:0)
[02:14:28.556] Timer tick, asking Buildbucket for the build status
[02:14:28.614] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:28.614] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:35:0) after 1m0s
[02:15:28.633] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:35:0)
[02:15:28.633] Timer tick, asking Buildbucket for the build status
[02:15:28.695] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:28.695] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:36:0) after 1m0s
[02:16:28.717] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:36:0)
[02:16:28.717] Timer tick, asking Buildbucket for the build status
[02:16:28.950] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:28.950] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:37:0) after 1m0s
[02:17:28.975] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:37:0)
[02:17:28.975] Timer tick, asking Buildbucket for the build status
[02:17:29.025] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:29.025] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:38:0) after 1m0s
[02:18:29.048] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:38:0)
[02:18:29.048] Timer tick, asking Buildbucket for the build status
[02:18:29.187] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:29.187] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:39:0) after 1m0s
[02:19:29.208] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:39:0)
[02:19:29.208] Timer tick, asking Buildbucket for the build status
[02:19:30.072] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:30.072] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:40:0) after 1m0s
[02:20:30.101] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:40:0)
[02:20:30.101] Timer tick, asking Buildbucket for the build status
[02:20:30.415] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:30.415] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:41:0) after 1m0s
[02:21:30.437] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:41:0)
[02:21:30.437] Timer tick, asking Buildbucket for the build status
[02:21:30.500] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:30.500] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:42:0) after 1m0s
[02:22:30.522] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:42:0)
[02:22:30.522] Timer tick, asking Buildbucket for the build status
[02:22:30.584] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:30.584] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:43:0) after 1m0s
[02:23:30.607] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:43:0)
[02:23:30.607] Timer tick, asking Buildbucket for the build status
[02:23:30.677] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:30.677] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:44:0) after 1m0s
[02:24:30.747] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:44:0)
[02:24:30.747] Timer tick, asking Buildbucket for the build status
[02:24:30.863] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:30.863] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:45:0) after 1m0s
[02:25:30.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:45:0)
[02:25:30.886] Timer tick, asking Buildbucket for the build status
[02:25:30.971] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:30.971] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:46:0) after 1m0s
[02:26:30.994] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:46:0)
[02:26:30.994] Timer tick, asking Buildbucket for the build status
[02:26:31.253] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:31.253] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:47:0) after 1m0s
[02:27:31.326] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:47:0)
[02:27:31.326] Timer tick, asking Buildbucket for the build status
[02:27:31.410] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:31.410] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:48:0) after 1m0s
[02:28:31.432] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:48:0)
[02:28:31.432] Timer tick, asking Buildbucket for the build status
[02:28:31.509] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:31.509] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:49:0) after 1m0s
[02:29:31.530] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:49:0)
[02:29:31.530] Timer tick, asking Buildbucket for the build status
[02:29:31.751] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:31.751] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:50:0) after 1m0s
[02:30:31.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:50:0)
[02:30:31.773] Timer tick, asking Buildbucket for the build status
[02:30:31.826] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:31.826] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:51:0) after 1m0s
[02:31:31.850] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:51:0)
[02:31:31.851] Timer tick, asking Buildbucket for the build status
[02:31:31.983] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:31.983] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:52:0) after 1m0s
[02:32:32.012] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:52:0)
[02:32:32.012] Timer tick, asking Buildbucket for the build status
[02:32:32.318] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:32.318] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:53:0) after 1m0s
[02:33:32.338] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:53:0)
[02:33:32.338] Timer tick, asking Buildbucket for the build status
[02:33:32.422] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:32.422] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:54:0) after 1m0s
[02:34:32.480] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:54:0)
[02:34:32.480] Timer tick, asking Buildbucket for the build status
[02:34:32.528] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:32.528] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:55:0) after 1m0s
[02:35:32.549] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:55:0)
[02:35:32.549] Timer tick, asking Buildbucket for the build status
[02:35:32.684] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:32.684] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:56:0) after 1m0s
[02:36:32.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:56:0)
[02:36:32.708] Timer tick, asking Buildbucket for the build status
[02:36:32.796] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:32.796] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:57:0) after 1m0s
[02:37:32.835] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:57:0)
[02:37:32.835] Timer tick, asking Buildbucket for the build status
[02:37:32.898] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:32.898] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:58:0) after 1m0s
[02:38:32.918] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:58:0)
[02:38:32.918] Timer tick, asking Buildbucket for the build status
[02:38:32.986] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:32.986] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:59:0) after 1m0s
[02:39:33.006] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:59:0)
[02:39:33.007] Timer tick, asking Buildbucket for the build status
[02:39:33.103] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:33.103] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:60:0) after 1m0s
[02:40:33.129] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:60:0)
[02:40:33.129] Timer tick, asking Buildbucket for the build status
[02:40:33.232] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:33.232] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:61:0) after 1m0s
[02:41:33.264] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:61:0)
[02:41:33.264] Timer tick, asking Buildbucket for the build status
[02:41:33.329] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:33.329] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:62:0) after 1m0s
[02:42:33.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:62:0)
[02:42:33.350] Timer tick, asking Buildbucket for the build status
[02:42:33.438] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:33.438] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:63:0) after 1m0s
[02:43:33.479] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:63:0)
[02:43:33.479] Timer tick, asking Buildbucket for the build status
[02:43:33.590] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:33.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:64:0) after 1m0s
[02:44:33.652] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:64:0)
[02:44:33.652] Timer tick, asking Buildbucket for the build status
[02:44:33.952] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:33.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:65:0) after 1m0s
[02:45:33.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:65:0)
[02:45:33.980] Timer tick, asking Buildbucket for the build status
[02:45:34.151] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:34.151] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:66:0) after 1m0s
[02:46:34.195] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:66:0)
[02:46:34.195] Timer tick, asking Buildbucket for the build status
[02:46:34.243] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:34.243] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:67:0) after 1m0s
[02:47:34.261] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:67:0)
[02:47:34.261] Timer tick, asking Buildbucket for the build status
[02:47:34.310] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:34.310] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:68:0) after 1m0s
[02:48:34.351] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:68:0)
[02:48:34.351] Timer tick, asking Buildbucket for the build status
[02:48:34.397] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:34.397] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:69:0) after 1m0s
[02:49:34.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:69:0)
[02:49:34.470] Timer tick, asking Buildbucket for the build status
[02:49:34.548] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:34.548] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:70:0) after 1m0s
[02:50:34.586] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:70:0)
[02:50:34.587] Timer tick, asking Buildbucket for the build status
[02:50:34.738] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:34.739] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:71:0) after 1m0s
[02:51:34.820] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:71:0)
[02:51:34.820] Timer tick, asking Buildbucket for the build status
[02:51:34.898] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:34.898] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:72:0) after 1m0s
[02:52:34.928] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:72:0)
[02:52:34.928] Timer tick, asking Buildbucket for the build status
[02:52:35.002] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:35.002] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:73:0) after 1m0s
[02:53:35.081] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:73:0)
[02:53:35.081] Timer tick, asking Buildbucket for the build status
[02:53:35.189] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:35.190] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:74:0) after 1m0s
[02:54:35.212] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:74:0)
[02:54:35.212] Timer tick, asking Buildbucket for the build status
[02:54:35.793] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:35.793] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:75:0) after 1m0s
[02:55:35.852] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:75:0)
[02:55:35.852] Timer tick, asking Buildbucket for the build status
[02:55:35.921] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:35.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:76:0) after 1m0s
[02:56:35.999] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:76:0)
[02:56:35.999] Timer tick, asking Buildbucket for the build status
[02:56:36.043] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:36.043] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:77:0) after 1m0s
[02:57:36.088] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:77:0)
[02:57:36.088] Timer tick, asking Buildbucket for the build status
[02:57:36.287] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:36.287] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:78:0) after 1m0s
[02:58:36.314] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:78:0)
[02:58:36.314] Timer tick, asking Buildbucket for the build status
[02:58:36.411] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:36.411] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:79:0) after 1m0s
[02:59:36.439] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:79:0)
[02:59:36.440] Timer tick, asking Buildbucket for the build status
[02:59:36.580] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:36.580] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:80:0) after 1m0s
[03:00:36.605] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:80:0)
[03:00:36.605] Timer tick, asking Buildbucket for the build status
[03:00:36.676] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:36.676] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:81:0) after 1m0s
[03:01:36.703] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:81:0)
[03:01:36.703] Timer tick, asking Buildbucket for the build status
[03:01:36.757] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:36.757] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:82:0) after 1m0s
[03:02:36.785] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:82:0)
[03:02:36.785] Timer tick, asking Buildbucket for the build status
[03:02:36.868] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:36.868] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:83:0) after 1m0s
[03:03:36.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:83:0)
[03:03:36.912] Timer tick, asking Buildbucket for the build status
[03:03:36.957] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:36.957] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:84:0) after 1m0s
[03:04:36.983] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:84:0)
[03:04:36.983] Timer tick, asking Buildbucket for the build status
[03:04:37.115] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:37.115] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:85:0) after 1m0s
[03:05:37.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:85:0)
[03:05:37.137] Timer tick, asking Buildbucket for the build status
[03:05:37.303] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:37.303] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:86:0) after 1m0s
[03:06:37.342] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:86:0)
[03:06:37.342] Timer tick, asking Buildbucket for the build status
[03:06:37.558] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:37.558] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:87:0) after 1m0s
[03:07:37.580] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:87:0)
[03:07:37.580] Timer tick, asking Buildbucket for the build status
[03:07:37.655] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:37.655] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:88:0) after 1m0s
[03:08:37.685] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:88:0)
[03:08:37.685] Timer tick, asking Buildbucket for the build status
[03:08:37.750] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:37.750] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:89:0) after 1m0s
[03:09:37.789] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:89:0)
[03:09:37.789] Timer tick, asking Buildbucket for the build status
[03:09:37.990] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:37.990] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:90:0) after 1m0s
[03:10:38.057] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:90:0)
[03:10:38.057] Timer tick, asking Buildbucket for the build status
[03:10:38.182] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:38.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:91:0) after 1m0s
[03:11:38.209] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:91:0)
[03:11:38.209] Timer tick, asking Buildbucket for the build status
[03:11:38.447] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:38.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:92:0) after 1m0s
[03:12:38.472] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:92:0)
[03:12:38.472] Timer tick, asking Buildbucket for the build status
[03:12:38.745] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:38.745] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:93:0) after 1m0s
[03:13:38.769] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:93:0)
[03:13:38.769] Timer tick, asking Buildbucket for the build status
[03:13:38.877] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:38.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:94:0) after 1m0s
[03:14:38.921] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:94:0)
[03:14:38.921] Timer tick, asking Buildbucket for the build status
[03:14:39.002] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:39.002] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:95:0) after 1m0s
[03:15:39.084] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:95:0)
[03:15:39.084] Timer tick, asking Buildbucket for the build status
[03:15:39.128] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:39.128] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:96:0) after 1m0s
[03:16:39.179] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:96:0)
[03:16:39.179] Timer tick, asking Buildbucket for the build status
[03:16:39.533] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:39.533] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:97:0) after 1m0s
[03:17:39.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:97:0)
[03:17:39.591] Timer tick, asking Buildbucket for the build status
[03:17:39.635] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:39.635] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:98:0) after 1m0s
[03:18:39.670] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:98:0)
[03:18:39.670] Timer tick, asking Buildbucket for the build status
[03:18:39.766] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:39.766] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:99:0) after 1m0s
[03:19:39.836] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:99:0)
[03:19:39.836] Timer tick, asking Buildbucket for the build status
[03:19:40.158] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:40.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:100:0) after 1m0s
[03:20:40.192] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:100:0)
[03:20:40.192] Timer tick, asking Buildbucket for the build status
[03:20:40.259] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:40.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:101:0) after 1m0s
[03:21:40.281] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:101:0)
[03:21:40.282] Timer tick, asking Buildbucket for the build status
[03:21:40.361] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:40.361] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:102:0) after 1m0s
[03:22:40.445] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:102:0)
[03:22:40.445] Timer tick, asking Buildbucket for the build status
[03:22:40.489] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:40.489] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:103:0) after 1m0s
[03:23:40.589] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:103:0)
[03:23:40.589] Timer tick, asking Buildbucket for the build status
[03:23:40.874] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:40.874] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:104:0) after 1m0s
[03:24:40.903] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:104:0)
[03:24:40.903] Timer tick, asking Buildbucket for the build status
[03:24:40.972] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:40.972] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:105:0) after 1m0s
[03:25:41.074] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:105:0)
[03:25:41.074] Timer tick, asking Buildbucket for the build status
[03:25:41.160] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:41.160] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:106:0) after 1m0s
[03:26:41.193] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:106:0)
[03:26:41.193] Timer tick, asking Buildbucket for the build status
[03:26:41.382] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:41.382] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:107:0) after 1m0s
[03:27:41.459] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:107:0)
[03:27:41.459] Timer tick, asking Buildbucket for the build status
[03:27:41.512] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:41.512] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:108:0) after 1m0s
[03:28:41.577] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:108:0)
[03:28:41.577] Timer tick, asking Buildbucket for the build status
[03:28:41.663] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:41.663] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:109:0) after 1m0s
[03:29:41.692] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:109:0)
[03:29:41.692] Timer tick, asking Buildbucket for the build status
[03:29:41.822] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:41.822] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:110:0) after 1m0s
[03:30:41.843] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:110:0)
[03:30:41.843] Timer tick, asking Buildbucket for the build status
[03:30:41.895] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:41.895] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:111:0) after 1m0s
[03:31:41.915] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:111:0)
[03:31:41.915] Timer tick, asking Buildbucket for the build status
[03:31:42.025] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:42.025] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:112:0) after 1m0s
[03:32:42.050] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:112:0)
[03:32:42.050] Timer tick, asking Buildbucket for the build status
[03:32:42.180] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:42.180] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:113:0) after 1m0s
[03:33:42.234] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:113:0)
[03:33:42.234] Timer tick, asking Buildbucket for the build status
[03:33:42.306] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:42.306] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:114:0) after 1m0s
[03:34:42.373] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:114:0)
[03:34:42.373] Timer tick, asking Buildbucket for the build status
[03:34:42.569] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:42.569] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:115:0) after 1m0s
[03:35:42.596] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:115:0)
[03:35:42.596] Timer tick, asking Buildbucket for the build status
[03:35:42.689] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:42.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:116:0) after 1m0s
[03:36:42.715] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:116:0)
[03:36:42.715] Timer tick, asking Buildbucket for the build status
[03:36:43.056] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:43.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:117:0) after 1m0s
[03:37:43.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:117:0)
[03:37:43.089] Timer tick, asking Buildbucket for the build status
[03:37:43.181] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:43.181] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:118:0) after 1m0s
[03:38:43.206] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:118:0)
[03:38:43.206] Timer tick, asking Buildbucket for the build status
[03:38:43.250] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:43.250] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:119:0) after 1m0s
[03:39:43.280] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:119:0)
[03:39:43.280] Timer tick, asking Buildbucket for the build status
[03:39:43.339] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:43.339] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:120:0) after 1m0s
[03:40:43.364] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:120:0)
[03:40:43.364] Timer tick, asking Buildbucket for the build status
[03:40:43.558] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:43.558] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:121:0) after 1m0s
[03:41:43.583] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:121:0)
[03:41:43.583] Timer tick, asking Buildbucket for the build status
[03:41:43.629] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:43.629] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:122:0) after 1m0s
[03:42:43.655] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:122:0)
[03:42:43.655] Timer tick, asking Buildbucket for the build status
[03:42:43.707] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:43.707] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:123:0) after 1m0s
[03:43:43.745] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:123:0)
[03:43:43.745] Timer tick, asking Buildbucket for the build status
[03:43:43.887] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:43.887] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:124:0) after 1m0s
[03:44:43.935] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:124:0)
[03:44:43.935] Timer tick, asking Buildbucket for the build status
[03:44:44.001] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:44.001] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:125:0) after 1m0s
[03:45:44.031] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:125:0)
[03:45:44.031] Timer tick, asking Buildbucket for the build status
[03:45:44.081] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:44.081] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:126:0) after 1m0s
[03:46:44.153] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:126:0)
[03:46:44.153] Timer tick, asking Buildbucket for the build status
[03:46:44.193] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:44.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:127:0) after 1m0s
[03:47:44.224] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:127:0)
[03:47:44.224] Timer tick, asking Buildbucket for the build status
[03:47:44.271] Build 8922311875506367664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:44.271] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087741937660181072:128:0) after 1m0s
[03:48:22.901] Received PubSub notification, asking Buildbucket for the build status
[03:48:22.986] Build 8922311875506367664: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:48:22.986] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1549424901986138",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1549417343571186",
"id": "8922311875506367664",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e0d750f185d07d718ac624baeeed882caaecae70\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"swarm2173-c4\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4055-721ffb4\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"got_nacl_revision\": \"ff15c51d20785fa51c0ef03ccb480b87299acbeb\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"e0d750f185d07d718ac624baeeed882caaecae70\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"978fa65a2cc5e73f70b4d5c4021e49654b7c61b1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26557}\", \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"got_revision_cp\": \"refs/heads/master@{#629404}\", \"branch\": \"refs/heads/master\", \"revision\": \"e0d750f185d07d718ac624baeeed882caaecae70\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"65835be72273fc0f6016f24ad2344efd54ff1091\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"5f662c0042703344eb0eef6d1c123e902e3aefbf\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1549417343571186, \"tags\": [\"build_address:luci.chromium.ci/Windows deterministic/11858\", \"builder:Windows deterministic\", \"buildset:commit/git/e0d750f185d07d718ac624baeeed882caaecae70\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e0d750f185d07d718ac624baeeed882caaecae70\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9087741937660181072\", \"scheduler_job_id:chromium/Windows deterministic\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8922311875506367664\"}}, \"got_v8_revision\": \"fa9e50642d1c610f8555bd0bc378485521fdc44e\", \"got_v8_revision_cp\": \"refs/heads/7.4.83@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 11858, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Windows deterministic\", \"key\": \"builder\"}, {\"value\": \"commit/git/e0d750f185d07d718ac624baeeed882caaecae70\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/e0d750f185d07d718ac624baeeed882caaecae70\", \"key\": \"buildset\"}, {\"value\": \"9087741937660181072\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"chromium/Windows deterministic\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"chromium\", \"builder\": \"Windows deterministic\", \"bucket\": \"ci\"}, \"number\": 11858, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"ef156230f5cd7f8c23f689fa4dc7f92742cd904d\"}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8922311875506367664\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"e0d750f185d07d718ac624baeeed882caaecae70\"}}, \"createTime\": \"2019-02-06T01:42:23.571186Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1549417347877650",
"status": "COMPLETED",
"status_changed_ts": "1549424902597999",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11858",
"builder:Windows deterministic",
"buildset:commit/git/e0d750f185d07d718ac624baeeed882caaecae70",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e0d750f185d07d718ac624baeeed882caaecae70",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087741937660181072",
"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/8922311875506367664/+/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:42d949f4aaa7ef10",
"user_agent:luci-scheduler"
],
"updated_ts": "1549424902599368",
"url": "https://ci.chromium.org/b/8922311875506367664",
"utcnow_ts": "1549424902974291"
}
}
[03:48:22.986] Invocation finished in 2h6m0.853080897s with status SUCCEEDED