[10:14:44.304] New invocation is queued and will start shortly
[10:14:45.403] Starting the invocation (attempt 1)
[10:14:45.436] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:14:45.436] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:14:45.436] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9071492845187944064",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"0a226e460c41b3c3acad3a42e19e04d94e0e2566\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9071492845187944064",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/0a226e460c41b3c3acad3a42e19e04d94e0e2566",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0a226e460c41b3c3acad3a42e19e04d94e0e2566",
"gitiles_ref:refs/heads/master"
]
}
[10:14:46.645] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1564913685781038",
"id": "8906062782976783776",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0a226e460c41b3c3acad3a42e19e04d94e0e2566\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1564913686497369",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14331",
"builder:Windows deterministic",
"buildset:commit/git/0a226e460c41b3c3acad3a42e19e04d94e0e2566",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0a226e460c41b3c3acad3a42e19e04d94e0e2566",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9071492845187944064",
"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/8906062782976783776/+/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": "1564913686497464",
"url": "https://ci.chromium.org/b/8906062782976783776",
"utcnow_ts": "1564913686638230"
}
}
[10:14:46.645] Task URL: https://ci.chromium.org/b/8906062782976783776
[10:14:46.645] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:2:0) after 1m0s
[10:14:48.999] Received PubSub notification, asking Buildbucket for the build status
[10:14:49.447] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:46.767] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:2:0)
[10:15:46.767] Timer tick, asking Buildbucket for the build status
[10:15:47.083] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:47.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:4:0) after 1m0s
[10:16:47.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:4:0)
[10:16:47.117] Timer tick, asking Buildbucket for the build status
[10:16:47.306] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:47.306] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:5:0) after 1m0s
[10:17:47.291] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:5:0)
[10:17:47.291] Timer tick, asking Buildbucket for the build status
[10:17:47.467] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:47.467] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:6:0) after 1m0s
[10:18:47.501] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:6:0)
[10:18:47.501] Timer tick, asking Buildbucket for the build status
[10:18:47.650] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:47.650] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:7:0) after 1m0s
[10:19:47.670] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:7:0)
[10:19:47.670] Timer tick, asking Buildbucket for the build status
[10:19:47.839] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:47.839] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:8:0) after 1m0s
[10:20:47.868] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:8:0)
[10:20:47.868] Timer tick, asking Buildbucket for the build status
[10:20:48.715] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:48.715] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:9:0) after 1m0s
[10:21:48.789] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:9:0)
[10:21:48.789] Timer tick, asking Buildbucket for the build status
[10:21:48.935] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:48.935] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:10:0) after 1m0s
[10:22:48.961] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:10:0)
[10:22:48.961] Timer tick, asking Buildbucket for the build status
[10:22:50.988] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:50.988] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:11:0) after 1m0s
[10:23:51.033] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:11:0)
[10:23:51.033] Timer tick, asking Buildbucket for the build status
[10:23:51.223] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:51.223] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:12:0) after 1m0s
[10:24:51.276] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:12:0)
[10:24:51.276] Timer tick, asking Buildbucket for the build status
[10:24:51.415] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:51.415] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:13:0) after 1m0s
[10:25:51.418] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:13:0)
[10:25:51.418] Timer tick, asking Buildbucket for the build status
[10:25:52.659] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:52.659] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:14:0) after 1m0s
[10:26:52.693] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:14:0)
[10:26:52.693] Timer tick, asking Buildbucket for the build status
[10:26:53.374] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:53.374] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:15:0) after 1m0s
[10:27:53.395] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:15:0)
[10:27:53.395] Timer tick, asking Buildbucket for the build status
[10:27:53.522] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:53.523] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:16:0) after 1m0s
[10:28:53.544] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:16:0)
[10:28:53.544] Timer tick, asking Buildbucket for the build status
[10:28:54.143] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:54.143] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:17:0) after 1m0s
[10:29:54.174] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:17:0)
[10:29:54.174] Timer tick, asking Buildbucket for the build status
[10:29:54.694] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:54.694] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:18:0) after 1m0s
[10:30:54.727] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:18:0)
[10:30:54.727] Timer tick, asking Buildbucket for the build status
[10:30:56.573] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:56.573] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:19:0) after 1m0s
[10:31:56.661] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:19:0)
[10:31:56.661] Timer tick, asking Buildbucket for the build status
[10:31:56.819] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:56.819] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:20:0) after 1m0s
[10:32:56.887] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:20:0)
[10:32:56.887] Timer tick, asking Buildbucket for the build status
[10:32:57.052] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:57.052] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:21:0) after 1m0s
[10:33:57.162] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:21:0)
[10:33:57.162] Timer tick, asking Buildbucket for the build status
[10:33:57.771] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:57.771] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:22:0) after 1m0s
[10:34:57.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:22:0)
[10:34:57.792] Timer tick, asking Buildbucket for the build status
[10:34:58.351] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:58.351] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:23:0) after 1m0s
[10:35:58.394] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:23:0)
[10:35:58.394] Timer tick, asking Buildbucket for the build status
[10:35:59.213] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:59.213] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:24:0) after 1m0s
[10:36:59.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:24:0)
[10:36:59.308] Timer tick, asking Buildbucket for the build status
[10:37:00.130] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:00.130] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:25:0) after 1m0s
[10:38:00.198] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:25:0)
[10:38:00.198] Timer tick, asking Buildbucket for the build status
[10:38:00.409] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:00.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:26:0) after 1m0s
[10:39:00.464] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:26:0)
[10:39:00.464] Timer tick, asking Buildbucket for the build status
[10:39:00.723] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:00.723] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:27:0) after 1m0s
[10:40:00.747] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:27:0)
[10:40:00.747] Timer tick, asking Buildbucket for the build status
[10:40:01.122] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:01.122] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:28:0) after 1m0s
[10:41:01.201] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:28:0)
[10:41:01.201] Timer tick, asking Buildbucket for the build status
[10:41:01.339] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:01.339] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:29:0) after 1m0s
[10:42:01.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:29:0)
[10:42:01.397] Timer tick, asking Buildbucket for the build status
[10:42:01.754] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:01.754] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:30:0) after 1m0s
[10:43:01.827] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:30:0)
[10:43:01.827] Timer tick, asking Buildbucket for the build status
[10:43:02.522] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:02.522] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:31:0) after 1m0s
[10:44:02.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:31:0)
[10:44:02.588] Timer tick, asking Buildbucket for the build status
[10:44:02.869] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:02.869] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:32:0) after 1m0s
[10:45:02.899] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:32:0)
[10:45:02.899] Timer tick, asking Buildbucket for the build status
[10:45:03.751] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:03.751] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:33:0) after 1m0s
[10:46:03.777] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:33:0)
[10:46:03.777] Timer tick, asking Buildbucket for the build status
[10:46:04.372] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:04.372] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:34:0) after 1m0s
[10:47:04.413] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:34:0)
[10:47:04.413] Timer tick, asking Buildbucket for the build status
[10:47:05.115] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:05.115] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:35:0) after 1m0s
[10:48:05.260] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:35:0)
[10:48:05.260] Timer tick, asking Buildbucket for the build status
[10:48:05.522] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:05.522] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:36:0) after 1m0s
[10:49:05.577] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:36:0)
[10:49:05.577] Timer tick, asking Buildbucket for the build status
[10:49:05.824] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:05.824] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:37:0) after 1m0s
[10:50:05.851] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:37:0)
[10:50:05.851] Timer tick, asking Buildbucket for the build status
[10:50:06.394] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:06.394] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:38:0) after 1m0s
[10:51:06.520] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:38:0)
[10:51:06.520] Timer tick, asking Buildbucket for the build status
[10:51:06.686] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:06.686] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:39:0) after 1m0s
[10:52:06.819] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:39:0)
[10:52:06.819] Timer tick, asking Buildbucket for the build status
[10:52:06.993] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:06.993] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:40:0) after 1m0s
[10:53:07.142] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:40:0)
[10:53:07.142] Timer tick, asking Buildbucket for the build status
[10:53:07.359] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:07.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:41:0) after 1m0s
[10:54:07.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:41:0)
[10:54:07.599] Timer tick, asking Buildbucket for the build status
[10:54:08.162] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:08.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:42:0) after 1m0s
[10:55:08.263] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:42:0)
[10:55:08.263] Timer tick, asking Buildbucket for the build status
[10:55:09.112] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:09.112] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:43:0) after 1m0s
[10:56:09.236] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:43:0)
[10:56:09.236] Timer tick, asking Buildbucket for the build status
[10:56:11.689] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:11.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:44:0) after 1m0s
[10:57:11.785] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:44:0)
[10:57:11.785] Timer tick, asking Buildbucket for the build status
[10:57:15.273] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:15.273] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:45:0) after 1m0s
[10:58:15.468] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:45:0)
[10:58:15.468] Timer tick, asking Buildbucket for the build status
[10:58:15.949] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:15.949] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:46:0) after 1m0s
[10:59:15.977] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:46:0)
[10:59:15.977] Timer tick, asking Buildbucket for the build status
[10:59:16.197] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:16.197] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:47:0) after 1m0s
[11:00:16.225] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:47:0)
[11:00:16.225] Timer tick, asking Buildbucket for the build status
[11:00:16.397] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:16.397] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:48:0) after 1m0s
[11:01:16.647] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:48:0)
[11:01:16.647] Timer tick, asking Buildbucket for the build status
[11:01:18.320] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:18.320] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:49:0) after 1m0s
[11:02:18.396] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:49:0)
[11:02:18.396] Timer tick, asking Buildbucket for the build status
[11:02:18.580] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:18.580] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:50:0) after 1m0s
[11:03:18.707] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:50:0)
[11:03:18.707] Timer tick, asking Buildbucket for the build status
[11:03:19.632] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:19.632] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:51:0) after 1m0s
[11:04:19.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:51:0)
[11:04:19.721] Timer tick, asking Buildbucket for the build status
[11:04:20.256] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:20.256] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:52:0) after 1m0s
[11:05:20.608] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:52:0)
[11:05:20.608] Timer tick, asking Buildbucket for the build status
[11:05:21.328] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:21.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:53:0) after 1m0s
[11:06:21.451] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:53:0)
[11:06:21.451] Timer tick, asking Buildbucket for the build status
[11:06:22.071] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:22.071] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:54:0) after 1m0s
[11:07:22.123] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:54:0)
[11:07:22.123] Timer tick, asking Buildbucket for the build status
[11:07:22.764] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:22.764] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:55:0) after 1m0s
[11:08:22.811] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:55:0)
[11:08:22.811] Timer tick, asking Buildbucket for the build status
[11:08:23.535] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:23.535] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:56:0) after 1m0s
[11:09:23.668] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:56:0)
[11:09:23.668] Timer tick, asking Buildbucket for the build status
[11:09:24.685] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:24.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:57:0) after 1m0s
[11:10:24.785] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:57:0)
[11:10:24.785] Timer tick, asking Buildbucket for the build status
[11:10:25.640] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:25.640] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:58:0) after 1m0s
[11:11:25.881] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:58:0)
[11:11:25.881] Timer tick, asking Buildbucket for the build status
[11:11:26.204] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:26.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:59:0) after 1m0s
[11:12:26.238] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:59:0)
[11:12:26.238] Timer tick, asking Buildbucket for the build status
[11:12:26.814] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:26.814] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:60:0) after 1m0s
[11:13:26.820] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:60:0)
[11:13:26.820] Timer tick, asking Buildbucket for the build status
[11:13:27.639] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:27.639] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:61:0) after 1m0s
[11:14:27.754] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:61:0)
[11:14:27.754] Timer tick, asking Buildbucket for the build status
[11:14:28.302] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:28.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:62:0) after 1m0s
[11:15:28.406] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:62:0)
[11:15:28.406] Timer tick, asking Buildbucket for the build status
[11:15:28.618] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:28.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:63:0) after 1m0s
[11:16:28.818] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:63:0)
[11:16:28.818] Timer tick, asking Buildbucket for the build status
[11:16:29.027] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:29.027] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:64:0) after 1m0s
[11:17:29.082] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:64:0)
[11:17:29.082] Timer tick, asking Buildbucket for the build status
[11:17:29.679] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:29.679] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:65:0) after 1m0s
[11:18:29.701] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:65:0)
[11:18:29.701] Timer tick, asking Buildbucket for the build status
[11:18:30.135] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:30.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:66:0) after 1m0s
[11:19:30.372] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:66:0)
[11:19:30.372] Timer tick, asking Buildbucket for the build status
[11:19:31.156] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:31.156] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:67:0) after 1m0s
[11:20:31.175] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:67:0)
[11:20:31.175] Timer tick, asking Buildbucket for the build status
[11:20:32.440] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:32.440] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:68:0) after 1m0s
[11:21:32.746] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:68:0)
[11:21:32.746] Timer tick, asking Buildbucket for the build status
[11:21:33.357] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:33.357] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:69:0) after 1m0s
[11:22:33.539] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:69:0)
[11:22:33.539] Timer tick, asking Buildbucket for the build status
[11:22:33.940] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:33.940] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:70:0) after 1m0s
[11:23:33.945] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:70:0)
[11:23:33.945] Timer tick, asking Buildbucket for the build status
[11:23:34.696] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:34.696] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:71:0) after 1m0s
[11:24:34.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:71:0)
[11:24:34.815] Timer tick, asking Buildbucket for the build status
[11:24:35.816] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:35.817] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:72:0) after 1m0s
[11:25:35.996] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:72:0)
[11:25:35.996] Timer tick, asking Buildbucket for the build status
[11:25:36.158] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:36.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:73:0) after 1m0s
[11:26:36.338] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:73:0)
[11:26:36.338] Timer tick, asking Buildbucket for the build status
[11:26:36.556] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:36.556] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:74:0) after 1m0s
[11:27:36.644] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:74:0)
[11:27:36.644] Timer tick, asking Buildbucket for the build status
[11:27:37.651] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:37.651] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:75:0) after 1m0s
[11:28:37.729] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:75:0)
[11:28:37.729] Timer tick, asking Buildbucket for the build status
[11:28:37.875] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:37.875] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:76:0) after 1m0s
[11:29:37.901] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:76:0)
[11:29:37.901] Timer tick, asking Buildbucket for the build status
[11:29:38.097] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:38.097] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:77:0) after 1m0s
[11:30:38.200] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:77:0)
[11:30:38.200] Timer tick, asking Buildbucket for the build status
[11:30:38.354] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:38.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:78:0) after 1m0s
[11:31:38.385] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:78:0)
[11:31:38.385] Timer tick, asking Buildbucket for the build status
[11:31:38.660] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:38.660] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:79:0) after 1m0s
[11:32:39.767] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:79:0)
[11:32:39.767] Timer tick, asking Buildbucket for the build status
[11:32:42.923] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:42.923] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:80:0) after 1m0s
[11:33:43.005] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:80:0)
[11:33:43.005] Timer tick, asking Buildbucket for the build status
[11:33:43.444] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:43.444] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:81:0) after 1m0s
[11:34:43.535] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:81:0)
[11:34:43.535] Timer tick, asking Buildbucket for the build status
[11:34:43.722] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:43.722] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:82:0) after 1m0s
[11:35:43.791] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:82:0)
[11:35:43.791] Timer tick, asking Buildbucket for the build status
[11:35:44.357] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:44.357] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:83:0) after 1m0s
[11:36:44.380] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:83:0)
[11:36:44.380] Timer tick, asking Buildbucket for the build status
[11:36:44.588] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:44.588] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:84:0) after 1m0s
[11:37:44.564] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:84:0)
[11:37:44.564] Timer tick, asking Buildbucket for the build status
[11:37:44.762] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:44.762] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:85:0) after 1m0s
[11:38:44.876] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:85:0)
[11:38:44.876] Timer tick, asking Buildbucket for the build status
[11:38:45.187] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:45.187] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:86:0) after 1m0s
[11:39:45.295] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:86:0)
[11:39:45.295] Timer tick, asking Buildbucket for the build status
[11:39:45.569] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:45.569] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:87:0) after 1m0s
[11:40:45.622] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:87:0)
[11:40:45.622] Timer tick, asking Buildbucket for the build status
[11:40:46.177] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:46.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:88:0) after 1m0s
[11:41:46.222] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:88:0)
[11:41:46.222] Timer tick, asking Buildbucket for the build status
[11:41:47.282] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:47.282] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:89:0) after 1m0s
[11:42:47.339] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:89:0)
[11:42:47.339] Timer tick, asking Buildbucket for the build status
[11:42:47.910] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:47.910] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:90:0) after 1m0s
[11:43:48.032] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:90:0)
[11:43:48.032] Timer tick, asking Buildbucket for the build status
[11:43:48.470] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:48.470] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:91:0) after 1m0s
[11:44:48.619] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:91:0)
[11:44:48.619] Timer tick, asking Buildbucket for the build status
[11:44:52.253] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:52.253] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:92:0) after 1m0s
[11:45:52.284] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:92:0)
[11:45:52.284] Timer tick, asking Buildbucket for the build status
[11:45:52.408] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:52.408] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:93:0) after 1m0s
[11:46:52.435] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:93:0)
[11:46:52.435] Timer tick, asking Buildbucket for the build status
[11:46:52.590] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:52.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:94:0) after 1m0s
[11:47:52.671] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:94:0)
[11:47:52.671] Timer tick, asking Buildbucket for the build status
[11:47:52.889] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:52.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:95:0) after 1m0s
[11:48:52.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:95:0)
[11:48:52.972] Timer tick, asking Buildbucket for the build status
[11:48:53.197] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:53.197] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:96:0) after 1m0s
[11:49:53.226] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:96:0)
[11:49:53.226] Timer tick, asking Buildbucket for the build status
[11:49:54.957] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:54.957] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:97:0) after 1m0s
[11:50:54.995] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:97:0)
[11:50:54.995] Timer tick, asking Buildbucket for the build status
[11:50:55.553] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:55.553] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:98:0) after 1m0s
[11:51:55.577] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:98:0)
[11:51:55.577] Timer tick, asking Buildbucket for the build status
[11:51:55.704] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:55.704] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:99:0) after 1m0s
[11:52:55.728] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:99:0)
[11:52:55.728] Timer tick, asking Buildbucket for the build status
[11:52:56.346] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:56.346] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:100:0) after 1m0s
[11:53:56.382] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:100:0)
[11:53:56.382] Timer tick, asking Buildbucket for the build status
[11:53:56.567] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:56.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:101:0) after 1m0s
[11:54:56.593] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:101:0)
[11:54:56.593] Timer tick, asking Buildbucket for the build status
[11:54:56.715] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:56.715] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:102:0) after 1m0s
[11:55:56.779] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:102:0)
[11:55:56.779] Timer tick, asking Buildbucket for the build status
[11:55:57.313] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:57.313] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:103:0) after 1m0s
[11:56:57.370] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:103:0)
[11:56:57.370] Timer tick, asking Buildbucket for the build status
[11:56:57.924] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:57.924] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:104:0) after 1m0s
[11:57:57.948] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:104:0)
[11:57:57.948] Timer tick, asking Buildbucket for the build status
[11:58:02.693] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:02.693] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:105:0) after 1m0s
[11:59:02.889] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:105:0)
[11:59:02.889] Timer tick, asking Buildbucket for the build status
[11:59:03.075] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:03.075] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:106:0) after 1m0s
[12:00:03.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:106:0)
[12:00:03.815] Timer tick, asking Buildbucket for the build status
[12:00:04.926] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:04.926] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:107:0) after 1m0s
[12:01:05.766] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:107:0)
[12:01:05.766] Timer tick, asking Buildbucket for the build status
[12:01:06.028] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:06.028] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:108:0) after 1m0s
[12:02:06.286] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:108:0)
[12:02:06.286] Timer tick, asking Buildbucket for the build status
[12:02:06.487] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:06.487] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:109:0) after 1m0s
[12:03:06.521] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:109:0)
[12:03:06.521] Timer tick, asking Buildbucket for the build status
[12:03:06.664] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:06.664] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:110:0) after 1m0s
[12:04:06.739] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:110:0)
[12:04:06.739] Timer tick, asking Buildbucket for the build status
[12:04:07.042] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:07.042] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:111:0) after 1m0s
[12:05:07.068] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:111:0)
[12:05:07.068] Timer tick, asking Buildbucket for the build status
[12:05:07.309] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:07.309] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:112:0) after 1m0s
[12:06:07.412] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:112:0)
[12:06:07.412] Timer tick, asking Buildbucket for the build status
[12:06:07.553] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:07.553] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:113:0) after 1m0s
[12:07:07.672] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:113:0)
[12:07:07.672] Timer tick, asking Buildbucket for the build status
[12:07:07.825] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:07.825] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:114:0) after 1m0s
[12:08:08.140] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:114:0)
[12:08:08.140] Timer tick, asking Buildbucket for the build status
[12:08:08.376] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:08.376] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:115:0) after 1m0s
[12:09:08.398] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:115:0)
[12:09:08.398] Timer tick, asking Buildbucket for the build status
[12:09:08.604] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:08.604] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:116:0) after 1m0s
[12:10:08.775] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:116:0)
[12:10:08.775] Timer tick, asking Buildbucket for the build status
[12:10:09.052] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:09.052] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:117:0) after 1m0s
[12:11:09.175] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:117:0)
[12:11:09.175] Timer tick, asking Buildbucket for the build status
[12:11:09.499] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:09.499] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:118:0) after 1m0s
[12:12:09.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:118:0)
[12:12:09.591] Timer tick, asking Buildbucket for the build status
[12:12:09.969] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:09.969] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:119:0) after 1m0s
[12:13:10.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:119:0)
[12:13:10.194] Timer tick, asking Buildbucket for the build status
[12:13:10.467] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:10.467] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:120:0) after 1m0s
[12:14:10.497] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:120:0)
[12:14:10.497] Timer tick, asking Buildbucket for the build status
[12:14:11.024] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:11.024] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:121:0) after 1m0s
[12:15:11.178] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:121:0)
[12:15:11.178] Timer tick, asking Buildbucket for the build status
[12:15:11.328] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:11.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:122:0) after 1m0s
[12:16:11.376] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:122:0)
[12:16:11.376] Timer tick, asking Buildbucket for the build status
[12:16:11.889] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:11.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:123:0) after 1m0s
[12:17:12.019] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:123:0)
[12:17:12.019] Timer tick, asking Buildbucket for the build status
[12:17:12.525] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:12.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:124:0) after 1m0s
[12:18:12.590] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:124:0)
[12:18:12.590] Timer tick, asking Buildbucket for the build status
[12:18:14.882] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:14.882] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:125:0) after 1m0s
[12:19:15.215] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:125:0)
[12:19:15.215] Timer tick, asking Buildbucket for the build status
[12:19:15.435] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:15.435] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:126:0) after 1m0s
[12:20:15.558] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:126:0)
[12:20:15.558] Timer tick, asking Buildbucket for the build status
[12:20:17.845] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:17.845] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:127:0) after 1m0s
[12:21:18.165] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:127:0)
[12:21:18.165] Timer tick, asking Buildbucket for the build status
[12:21:18.425] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:18.425] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:128:0) after 1m0s
[12:22:18.751] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:128:0)
[12:22:18.751] Timer tick, asking Buildbucket for the build status
[12:22:19.191] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:19.191] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:129:0) after 1m0s
[12:23:19.227] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:129:0)
[12:23:19.227] Timer tick, asking Buildbucket for the build status
[12:23:19.623] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:19.623] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:130:0) after 1m0s
[12:24:19.646] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:130:0)
[12:24:19.646] Timer tick, asking Buildbucket for the build status
[12:24:19.812] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:19.812] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:131:0) after 1m0s
[12:25:19.841] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:131:0)
[12:25:19.842] Timer tick, asking Buildbucket for the build status
[12:25:20.814] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:20.814] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:132:0) after 1m0s
[12:26:20.839] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:132:0)
[12:26:20.839] Timer tick, asking Buildbucket for the build status
[12:26:20.979] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:20.979] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:133:0) after 1m0s
[12:27:21.008] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:133:0)
[12:27:21.008] Timer tick, asking Buildbucket for the build status
[12:27:21.204] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:21.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:134:0) after 1m0s
[12:28:21.244] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:134:0)
[12:28:21.244] Timer tick, asking Buildbucket for the build status
[12:28:21.627] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:21.627] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:135:0) after 1m0s
[12:29:21.727] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:135:0)
[12:29:21.727] Timer tick, asking Buildbucket for the build status
[12:29:24.449] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:24.449] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:136:0) after 1m0s
[12:30:24.660] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:136:0)
[12:30:24.660] Timer tick, asking Buildbucket for the build status
[12:30:24.860] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:24.860] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:137:0) after 1m0s
[12:31:25.098] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:137:0)
[12:31:25.098] Timer tick, asking Buildbucket for the build status
[12:31:25.327] Build 8906062782976783776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:25.327] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9071492845187944064:138:0) after 1m0s
[12:31:39.905] Received PubSub notification, asking Buildbucket for the build status
[12:31:40.099] Build 8906062782976783776: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:31:40.099] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1564921898936019",
"created_by": "project:chromium",
"created_ts": "1564913685781038",
"id": "8906062782976783776",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0a226e460c41b3c3acad3a42e19e04d94e0e2566\"}}",
"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-03yq\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 14331, \"got_angle_revision\": \"dc2c5c5a419e45891ec5898e675b8f447b1bd9fa\", \"got_dawn_revision\": \"bfc9cee5d3bea7e37ff26f2952d2d112f3776790\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"0a226e460c41b3c3acad3a42e19e04d94e0e2566\", \"got_revision_cp\": \"refs/heads/master@{#683877}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"7550297429f9368192c5ef4570696526e8e0b773\", \"got_v8_revision_cp\": \"refs/heads/7.8.58@{#1}\", \"got_webrtc_revision\": \"fedd625e0cc634a6ae12683e7bf61f6bc01e677d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28752}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0a226e460c41b3c3acad3a42e19e04d94e0e2566\"}, \"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-03yq\"], \"image\": [\"chrome-win10-1703-19072400-fedda89f1f1\"], \"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\": [\"4382-5929880\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1564913688278593",
"status": "COMPLETED",
"status_changed_ts": "1564921899379305",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14331",
"builder:Windows deterministic",
"buildset:commit/git/0a226e460c41b3c3acad3a42e19e04d94e0e2566",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0a226e460c41b3c3acad3a42e19e04d94e0e2566",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9071492845187944064",
"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/8906062782976783776/+/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:4674f16313260010",
"user_agent:luci-scheduler"
],
"updated_ts": "1564921899394140",
"url": "https://ci.chromium.org/b/8906062782976783776",
"utcnow_ts": "1564921900063955"
}
}
[12:31:40.099] Invocation finished in 2h16m55.810185626s with status SUCCEEDED