[13:39:58.739] New invocation is queued and will start shortly
[13:39:59.870] Starting the invocation (attempt 1)
[13:39:59.972] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:39:59.972] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:39:59.972] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9061423669305921072",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c83e9071b72c2fc0cfa2538a066f52cd648a2401\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9061423669305921072",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/c83e9071b72c2fc0cfa2538a066f52cd648a2401",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c83e9071b72c2fc0cfa2538a066f52cd648a2401",
"gitiles_ref:refs/heads/master"
]
}
[13:40:02.649] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1574516401708737",
"id": "8895993605520833184",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c83e9071b72c2fc0cfa2538a066f52cd648a2401\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1574516402448473",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/15277",
"builder:Windows deterministic",
"buildset:commit/git/c83e9071b72c2fc0cfa2538a066f52cd648a2401",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c83e9071b72c2fc0cfa2538a066f52cd648a2401",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061423669305921072",
"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/8895993605520833184/+/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": "1574516402448588",
"url": "https://ci.chromium.org/b/8895993605520833184",
"utcnow_ts": "1574516402633657"
}
}
[13:40:02.649] Task URL: https://ci.chromium.org/b/8895993605520833184
[13:40:02.649] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:2:0) after 1m0s
[13:40:08.333] Received PubSub notification, asking Buildbucket for the build status
[13:40:08.505] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:02.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:2:0)
[13:41:02.792] Timer tick, asking Buildbucket for the build status
[13:41:02.980] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:02.980] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:4:0) after 1m0s
[13:42:02.976] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:4:0)
[13:42:02.976] Timer tick, asking Buildbucket for the build status
[13:42:03.523] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:03.523] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:5:0) after 1m0s
[13:43:03.523] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:5:0)
[13:43:03.523] Timer tick, asking Buildbucket for the build status
[13:43:03.992] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:03.992] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:6:0) after 1m0s
[13:44:04.120] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:6:0)
[13:44:04.120] Timer tick, asking Buildbucket for the build status
[13:44:04.626] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:04.626] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:7:0) after 1m0s
[13:45:04.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:7:0)
[13:45:04.792] Timer tick, asking Buildbucket for the build status
[13:45:05.074] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:05.074] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:8:0) after 1m0s
[13:46:05.189] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:8:0)
[13:46:05.189] Timer tick, asking Buildbucket for the build status
[13:46:06.114] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:06.114] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:9:0) after 1m0s
[13:47:06.189] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:9:0)
[13:47:06.190] Timer tick, asking Buildbucket for the build status
[13:47:06.842] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:06.842] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:10:0) after 1m0s
[13:48:06.882] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:10:0)
[13:48:06.882] Timer tick, asking Buildbucket for the build status
[13:48:07.147] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:07.147] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:11:0) after 1m0s
[13:49:07.169] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:11:0)
[13:49:07.169] Timer tick, asking Buildbucket for the build status
[13:49:07.379] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:07.379] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:12:0) after 1m0s
[13:50:07.422] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:12:0)
[13:50:07.422] Timer tick, asking Buildbucket for the build status
[13:50:08.000] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:08.000] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:13:0) after 1m0s
[13:51:08.079] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:13:0)
[13:51:08.079] Timer tick, asking Buildbucket for the build status
[13:51:08.364] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:08.364] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:14:0) after 1m0s
[13:52:08.511] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:14:0)
[13:52:08.511] Timer tick, asking Buildbucket for the build status
[13:52:08.776] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:08.776] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:15:0) after 1m0s
[13:53:08.867] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:15:0)
[13:53:08.867] Timer tick, asking Buildbucket for the build status
[13:53:09.438] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:09.438] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:16:0) after 1m0s
[13:54:09.450] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:16:0)
[13:54:09.450] Timer tick, asking Buildbucket for the build status
[13:54:09.800] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:09.800] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:17:0) after 1m0s
[13:55:09.882] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:17:0)
[13:55:09.882] Timer tick, asking Buildbucket for the build status
[13:55:10.153] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:10.153] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:18:0) after 1m0s
[13:56:10.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:18:0)
[13:56:10.202] Timer tick, asking Buildbucket for the build status
[13:56:10.852] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:10.852] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:19:0) after 1m0s
[13:57:10.924] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:19:0)
[13:57:10.924] Timer tick, asking Buildbucket for the build status
[13:57:11.084] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:11.084] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:20:0) after 1m0s
[13:58:11.111] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:20:0)
[13:58:11.111] Timer tick, asking Buildbucket for the build status
[13:58:11.597] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:11.597] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:21:0) after 1m0s
[13:59:11.770] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:21:0)
[13:59:11.770] Timer tick, asking Buildbucket for the build status
[13:59:12.012] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:12.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:22:0) after 1m0s
[14:00:12.115] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:22:0)
[14:00:12.115] Timer tick, asking Buildbucket for the build status
[14:00:13.805] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:13.805] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:23:0) after 1m0s
[14:01:13.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:23:0)
[14:01:13.886] Timer tick, asking Buildbucket for the build status
[14:01:14.139] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:14.139] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:24:0) after 1m0s
[14:02:14.172] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:24:0)
[14:02:14.172] Timer tick, asking Buildbucket for the build status
[14:02:14.624] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:14.624] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:25:0) after 1m0s
[14:03:14.647] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:25:0)
[14:03:14.647] Timer tick, asking Buildbucket for the build status
[14:03:15.500] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:15.500] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:26:0) after 1m0s
[14:04:15.566] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:26:0)
[14:04:15.566] Timer tick, asking Buildbucket for the build status
[14:04:15.781] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:15.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:27:0) after 1m0s
[14:05:15.877] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:27:0)
[14:05:15.877] Timer tick, asking Buildbucket for the build status
[14:05:16.303] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:16.303] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:28:0) after 1m0s
[14:06:16.374] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:28:0)
[14:06:16.374] Timer tick, asking Buildbucket for the build status
[14:06:16.618] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:16.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:29:0) after 1m0s
[14:07:16.725] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:29:0)
[14:07:16.725] Timer tick, asking Buildbucket for the build status
[14:07:17.150] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:17.150] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:30:0) after 1m0s
[14:08:17.217] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:30:0)
[14:08:17.217] Timer tick, asking Buildbucket for the build status
[14:08:17.410] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:17.410] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:31:0) after 1m0s
[14:09:17.530] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:31:0)
[14:09:17.530] Timer tick, asking Buildbucket for the build status
[14:09:17.749] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:17.749] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:32:0) after 1m0s
[14:10:17.830] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:32:0)
[14:10:17.830] Timer tick, asking Buildbucket for the build status
[14:10:18.563] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:18.563] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:33:0) after 1m0s
[14:11:18.741] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:33:0)
[14:11:18.741] Timer tick, asking Buildbucket for the build status
[14:11:18.943] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:18.943] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:34:0) after 1m0s
[14:12:19.026] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:34:0)
[14:12:19.026] Timer tick, asking Buildbucket for the build status
[14:12:19.241] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:19.241] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:35:0) after 1m0s
[14:13:19.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:35:0)
[14:13:19.273] Timer tick, asking Buildbucket for the build status
[14:13:19.617] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:19.617] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:36:0) after 1m0s
[14:14:19.653] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:36:0)
[14:14:19.653] Timer tick, asking Buildbucket for the build status
[14:14:20.144] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:20.144] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:37:0) after 1m0s
[14:15:20.235] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:37:0)
[14:15:20.235] Timer tick, asking Buildbucket for the build status
[14:15:20.413] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:20.413] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:38:0) after 1m0s
[14:16:20.459] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:38:0)
[14:16:20.459] Timer tick, asking Buildbucket for the build status
[14:16:20.620] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:20.620] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:39:0) after 1m0s
[14:17:20.647] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:39:0)
[14:17:20.647] Timer tick, asking Buildbucket for the build status
[14:17:21.042] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:21.042] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:40:0) after 1m0s
[14:18:21.113] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:40:0)
[14:18:21.113] Timer tick, asking Buildbucket for the build status
[14:18:21.711] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:21.711] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:41:0) after 1m0s
[14:19:21.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:41:0)
[14:19:21.792] Timer tick, asking Buildbucket for the build status
[14:19:22.020] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:22.020] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:42:0) after 1m0s
[14:20:22.140] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:42:0)
[14:20:22.140] Timer tick, asking Buildbucket for the build status
[14:20:22.648] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:22.648] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:43:0) after 1m0s
[14:21:22.695] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:43:0)
[14:21:22.695] Timer tick, asking Buildbucket for the build status
[14:21:23.404] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:23.404] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:44:0) after 1m0s
[14:22:23.486] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:44:0)
[14:22:23.486] Timer tick, asking Buildbucket for the build status
[14:22:24.441] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:24.441] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:45:0) after 1m0s
[14:23:24.521] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:45:0)
[14:23:24.521] Timer tick, asking Buildbucket for the build status
[14:23:25.927] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:25.927] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:46:0) after 1m0s
[14:24:25.947] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:46:0)
[14:24:25.947] Timer tick, asking Buildbucket for the build status
[14:24:26.102] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:26.102] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:47:0) after 1m0s
[14:25:26.163] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:47:0)
[14:25:26.163] Timer tick, asking Buildbucket for the build status
[14:25:26.348] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:26.348] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:48:0) after 1m0s
[14:26:26.419] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:48:0)
[14:26:26.419] Timer tick, asking Buildbucket for the build status
[14:26:26.664] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:26.665] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:49:0) after 1m0s
[14:27:26.697] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:49:0)
[14:27:26.697] Timer tick, asking Buildbucket for the build status
[14:27:27.227] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:27.227] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:50:0) after 1m0s
[14:28:27.309] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:50:0)
[14:28:27.309] Timer tick, asking Buildbucket for the build status
[14:28:27.848] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:27.848] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:51:0) after 1m0s
[14:29:27.992] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:51:0)
[14:29:27.992] Timer tick, asking Buildbucket for the build status
[14:29:28.646] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:28.646] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:52:0) after 1m0s
[14:30:29.031] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:52:0)
[14:30:29.031] Timer tick, asking Buildbucket for the build status
[14:30:29.242] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:29.242] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:53:0) after 1m0s
[14:31:29.390] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:53:0)
[14:31:29.390] Timer tick, asking Buildbucket for the build status
[14:31:29.570] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:29.570] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:54:0) after 1m0s
[14:32:29.697] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:54:0)
[14:32:29.697] Timer tick, asking Buildbucket for the build status
[14:32:29.873] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:29.873] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:55:0) after 1m0s
[14:33:30.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:55:0)
[14:33:30.045] Timer tick, asking Buildbucket for the build status
[14:33:30.615] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:30.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:56:0) after 1m0s
[14:34:30.664] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:56:0)
[14:34:30.664] Timer tick, asking Buildbucket for the build status
[14:34:31.265] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:31.265] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:57:0) after 1m0s
[14:35:31.375] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:57:0)
[14:35:31.375] Timer tick, asking Buildbucket for the build status
[14:35:32.033] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:32.033] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:58:0) after 1m0s
[14:36:32.116] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:58:0)
[14:36:32.116] Timer tick, asking Buildbucket for the build status
[14:36:32.294] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:32.294] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:59:0) after 1m0s
[14:37:32.407] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:59:0)
[14:37:32.408] Timer tick, asking Buildbucket for the build status
[14:37:32.844] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:32.844] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:60:0) after 1m0s
[14:38:33.029] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:60:0)
[14:38:33.029] Timer tick, asking Buildbucket for the build status
[14:38:34.774] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:34.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:61:0) after 1m0s
[14:39:34.811] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:61:0)
[14:39:34.811] Timer tick, asking Buildbucket for the build status
[14:39:35.004] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:35.005] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:62:0) after 1m0s
[14:40:35.106] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:62:0)
[14:40:35.106] Timer tick, asking Buildbucket for the build status
[14:40:37.048] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:37.048] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:63:0) after 1m0s
[14:41:37.118] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:63:0)
[14:41:37.118] Timer tick, asking Buildbucket for the build status
[14:41:37.331] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:37.331] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:64:0) after 1m0s
[14:42:37.356] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:64:0)
[14:42:37.356] Timer tick, asking Buildbucket for the build status
[14:42:37.499] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:37.499] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:65:0) after 1m0s
[14:43:37.539] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:65:0)
[14:43:37.539] Timer tick, asking Buildbucket for the build status
[14:43:39.442] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:39.442] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:66:0) after 1m0s
[14:44:39.531] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:66:0)
[14:44:39.531] Timer tick, asking Buildbucket for the build status
[14:44:39.680] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:39.680] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:67:0) after 1m0s
[14:45:39.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:67:0)
[14:45:39.763] Timer tick, asking Buildbucket for the build status
[14:45:40.729] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:40.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:68:0) after 1m0s
[14:46:40.819] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:68:0)
[14:46:40.819] Timer tick, asking Buildbucket for the build status
[14:46:41.436] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:41.436] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:69:0) after 1m0s
[14:47:41.516] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:69:0)
[14:47:41.516] Timer tick, asking Buildbucket for the build status
[14:47:41.848] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:41.848] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:70:0) after 1m0s
[14:48:41.926] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:70:0)
[14:48:41.926] Timer tick, asking Buildbucket for the build status
[14:48:42.153] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:42.153] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:71:0) after 1m0s
[14:49:42.198] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:71:0)
[14:49:42.198] Timer tick, asking Buildbucket for the build status
[14:49:42.348] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:42.348] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:72:0) after 1m0s
[14:50:42.440] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:72:0)
[14:50:42.440] Timer tick, asking Buildbucket for the build status
[14:50:42.612] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:42.612] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:73:0) after 1m0s
[14:51:42.690] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:73:0)
[14:51:42.690] Timer tick, asking Buildbucket for the build status
[14:51:43.225] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:43.225] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:74:0) after 1m0s
[14:52:43.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:74:0)
[14:52:43.277] Timer tick, asking Buildbucket for the build status
[14:52:43.988] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:43.988] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:75:0) after 1m0s
[14:53:44.016] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:75:0)
[14:53:44.016] Timer tick, asking Buildbucket for the build status
[14:53:44.225] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:44.226] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:76:0) after 1m0s
[14:54:44.245] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:76:0)
[14:54:44.245] Timer tick, asking Buildbucket for the build status
[14:54:44.430] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:44.430] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:77:0) after 1m0s
[14:55:44.459] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:77:0)
[14:55:44.459] Timer tick, asking Buildbucket for the build status
[14:55:45.115] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:45.115] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:78:0) after 1m0s
[14:56:45.237] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:78:0)
[14:56:45.237] Timer tick, asking Buildbucket for the build status
[14:56:45.451] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:45.451] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:79:0) after 1m0s
[14:57:45.498] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:79:0)
[14:57:45.498] Timer tick, asking Buildbucket for the build status
[14:57:45.998] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:45.998] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:80:0) after 1m0s
[14:58:46.030] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:80:0)
[14:58:46.031] Timer tick, asking Buildbucket for the build status
[14:58:47.031] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:47.031] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:81:0) after 1m0s
[14:59:47.125] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:81:0)
[14:59:47.125] Timer tick, asking Buildbucket for the build status
[14:59:48.055] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:48.055] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:82:0) after 1m0s
[15:00:48.099] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:82:0)
[15:00:48.099] Timer tick, asking Buildbucket for the build status
[15:00:48.261] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:48.261] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:83:0) after 1m0s
[15:01:48.312] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:83:0)
[15:01:48.312] Timer tick, asking Buildbucket for the build status
[15:01:49.638] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:49.638] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:84:0) after 1m0s
[15:02:49.681] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:84:0)
[15:02:49.681] Timer tick, asking Buildbucket for the build status
[15:02:50.008] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:50.008] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:85:0) after 1m0s
[15:03:50.035] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:85:0)
[15:03:50.035] Timer tick, asking Buildbucket for the build status
[15:03:50.258] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:50.263] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:86:0) after 1m0s
[15:04:50.309] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:86:0)
[15:04:50.309] Timer tick, asking Buildbucket for the build status
[15:04:51.215] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:51.215] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:87:0) after 1m0s
[15:05:51.285] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:87:0)
[15:05:51.285] Timer tick, asking Buildbucket for the build status
[15:05:51.519] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:51.519] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:88:0) after 1m0s
[15:06:51.600] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:88:0)
[15:06:51.600] Timer tick, asking Buildbucket for the build status
[15:06:52.017] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:52.017] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:89:0) after 1m0s
[15:07:52.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:89:0)
[15:07:52.087] Timer tick, asking Buildbucket for the build status
[15:07:52.406] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:52.406] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:90:0) after 1m0s
[15:08:52.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:90:0)
[15:08:52.467] Timer tick, asking Buildbucket for the build status
[15:08:53.231] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:53.231] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:91:0) after 1m0s
[15:09:53.413] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:91:0)
[15:09:53.413] Timer tick, asking Buildbucket for the build status
[15:09:53.653] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:53.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:92:0) after 1m0s
[15:10:53.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:92:0)
[15:10:53.708] Timer tick, asking Buildbucket for the build status
[15:10:53.893] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:53.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:93:0) after 1m0s
[15:11:53.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:93:0)
[15:11:53.927] Timer tick, asking Buildbucket for the build status
[15:11:54.338] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:54.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:94:0) after 1m0s
[15:12:54.435] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:94:0)
[15:12:54.435] Timer tick, asking Buildbucket for the build status
[15:12:54.571] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:54.571] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:95:0) after 1m0s
[15:13:54.619] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:95:0)
[15:13:54.619] Timer tick, asking Buildbucket for the build status
[15:13:54.824] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:54.824] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:96:0) after 1m0s
[15:14:54.880] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:96:0)
[15:14:54.880] Timer tick, asking Buildbucket for the build status
[15:14:55.037] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:55.037] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:97:0) after 1m0s
[15:15:55.096] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:97:0)
[15:15:55.096] Timer tick, asking Buildbucket for the build status
[15:15:55.268] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:55.268] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:98:0) after 1m0s
[15:16:55.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:98:0)
[15:16:55.308] Timer tick, asking Buildbucket for the build status
[15:16:55.510] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:55.510] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:99:0) after 1m0s
[15:17:55.587] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:99:0)
[15:17:55.587] Timer tick, asking Buildbucket for the build status
[15:17:56.274] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:56.274] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:100:0) after 1m0s
[15:18:56.317] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:100:0)
[15:18:56.317] Timer tick, asking Buildbucket for the build status
[15:18:57.024] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:57.024] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:101:0) after 1m0s
[15:19:57.074] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:101:0)
[15:19:57.074] Timer tick, asking Buildbucket for the build status
[15:19:57.488] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:57.488] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:102:0) after 1m0s
[15:20:57.594] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:102:0)
[15:20:57.594] Timer tick, asking Buildbucket for the build status
[15:20:58.269] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:58.269] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:103:0) after 1m0s
[15:21:58.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:103:0)
[15:21:58.308] Timer tick, asking Buildbucket for the build status
[15:21:58.542] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:58.542] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:104:0) after 1m0s
[15:22:58.571] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:104:0)
[15:22:58.571] Timer tick, asking Buildbucket for the build status
[15:22:58.755] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:58.756] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:105:0) after 1m0s
[15:23:58.851] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:105:0)
[15:23:58.851] Timer tick, asking Buildbucket for the build status
[15:23:58.999] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:59.001] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:106:0) after 1m0s
[15:24:59.127] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:106:0)
[15:24:59.127] Timer tick, asking Buildbucket for the build status
[15:24:59.447] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:59.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:107:0) after 1m0s
[15:25:59.608] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:107:0)
[15:25:59.608] Timer tick, asking Buildbucket for the build status
[15:25:59.803] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:59.803] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:108:0) after 1m0s
[15:26:59.974] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:108:0)
[15:26:59.974] Timer tick, asking Buildbucket for the build status
[15:27:00.344] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:00.344] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:109:0) after 1m0s
[15:28:00.372] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:109:0)
[15:28:00.372] Timer tick, asking Buildbucket for the build status
[15:28:00.693] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:00.693] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:110:0) after 1m0s
[15:29:00.749] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:110:0)
[15:29:00.749] Timer tick, asking Buildbucket for the build status
[15:29:01.437] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:01.437] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:111:0) after 1m0s
[15:30:01.488] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:111:0)
[15:30:01.488] Timer tick, asking Buildbucket for the build status
[15:30:01.900] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:01.900] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:112:0) after 1m0s
[15:31:01.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:112:0)
[15:31:01.927] Timer tick, asking Buildbucket for the build status
[15:31:02.116] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:02.116] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:113:0) after 1m0s
[15:32:02.188] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:113:0)
[15:32:02.188] Timer tick, asking Buildbucket for the build status
[15:32:02.344] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:02.344] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:114:0) after 1m0s
[15:33:02.490] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:114:0)
[15:33:02.490] Timer tick, asking Buildbucket for the build status
[15:33:02.802] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:02.802] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:115:0) after 1m0s
[15:34:02.813] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:115:0)
[15:34:02.813] Timer tick, asking Buildbucket for the build status
[15:34:03.624] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:03.624] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:116:0) after 1m0s
[15:35:03.665] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:116:0)
[15:35:03.665] Timer tick, asking Buildbucket for the build status
[15:35:03.816] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:03.816] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:117:0) after 1m0s
[15:36:03.892] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:117:0)
[15:36:03.892] Timer tick, asking Buildbucket for the build status
[15:36:04.243] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:04.243] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:118:0) after 1m0s
[15:37:04.267] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:118:0)
[15:37:04.267] Timer tick, asking Buildbucket for the build status
[15:37:04.746] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:04.746] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:119:0) after 1m0s
[15:38:04.804] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:119:0)
[15:38:04.804] Timer tick, asking Buildbucket for the build status
[15:38:05.393] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:05.393] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:120:0) after 1m0s
[15:39:05.535] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:120:0)
[15:39:05.535] Timer tick, asking Buildbucket for the build status
[15:39:05.715] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:05.715] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:121:0) after 1m0s
[15:40:05.765] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:121:0)
[15:40:05.765] Timer tick, asking Buildbucket for the build status
[15:40:05.969] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:05.969] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:122:0) after 1m0s
[15:41:06.163] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:122:0)
[15:41:06.163] Timer tick, asking Buildbucket for the build status
[15:41:06.408] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:06.408] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:123:0) after 1m0s
[15:42:06.463] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:123:0)
[15:42:06.463] Timer tick, asking Buildbucket for the build status
[15:42:06.712] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:06.712] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:124:0) after 1m0s
[15:43:06.735] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:124:0)
[15:43:06.735] Timer tick, asking Buildbucket for the build status
[15:43:07.174] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:07.174] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:125:0) after 1m0s
[15:44:07.279] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:125:0)
[15:44:07.279] Timer tick, asking Buildbucket for the build status
[15:44:07.699] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:07.699] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:126:0) after 1m0s
[15:45:07.894] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:126:0)
[15:45:07.894] Timer tick, asking Buildbucket for the build status
[15:45:08.059] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:08.059] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:127:0) after 1m0s
[15:46:08.144] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:127:0)
[15:46:08.144] Timer tick, asking Buildbucket for the build status
[15:46:08.338] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:08.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:128:0) after 1m0s
[15:47:08.564] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:128:0)
[15:47:08.564] Timer tick, asking Buildbucket for the build status
[15:47:08.721] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:08.721] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:129:0) after 1m0s
[15:48:09.218] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:129:0)
[15:48:09.218] Timer tick, asking Buildbucket for the build status
[15:48:10.007] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:10.007] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:130:0) after 1m0s
[15:49:10.038] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:130:0)
[15:49:10.038] Timer tick, asking Buildbucket for the build status
[15:49:10.722] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:10.722] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:131:0) after 1m0s
[15:50:10.814] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:131:0)
[15:50:10.814] Timer tick, asking Buildbucket for the build status
[15:50:11.054] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:11.054] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:132:0) after 1m0s
[15:51:11.126] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:132:0)
[15:51:11.126] Timer tick, asking Buildbucket for the build status
[15:51:13.202] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:13.202] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:133:0) after 1m0s
[15:52:13.262] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:133:0)
[15:52:13.262] Timer tick, asking Buildbucket for the build status
[15:52:14.242] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:14.242] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:134:0) after 1m0s
[15:53:14.305] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:134:0)
[15:53:14.305] Timer tick, asking Buildbucket for the build status
[15:53:14.528] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:14.528] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:135:0) after 1m0s
[15:54:14.618] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:135:0)
[15:54:14.618] Timer tick, asking Buildbucket for the build status
[15:54:14.830] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:14.830] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:136:0) after 1m0s
[15:55:14.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:136:0)
[15:55:14.869] Timer tick, asking Buildbucket for the build status
[15:55:15.024] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:15.024] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:137:0) after 1m0s
[15:56:15.055] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:137:0)
[15:56:15.055] Timer tick, asking Buildbucket for the build status
[15:56:15.478] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:15.478] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:138:0) after 1m0s
[15:57:15.552] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:138:0)
[15:57:15.552] Timer tick, asking Buildbucket for the build status
[15:57:15.754] Build 8895993605520833184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:15.754] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061423669305921072:139:0) after 1m0s
[15:58:07.707] Received PubSub notification, asking Buildbucket for the build status
[15:58:07.909] Build 8895993605520833184: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:58:07.909] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1574524686376357",
"created_by": "project:chromium",
"created_ts": "1574516401708737",
"id": "8895993605520833184",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c83e9071b72c2fc0cfa2538a066f52cd648a2401\"}}",
"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-am2f\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 15277, \"got_angle_revision\": \"e9b68f332a30f60ba722c511b7335a38da6fa009\", \"got_dawn_revision\": \"8e9c3f663ccd270b8f047afe668e7cbc15e11bba\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"c83e9071b72c2fc0cfa2538a066f52cd648a2401\", \"got_revision_cp\": \"refs/heads/master@{#718444}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"4584a9c2ccb9f2a7bc7cd3c55f5b95507901e035\", \"got_v8_revision\": \"30319dfe043b83a37b6ba1c601a838d6544d4ec6\", \"got_v8_revision_cp\": \"refs/heads/8.0.353@{#1}\", \"got_webrtc_revision\": \"e43b53126600d247fbfde8b5c29b22ee22e59c86\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29873}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c83e9071b72c2fc0cfa2538a066f52cd648a2401\"}, \"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-am2f\"], \"image\": [\"chrome-win10-1703-19102400-d35fedf5a0d\"], \"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\": [\"4651-c74e250\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1574516407527893",
"status": "COMPLETED",
"status_changed_ts": "1574524686858753",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/15277",
"builder:Windows deterministic",
"buildset:commit/git/c83e9071b72c2fc0cfa2538a066f52cd648a2401",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c83e9071b72c2fc0cfa2538a066f52cd648a2401",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061423669305921072",
"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/8895993605520833184/+/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:48b14f3274173c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1574524686915460",
"url": "https://ci.chromium.org/b/8895993605520833184",
"utcnow_ts": "1574524687892708"
}
}
[15:58:07.909] Invocation finished in 2h18m9.193636462s with status SUCCEEDED