[23:20:14.284] New invocation is queued and will start shortly
[23:20:15.399] Starting the invocation (attempt 1)
[23:20:15.440] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:20:15.440] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:20:15.440] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9078057004370824768",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"63b914e26216d05bf52a1de891cb872f12379bc1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9078057004370824768",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/63b914e26216d05bf52a1de891cb872f12379bc1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/63b914e26216d05bf52a1de891cb872f12379bc1",
"gitiles_ref:refs/heads/master"
]
}
[23:20:21.432] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1558653617638622",
"id": "8912626940190134464",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"63b914e26216d05bf52a1de891cb872f12379bc1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558653620748416",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13513",
"builder:Windows deterministic",
"buildset:commit/git/63b914e26216d05bf52a1de891cb872f12379bc1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/63b914e26216d05bf52a1de891cb872f12379bc1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078057004370824768",
"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/8912626940190134464/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1558653620748516",
"url": "https://ci.chromium.org/b/8912626940190134464",
"utcnow_ts": "1558653621370500"
}
}
[23:20:21.432] Task URL: https://ci.chromium.org/b/8912626940190134464
[23:20:21.432] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:2:0) after 1m0s
[23:20:23.613] Received PubSub notification, asking Buildbucket for the build status
[23:20:25.312] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:21.464] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:2:0)
[23:21:21.464] Timer tick, asking Buildbucket for the build status
[23:21:21.681] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:21.681] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:4:0) after 1m0s
[23:22:21.754] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:4:0)
[23:22:21.754] Timer tick, asking Buildbucket for the build status
[23:22:21.905] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:21.905] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:5:0) after 1m0s
[23:23:21.944] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:5:0)
[23:23:21.944] Timer tick, asking Buildbucket for the build status
[23:23:22.390] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:22.390] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:6:0) after 1m0s
[23:24:22.461] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:6:0)
[23:24:22.461] Timer tick, asking Buildbucket for the build status
[23:24:22.737] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:22.737] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:7:0) after 1m0s
[23:25:22.769] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:7:0)
[23:25:22.769] Timer tick, asking Buildbucket for the build status
[23:25:24.006] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:24.006] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:8:0) after 1m0s
[23:26:24.053] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:8:0)
[23:26:24.053] Timer tick, asking Buildbucket for the build status
[23:26:24.163] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:24.163] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:9:0) after 1m0s
[23:27:24.219] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:9:0)
[23:27:24.220] Timer tick, asking Buildbucket for the build status
[23:27:24.586] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:24.586] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:10:0) after 1m0s
[23:28:24.652] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:10:0)
[23:28:24.652] Timer tick, asking Buildbucket for the build status
[23:28:25.202] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:25.202] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:11:0) after 1m0s
[23:29:25.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:11:0)
[23:29:25.194] Timer tick, asking Buildbucket for the build status
[23:29:25.902] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:25.902] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:12:0) after 1m0s
[23:30:25.925] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:12:0)
[23:30:25.925] Timer tick, asking Buildbucket for the build status
[23:30:26.073] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:26.073] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:13:0) after 1m0s
[23:31:26.201] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:13:0)
[23:31:26.201] Timer tick, asking Buildbucket for the build status
[23:31:27.946] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:27.946] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:14:0) after 1m0s
[23:32:27.954] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:14:0)
[23:32:27.954] Timer tick, asking Buildbucket for the build status
[23:32:28.086] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:28.086] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:15:0) after 1m0s
[23:33:28.164] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:15:0)
[23:33:28.164] Timer tick, asking Buildbucket for the build status
[23:33:29.210] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:29.210] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:16:0) after 1m0s
[23:34:29.281] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:16:0)
[23:34:29.281] Timer tick, asking Buildbucket for the build status
[23:34:29.424] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:29.425] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:17:0) after 1m0s
[23:35:29.458] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:17:0)
[23:35:29.458] Timer tick, asking Buildbucket for the build status
[23:35:29.972] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:29.972] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:18:0) after 1m0s
[23:36:29.999] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:18:0)
[23:36:29.999] Timer tick, asking Buildbucket for the build status
[23:36:30.175] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:30.175] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:19:0) after 1m0s
[23:37:30.336] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:19:0)
[23:37:30.336] Timer tick, asking Buildbucket for the build status
[23:37:31.640] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:31.640] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:20:0) after 1m0s
[23:38:31.630] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:20:0)
[23:38:31.630] Timer tick, asking Buildbucket for the build status
[23:38:32.260] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:32.260] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:21:0) after 1m0s
[23:39:32.292] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:21:0)
[23:39:32.293] Timer tick, asking Buildbucket for the build status
[23:39:33.714] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:33.715] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:22:0) after 1m0s
[23:40:33.921] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:22:0)
[23:40:33.921] Timer tick, asking Buildbucket for the build status
[23:40:34.300] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:34.300] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:23:0) after 1m0s
[23:41:34.395] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:23:0)
[23:41:34.395] Timer tick, asking Buildbucket for the build status
[23:41:35.942] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:35.942] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:24:0) after 1m0s
[23:42:35.974] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:24:0)
[23:42:35.974] Timer tick, asking Buildbucket for the build status
[23:42:36.082] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:36.082] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:25:0) after 1m0s
[23:43:36.211] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:25:0)
[23:43:36.211] Timer tick, asking Buildbucket for the build status
[23:43:36.682] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:36.682] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:26:0) after 1m0s
[23:44:36.852] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:26:0)
[23:44:36.852] Timer tick, asking Buildbucket for the build status
[23:44:37.303] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:37.303] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:27:0) after 1m0s
[23:45:37.336] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:27:0)
[23:45:37.336] Timer tick, asking Buildbucket for the build status
[23:45:37.652] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:37.652] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:28:0) after 1m0s
[23:46:37.681] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:28:0)
[23:46:37.681] Timer tick, asking Buildbucket for the build status
[23:46:37.941] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:37.941] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:29:0) after 1m0s
[23:47:37.948] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:29:0)
[23:47:37.948] Timer tick, asking Buildbucket for the build status
[23:47:38.092] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:38.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:30:0) after 1m0s
[23:48:38.165] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:30:0)
[23:48:38.165] Timer tick, asking Buildbucket for the build status
[23:48:39.414] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:39.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:31:0) after 1m0s
[23:49:39.548] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:31:0)
[23:49:39.548] Timer tick, asking Buildbucket for the build status
[23:49:39.867] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:39.867] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:32:0) after 1m0s
[23:50:39.930] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:32:0)
[23:50:39.930] Timer tick, asking Buildbucket for the build status
[23:50:40.182] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:40.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:33:0) after 1m0s
[23:51:40.243] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:33:0)
[23:51:40.243] Timer tick, asking Buildbucket for the build status
[23:51:40.704] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:40.704] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:34:0) after 1m0s
[23:52:40.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:34:0)
[23:52:40.721] Timer tick, asking Buildbucket for the build status
[23:52:40.852] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:40.852] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:35:0) after 1m0s
[23:53:40.868] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:35:0)
[23:53:40.868] Timer tick, asking Buildbucket for the build status
[23:53:41.202] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:41.202] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:36:0) after 1m0s
[23:54:41.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:36:0)
[23:54:41.197] Timer tick, asking Buildbucket for the build status
[23:54:43.285] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:43.285] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:37:0) after 1m0s
[23:55:43.463] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:37:0)
[23:55:43.463] Timer tick, asking Buildbucket for the build status
[23:55:43.611] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:43.611] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:38:0) after 1m0s
[23:56:43.644] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:38:0)
[23:56:43.644] Timer tick, asking Buildbucket for the build status
[23:56:45.950] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:45.950] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:39:0) after 1m0s
[23:57:45.973] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:39:0)
[23:57:45.973] Timer tick, asking Buildbucket for the build status
[23:57:46.716] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:46.716] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:40:0) after 1m0s
[23:58:46.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:40:0)
[23:58:46.763] Timer tick, asking Buildbucket for the build status
[23:58:47.038] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:47.038] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:41:0) after 1m0s
[23:59:47.151] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:41:0)
[23:59:47.151] Timer tick, asking Buildbucket for the build status
[23:59:47.246] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:47.246] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:42:0) after 1m0s
[00:00:47.284] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:42:0)
[00:00:47.284] Timer tick, asking Buildbucket for the build status
[00:00:47.460] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:47.460] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:43:0) after 1m0s
[00:01:47.499] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:43:0)
[00:01:47.499] Timer tick, asking Buildbucket for the build status
[00:01:47.729] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:47.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:44:0) after 1m0s
[00:02:47.752] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:44:0)
[00:02:47.752] Timer tick, asking Buildbucket for the build status
[00:02:48.308] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:48.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:45:0) after 1m0s
[00:03:48.326] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:45:0)
[00:03:48.326] Timer tick, asking Buildbucket for the build status
[00:03:49.655] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:49.655] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:46:0) after 1m0s
[00:04:49.674] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:46:0)
[00:04:49.674] Timer tick, asking Buildbucket for the build status
[00:04:50.083] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:50.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:47:0) after 1m0s
[00:05:50.162] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:47:0)
[00:05:50.162] Timer tick, asking Buildbucket for the build status
[00:05:50.406] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:50.406] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:48:0) after 1m0s
[00:06:50.428] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:48:0)
[00:06:50.428] Timer tick, asking Buildbucket for the build status
[00:06:51.699] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:51.699] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:49:0) after 1m0s
[00:07:51.848] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:49:0)
[00:07:51.848] Timer tick, asking Buildbucket for the build status
[00:07:52.136] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:52.136] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:50:0) after 1m0s
[00:08:52.258] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:50:0)
[00:08:52.258] Timer tick, asking Buildbucket for the build status
[00:08:53.841] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:53.841] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:51:0) after 1m0s
[00:09:53.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:51:0)
[00:09:53.938] Timer tick, asking Buildbucket for the build status
[00:09:54.897] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:54.897] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:52:0) after 1m0s
[00:10:54.991] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:52:0)
[00:10:54.991] Timer tick, asking Buildbucket for the build status
[00:10:56.050] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:56.050] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:53:0) after 1m0s
[00:11:56.145] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:53:0)
[00:11:56.145] Timer tick, asking Buildbucket for the build status
[00:11:58.520] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:58.520] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:54:0) after 1m0s
[00:12:58.562] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:54:0)
[00:12:58.562] Timer tick, asking Buildbucket for the build status
[00:12:59.713] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:59.713] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:55:0) after 1m0s
[00:13:59.777] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:55:0)
[00:13:59.777] Timer tick, asking Buildbucket for the build status
[00:14:01.431] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:01.431] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:56:0) after 1m0s
[00:15:01.487] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:56:0)
[00:15:01.487] Timer tick, asking Buildbucket for the build status
[00:15:02.415] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:02.415] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:57:0) after 1m0s
[00:16:02.420] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:57:0)
[00:16:02.420] Timer tick, asking Buildbucket for the build status
[00:16:03.821] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:03.821] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:58:0) after 1m0s
[00:17:03.853] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:58:0)
[00:17:03.853] Timer tick, asking Buildbucket for the build status
[00:17:04.155] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:04.155] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:59:0) after 1m0s
[00:18:04.209] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:59:0)
[00:18:04.209] Timer tick, asking Buildbucket for the build status
[00:18:04.882] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:04.882] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:60:0) after 1m0s
[00:19:04.932] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:60:0)
[00:19:04.932] Timer tick, asking Buildbucket for the build status
[00:19:06.481] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:06.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:61:0) after 1m0s
[00:20:06.532] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:61:0)
[00:20:06.532] Timer tick, asking Buildbucket for the build status
[00:20:07.140] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:07.140] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:62:0) after 1m0s
[00:21:07.223] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:62:0)
[00:21:07.223] Timer tick, asking Buildbucket for the build status
[00:21:07.707] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:07.707] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:63:0) after 1m0s
[00:22:07.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:63:0)
[00:22:07.705] Timer tick, asking Buildbucket for the build status
[00:22:08.577] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:08.577] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:64:0) after 1m0s
[00:23:08.686] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:64:0)
[00:23:08.686] Timer tick, asking Buildbucket for the build status
[00:23:11.290] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:11.290] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:65:0) after 1m0s
[00:24:11.357] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:65:0)
[00:24:11.357] Timer tick, asking Buildbucket for the build status
[00:24:12.727] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:12.727] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:66:0) after 1m0s
[00:25:12.829] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:66:0)
[00:25:12.829] Timer tick, asking Buildbucket for the build status
[00:25:15.985] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:15.985] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:67:0) after 1m0s
[00:26:16.039] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:67:0)
[00:26:16.039] Timer tick, asking Buildbucket for the build status
[00:26:16.715] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:16.715] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:68:0) after 1m0s
[00:27:16.756] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:68:0)
[00:27:16.756] Timer tick, asking Buildbucket for the build status
[00:27:17.210] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:17.210] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:69:0) after 1m0s
[00:28:17.256] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:69:0)
[00:28:17.256] Timer tick, asking Buildbucket for the build status
[00:28:19.060] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:19.060] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:70:0) after 1m0s
[00:29:19.179] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:70:0)
[00:29:19.179] Timer tick, asking Buildbucket for the build status
[00:29:20.074] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:20.074] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:71:0) after 1m0s
[00:30:20.191] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:71:0)
[00:30:20.191] Timer tick, asking Buildbucket for the build status
[00:30:20.413] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:20.413] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:72:0) after 1m0s
[00:31:20.469] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:72:0)
[00:31:20.469] Timer tick, asking Buildbucket for the build status
[00:31:21.590] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:21.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:73:0) after 1m0s
[00:32:21.629] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:73:0)
[00:32:21.629] Timer tick, asking Buildbucket for the build status
[00:32:23.723] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:23.723] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:74:0) after 1m0s
[00:33:23.746] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:74:0)
[00:33:23.746] Timer tick, asking Buildbucket for the build status
[00:33:23.893] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:23.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:75:0) after 1m0s
[00:34:24.097] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:75:0)
[00:34:24.097] Timer tick, asking Buildbucket for the build status
[00:34:24.590] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:24.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:76:0) after 1m0s
[00:35:24.679] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:76:0)
[00:35:24.679] Timer tick, asking Buildbucket for the build status
[00:35:26.624] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:26.624] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:77:0) after 1m0s
[00:36:26.629] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:77:0)
[00:36:26.629] Timer tick, asking Buildbucket for the build status
[00:36:26.774] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:26.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:78:0) after 1m0s
[00:37:26.838] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:78:0)
[00:37:26.838] Timer tick, asking Buildbucket for the build status
[00:37:27.521] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:27.521] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:79:0) after 1m0s
[00:38:27.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:79:0)
[00:38:27.528] Timer tick, asking Buildbucket for the build status
[00:38:28.488] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:28.488] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:80:0) after 1m0s
[00:39:28.610] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:80:0)
[00:39:28.610] Timer tick, asking Buildbucket for the build status
[00:39:29.414] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:29.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:81:0) after 1m0s
[00:40:29.448] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:81:0)
[00:40:29.448] Timer tick, asking Buildbucket for the build status
[00:40:29.625] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:29.625] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:82:0) after 1m0s
[00:41:29.703] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:82:0)
[00:41:29.703] Timer tick, asking Buildbucket for the build status
[00:41:29.868] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:29.868] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:83:0) after 1m0s
[00:42:29.958] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:83:0)
[00:42:29.958] Timer tick, asking Buildbucket for the build status
[00:42:31.244] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:31.244] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:84:0) after 1m0s
[00:43:31.314] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:84:0)
[00:43:31.314] Timer tick, asking Buildbucket for the build status
[00:43:32.885] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:32.885] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:85:0) after 1m0s
[00:44:32.900] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:85:0)
[00:44:32.900] Timer tick, asking Buildbucket for the build status
[00:44:35.041] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:35.041] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:86:0) after 1m0s
[00:45:35.148] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:86:0)
[00:45:35.148] Timer tick, asking Buildbucket for the build status
[00:45:35.891] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:35.891] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:87:0) after 1m0s
[00:46:35.971] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:87:0)
[00:46:35.971] Timer tick, asking Buildbucket for the build status
[00:46:38.106] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:38.106] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:88:0) after 1m0s
[00:47:38.153] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:88:0)
[00:47:38.153] Timer tick, asking Buildbucket for the build status
[00:47:38.389] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:38.389] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:89:0) after 1m0s
[00:48:38.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:89:0)
[00:48:38.417] Timer tick, asking Buildbucket for the build status
[00:48:38.675] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:38.675] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:90:0) after 1m0s
[00:49:38.716] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:90:0)
[00:49:38.716] Timer tick, asking Buildbucket for the build status
[00:49:38.827] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:38.827] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:91:0) after 1m0s
[00:50:38.843] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:91:0)
[00:50:38.843] Timer tick, asking Buildbucket for the build status
[00:50:39.194] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:39.194] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:92:0) after 1m0s
[00:51:39.243] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:92:0)
[00:51:39.243] Timer tick, asking Buildbucket for the build status
[00:51:40.921] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:40.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:93:0) after 1m0s
[00:52:40.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:93:0)
[00:52:40.927] Timer tick, asking Buildbucket for the build status
[00:52:41.106] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:41.106] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:94:0) after 1m0s
[00:53:41.120] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:94:0)
[00:53:41.120] Timer tick, asking Buildbucket for the build status
[00:53:42.660] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:42.660] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:95:0) after 1m0s
[00:54:42.690] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:95:0)
[00:54:42.690] Timer tick, asking Buildbucket for the build status
[00:54:43.414] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:43.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:96:0) after 1m0s
[00:55:43.465] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:96:0)
[00:55:43.465] Timer tick, asking Buildbucket for the build status
[00:55:43.602] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:43.602] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:97:0) after 1m0s
[00:56:43.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:97:0)
[00:56:43.637] Timer tick, asking Buildbucket for the build status
[00:56:43.861] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:43.861] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:98:0) after 1m0s
[00:57:46.999] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:98:0)
[00:57:46.999] Timer tick, asking Buildbucket for the build status
[00:57:47.324] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:47.324] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:99:0) after 1m0s
[00:58:47.443] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:99:0)
[00:58:47.443] Timer tick, asking Buildbucket for the build status
[00:58:48.698] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:48.698] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:100:0) after 1m0s
[00:59:48.987] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:100:0)
[00:59:48.987] Timer tick, asking Buildbucket for the build status
[00:59:49.370] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:49.370] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:101:0) after 1m0s
[01:00:49.489] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:101:0)
[01:00:49.489] Timer tick, asking Buildbucket for the build status
[01:00:49.783] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:49.783] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:102:0) after 1m0s
[01:01:49.889] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:102:0)
[01:01:49.889] Timer tick, asking Buildbucket for the build status
[01:01:50.397] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:50.397] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:103:0) after 1m0s
[01:02:50.421] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:103:0)
[01:02:50.421] Timer tick, asking Buildbucket for the build status
[01:02:50.545] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:50.545] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:104:0) after 1m0s
[01:03:50.722] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:104:0)
[01:03:50.722] Timer tick, asking Buildbucket for the build status
[01:03:51.676] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:51.676] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:105:0) after 1m0s
[01:04:51.794] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:105:0)
[01:04:51.794] Timer tick, asking Buildbucket for the build status
[01:04:52.198] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:52.198] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:106:0) after 1m0s
[01:05:52.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:106:0)
[01:05:52.869] Timer tick, asking Buildbucket for the build status
[01:05:52.964] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:52.964] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:107:0) after 1m0s
[01:06:53.317] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:107:0)
[01:06:53.317] Timer tick, asking Buildbucket for the build status
[01:06:54.107] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:54.107] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:108:0) after 1m0s
[01:07:54.520] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:108:0)
[01:07:54.520] Timer tick, asking Buildbucket for the build status
[01:07:55.409] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:55.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:109:0) after 1m0s
[01:08:55.693] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:109:0)
[01:08:55.693] Timer tick, asking Buildbucket for the build status
[01:08:55.922] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:55.922] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:110:0) after 1m0s
[01:09:56.074] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:110:0)
[01:09:56.074] Timer tick, asking Buildbucket for the build status
[01:09:56.340] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:56.340] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:111:0) after 1m0s
[01:10:56.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:111:0)
[01:10:56.495] Timer tick, asking Buildbucket for the build status
[01:10:56.880] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:56.880] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:112:0) after 1m0s
[01:11:56.958] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:112:0)
[01:11:56.958] Timer tick, asking Buildbucket for the build status
[01:11:57.110] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:57.110] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:113:0) after 1m0s
[01:12:57.333] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:113:0)
[01:12:57.333] Timer tick, asking Buildbucket for the build status
[01:12:57.441] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:57.441] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:114:0) after 1m0s
[01:13:57.551] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:114:0)
[01:13:57.551] Timer tick, asking Buildbucket for the build status
[01:13:59.255] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:59.255] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:115:0) after 1m0s
[01:14:59.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:115:0)
[01:14:59.377] Timer tick, asking Buildbucket for the build status
[01:14:59.558] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:59.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:116:0) after 1m0s
[01:15:59.662] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:116:0)
[01:15:59.662] Timer tick, asking Buildbucket for the build status
[01:16:00.750] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:00.750] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:117:0) after 1m0s
[01:17:00.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:117:0)
[01:17:00.763] Timer tick, asking Buildbucket for the build status
[01:17:01.231] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:01.231] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:118:0) after 1m0s
[01:18:01.404] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:118:0)
[01:18:01.404] Timer tick, asking Buildbucket for the build status
[01:18:01.968] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:01.968] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:119:0) after 1m0s
[01:19:02.012] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:119:0)
[01:19:02.012] Timer tick, asking Buildbucket for the build status
[01:19:03.070] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:03.070] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:120:0) after 1m0s
[01:20:03.124] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:120:0)
[01:20:03.124] Timer tick, asking Buildbucket for the build status
[01:20:06.364] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:06.364] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:121:0) after 1m0s
[01:21:06.438] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:121:0)
[01:21:06.439] Timer tick, asking Buildbucket for the build status
[01:21:06.585] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:06.585] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:122:0) after 1m0s
[01:22:06.770] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:122:0)
[01:22:06.770] Timer tick, asking Buildbucket for the build status
[01:22:06.886] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:06.886] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:123:0) after 1m0s
[01:23:07.035] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:123:0)
[01:23:07.035] Timer tick, asking Buildbucket for the build status
[01:23:07.679] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:07.679] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:124:0) after 1m0s
[01:24:07.728] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:124:0)
[01:24:07.728] Timer tick, asking Buildbucket for the build status
[01:24:07.857] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:07.857] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:125:0) after 1m0s
[01:25:07.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:125:0)
[01:25:07.980] Timer tick, asking Buildbucket for the build status
[01:25:08.739] Build 8912626940190134464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:08.739] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078057004370824768:126:0) after 1m0s
[01:25:54.433] Received PubSub notification, asking Buildbucket for the build status
[01:25:54.600] Build 8912626940190134464: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:25:54.600] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1558661151162006",
"created_by": "project:chromium",
"created_ts": "1558653617638622",
"id": "8912626940190134464",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"63b914e26216d05bf52a1de891cb872f12379bc1\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"windows-deterministic-0-7052\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 13513, \"got_angle_revision\": \"6bb193c86a9a3242227012d17a267e3d71ee3fe0\", \"got_dawn_revision\": \"eea2091068037c41e52164f100802302768b674d\", \"got_nacl_revision\": \"4744a43b6fc4aaa5230541efad113b3cd86ea0f8\", \"got_revision\": \"63b914e26216d05bf52a1de891cb872f12379bc1\", \"got_revision_cp\": \"refs/heads/master@{#662884}\", \"got_swarming_client_revision\": \"779c4f0f8488c64587b75dbb001d18c3c0c4cda9\", \"got_v8_revision\": \"bffe9927669b41ad07e1a14bba243c4d4599e9c2\", \"got_v8_revision_cp\": \"refs/heads/7.6.267@{#1}\", \"got_webrtc_revision\": \"4163317283bea03c9eb1edfe0c1f5fe6029b8704\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28045}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"63b914e26216d05bf52a1de891cb872f12379bc1\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Windows deterministic\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"windows-deterministic-0-7052\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4303-bae210e\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1558653622757884",
"status": "COMPLETED",
"status_changed_ts": "1558661153977877",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13513",
"builder:Windows deterministic",
"buildset:commit/git/63b914e26216d05bf52a1de891cb872f12379bc1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/63b914e26216d05bf52a1de891cb872f12379bc1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078057004370824768",
"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/8912626940190134464/+/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:44ffd05c91375c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1558661153994774",
"url": "https://ci.chromium.org/b/8912626940190134464",
"utcnow_ts": "1558661154591199"
}
}
[01:25:54.600] Invocation finished in 2h5m40.332502291s with status SUCCEEDED