[15:46:02.445] New invocation is queued and will start shortly
[15:46:03.766] Starting the invocation (attempt 1)
[15:46:03.826] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:46:03.826] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:46:03.826] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9062774692676751872",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7647346881f453eca014eeaae93b9e5c65c17cb4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9062774692676751872",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/7647346881f453eca014eeaae93b9e5c65c17cb4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7647346881f453eca014eeaae93b9e5c65c17cb4",
"gitiles_ref:refs/heads/master"
]
}
[15:46:04.923] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1573227964049691",
"id": "8897344630327085008",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7647346881f453eca014eeaae93b9e5c65c17cb4\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1573227964669005",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/15150",
"builder:Windows deterministic",
"buildset:commit/git/7647346881f453eca014eeaae93b9e5c65c17cb4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7647346881f453eca014eeaae93b9e5c65c17cb4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9062774692676751872",
"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/8897344630327085008/+/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": "1573227964669832",
"url": "https://ci.chromium.org/b/8897344630327085008",
"utcnow_ts": "1573227964893564"
}
}
[15:46:04.923] Task URL: https://ci.chromium.org/b/8897344630327085008
[15:46:04.923] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:2:0) after 1m0s
[15:47:04.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:2:0)
[15:47:04.972] Timer tick, asking Buildbucket for the build status
[15:47:05.270] Build 8897344630327085008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:47:05.270] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:3:0) after 1m0s
[15:48:05.252] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:3:0)
[15:48:05.252] Timer tick, asking Buildbucket for the build status
[15:48:05.642] Build 8897344630327085008: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:48:05.642] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:4:0) after 1m0s
[15:48:28.450] Received PubSub notification, asking Buildbucket for the build status
[15:48:28.830] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:05.758] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:4:0)
[15:49:05.758] Timer tick, asking Buildbucket for the build status
[15:49:05.958] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:05.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:6:0) after 1m0s
[15:50:06.009] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:6:0)
[15:50:06.009] Timer tick, asking Buildbucket for the build status
[15:50:06.322] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:06.322] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:7:0) after 1m0s
[15:51:08.576] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:7:0)
[15:51:08.576] Timer tick, asking Buildbucket for the build status
[15:51:08.929] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:08.929] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:8:0) after 1m0s
[15:52:09.110] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:8:0)
[15:52:09.110] Timer tick, asking Buildbucket for the build status
[15:52:09.879] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:09.879] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:9:0) after 1m0s
[15:53:09.874] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:9:0)
[15:53:09.874] Timer tick, asking Buildbucket for the build status
[15:53:11.714] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:11.714] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:10:0) after 1m0s
[15:54:11.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:10:0)
[15:54:11.705] Timer tick, asking Buildbucket for the build status
[15:54:12.435] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:12.435] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:11:0) after 1m0s
[15:55:12.462] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:11:0)
[15:55:12.462] Timer tick, asking Buildbucket for the build status
[15:55:12.939] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:12.939] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:12:0) after 1m0s
[15:56:12.957] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:12:0)
[15:56:12.957] Timer tick, asking Buildbucket for the build status
[15:56:13.366] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:13.366] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:13:0) after 1m0s
[15:57:13.421] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:13:0)
[15:57:13.421] Timer tick, asking Buildbucket for the build status
[15:57:13.805] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:13.805] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:14:0) after 1m0s
[15:58:13.833] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:14:0)
[15:58:13.833] Timer tick, asking Buildbucket for the build status
[15:58:14.257] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:14.257] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:15:0) after 1m0s
[15:59:14.304] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:15:0)
[15:59:14.304] Timer tick, asking Buildbucket for the build status
[15:59:14.548] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:14.548] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:16:0) after 1m0s
[16:00:14.650] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:16:0)
[16:00:14.650] Timer tick, asking Buildbucket for the build status
[16:00:15.667] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:15.667] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:17:0) after 1m0s
[16:01:15.759] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:17:0)
[16:01:15.759] Timer tick, asking Buildbucket for the build status
[16:01:16.900] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:16.900] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:18:0) after 1m0s
[16:02:16.961] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:18:0)
[16:02:16.961] Timer tick, asking Buildbucket for the build status
[16:02:17.347] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:17.347] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:19:0) after 1m0s
[16:03:17.433] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:19:0)
[16:03:17.433] Timer tick, asking Buildbucket for the build status
[16:03:17.756] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:17.756] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:20:0) after 1m0s
[16:04:17.843] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:20:0)
[16:04:17.843] Timer tick, asking Buildbucket for the build status
[16:04:18.109] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:18.109] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:21:0) after 1m0s
[16:05:18.335] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:21:0)
[16:05:18.335] Timer tick, asking Buildbucket for the build status
[16:05:18.554] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:18.554] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:22:0) after 1m0s
[16:06:18.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:22:0)
[16:06:18.705] Timer tick, asking Buildbucket for the build status
[16:06:18.928] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:18.928] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:23:0) after 1m0s
[16:07:19.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:23:0)
[16:07:19.154] Timer tick, asking Buildbucket for the build status
[16:07:19.403] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:19.403] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:24:0) after 1m0s
[16:08:19.457] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:24:0)
[16:08:19.457] Timer tick, asking Buildbucket for the build status
[16:08:19.733] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:19.733] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:25:0) after 1m0s
[16:09:19.866] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:25:0)
[16:09:19.866] Timer tick, asking Buildbucket for the build status
[16:09:20.155] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:20.155] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:26:0) after 1m0s
[16:10:20.211] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:26:0)
[16:10:20.211] Timer tick, asking Buildbucket for the build status
[16:10:23.881] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:23.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:27:0) after 1m0s
[16:11:24.017] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:27:0)
[16:11:24.017] Timer tick, asking Buildbucket for the build status
[16:11:24.452] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:24.452] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:28:0) after 1m0s
[16:12:24.625] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:28:0)
[16:12:24.625] Timer tick, asking Buildbucket for the build status
[16:12:24.918] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:24.918] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:29:0) after 1m0s
[16:13:26.112] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:29:0)
[16:13:26.112] Timer tick, asking Buildbucket for the build status
[16:13:27.573] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:27.573] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:30:0) after 1m0s
[16:14:27.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:30:0)
[16:14:27.645] Timer tick, asking Buildbucket for the build status
[16:14:27.893] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:27.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:31:0) after 1m0s
[16:15:27.958] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:31:0)
[16:15:27.958] Timer tick, asking Buildbucket for the build status
[16:15:28.347] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:28.347] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:32:0) after 1m0s
[16:16:28.474] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:32:0)
[16:16:28.474] Timer tick, asking Buildbucket for the build status
[16:16:28.795] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:28.795] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:33:0) after 1m0s
[16:17:28.883] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:33:0)
[16:17:28.883] Timer tick, asking Buildbucket for the build status
[16:17:29.187] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:29.187] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:34:0) after 1m0s
[16:18:29.335] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:34:0)
[16:18:29.335] Timer tick, asking Buildbucket for the build status
[16:18:30.146] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:30.146] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:35:0) after 1m0s
[16:19:30.182] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:35:0)
[16:19:30.182] Timer tick, asking Buildbucket for the build status
[16:19:30.420] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:30.420] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:36:0) after 1m0s
[16:20:30.563] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:36:0)
[16:20:30.563] Timer tick, asking Buildbucket for the build status
[16:20:31.482] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:31.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:37:0) after 1m0s
[16:21:31.640] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:37:0)
[16:21:31.640] Timer tick, asking Buildbucket for the build status
[16:21:32.218] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:32.218] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:38:0) after 1m0s
[16:22:32.237] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:38:0)
[16:22:32.237] Timer tick, asking Buildbucket for the build status
[16:22:32.624] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:32.624] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:39:0) after 1m0s
[16:23:32.651] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:39:0)
[16:23:32.651] Timer tick, asking Buildbucket for the build status
[16:23:32.880] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:32.880] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:40:0) after 1m0s
[16:24:33.042] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:40:0)
[16:24:33.042] Timer tick, asking Buildbucket for the build status
[16:24:33.685] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:33.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:41:0) after 1m0s
[16:25:33.797] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:41:0)
[16:25:33.797] Timer tick, asking Buildbucket for the build status
[16:25:34.148] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:34.148] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:42:0) after 1m0s
[16:26:34.274] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:42:0)
[16:26:34.274] Timer tick, asking Buildbucket for the build status
[16:26:36.153] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:36.154] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:43:0) after 1m0s
[16:27:36.255] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:43:0)
[16:27:36.255] Timer tick, asking Buildbucket for the build status
[16:27:36.517] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:36.517] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:44:0) after 1m0s
[16:28:36.700] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:44:0)
[16:28:36.700] Timer tick, asking Buildbucket for the build status
[16:28:36.825] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:36.825] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:45:0) after 1m0s
[16:29:36.995] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:45:0)
[16:29:36.995] Timer tick, asking Buildbucket for the build status
[16:29:38.074] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:38.074] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:46:0) after 1m0s
[16:30:38.276] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:46:0)
[16:30:38.276] Timer tick, asking Buildbucket for the build status
[16:30:38.479] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:38.479] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:47:0) after 1m0s
[16:31:38.640] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:47:0)
[16:31:38.640] Timer tick, asking Buildbucket for the build status
[16:31:40.040] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:40.040] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:48:0) after 1m0s
[16:32:40.068] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:48:0)
[16:32:40.068] Timer tick, asking Buildbucket for the build status
[16:32:42.376] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:42.376] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:49:0) after 1m0s
[16:33:42.410] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:49:0)
[16:33:42.410] Timer tick, asking Buildbucket for the build status
[16:33:42.764] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:42.764] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:50:0) after 1m0s
[16:34:42.923] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:50:0)
[16:34:42.923] Timer tick, asking Buildbucket for the build status
[16:34:43.116] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:43.116] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:51:0) after 1m0s
[16:35:43.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:51:0)
[16:35:43.202] Timer tick, asking Buildbucket for the build status
[16:35:43.499] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:43.499] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:52:0) after 1m0s
[16:36:43.581] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:52:0)
[16:36:43.581] Timer tick, asking Buildbucket for the build status
[16:36:43.924] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:43.924] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:53:0) after 1m0s
[16:37:44.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:53:0)
[16:37:44.645] Timer tick, asking Buildbucket for the build status
[16:37:44.949] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:44.950] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:54:0) after 1m0s
[16:38:45.162] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:54:0)
[16:38:45.162] Timer tick, asking Buildbucket for the build status
[16:38:45.536] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:45.536] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:55:0) after 1m0s
[16:39:45.678] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:55:0)
[16:39:45.678] Timer tick, asking Buildbucket for the build status
[16:39:46.015] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:46.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:56:0) after 1m0s
[16:40:46.222] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:56:0)
[16:40:46.222] Timer tick, asking Buildbucket for the build status
[16:40:46.571] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:46.571] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:57:0) after 1m0s
[16:41:46.690] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:57:0)
[16:41:46.690] Timer tick, asking Buildbucket for the build status
[16:41:48.328] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:48.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:58:0) after 1m0s
[16:42:48.472] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:58:0)
[16:42:48.472] Timer tick, asking Buildbucket for the build status
[16:42:49.450] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:49.450] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:59:0) after 1m0s
[16:43:49.531] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:59:0)
[16:43:49.531] Timer tick, asking Buildbucket for the build status
[16:43:49.724] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:49.724] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:60:0) after 1m0s
[16:44:49.850] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:60:0)
[16:44:49.850] Timer tick, asking Buildbucket for the build status
[16:44:50.330] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:50.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:61:0) after 1m0s
[16:45:50.408] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:61:0)
[16:45:50.408] Timer tick, asking Buildbucket for the build status
[16:45:50.564] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:50.564] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:62:0) after 1m0s
[16:46:50.627] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:62:0)
[16:46:50.627] Timer tick, asking Buildbucket for the build status
[16:46:50.786] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:50.786] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:63:0) after 1m0s
[16:47:50.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:63:0)
[16:47:50.929] Timer tick, asking Buildbucket for the build status
[16:47:51.144] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:51.144] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:64:0) after 1m0s
[16:48:51.316] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:64:0)
[16:48:51.316] Timer tick, asking Buildbucket for the build status
[16:48:51.570] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:51.570] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:65:0) after 1m0s
[16:49:51.819] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:65:0)
[16:49:51.819] Timer tick, asking Buildbucket for the build status
[16:49:53.098] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:53.098] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:66:0) after 1m0s
[16:50:53.258] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:66:0)
[16:50:53.258] Timer tick, asking Buildbucket for the build status
[16:50:53.899] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:53.899] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:67:0) after 1m0s
[16:51:53.922] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:67:0)
[16:51:53.922] Timer tick, asking Buildbucket for the build status
[16:51:55.411] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:55.411] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:68:0) after 1m0s
[16:52:55.441] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:68:0)
[16:52:55.441] Timer tick, asking Buildbucket for the build status
[16:52:56.006] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:56.006] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:69:0) after 1m0s
[16:53:56.059] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:69:0)
[16:53:56.059] Timer tick, asking Buildbucket for the build status
[16:53:56.825] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:56.825] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:70:0) after 1m0s
[16:54:56.945] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:70:0)
[16:54:56.945] Timer tick, asking Buildbucket for the build status
[16:54:57.143] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:57.143] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:71:0) after 1m0s
[16:55:57.181] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:71:0)
[16:55:57.181] Timer tick, asking Buildbucket for the build status
[16:55:57.594] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:57.594] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:72:0) after 1m0s
[16:56:57.625] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:72:0)
[16:56:57.625] Timer tick, asking Buildbucket for the build status
[16:56:57.841] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:57.841] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:73:0) after 1m0s
[16:57:58.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:73:0)
[16:57:58.083] Timer tick, asking Buildbucket for the build status
[16:57:58.402] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:58.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:74:0) after 1m0s
[16:58:58.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:74:0)
[16:58:58.757] Timer tick, asking Buildbucket for the build status
[16:58:58.948] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:58.948] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:75:0) after 1m0s
[16:59:59.263] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:75:0)
[16:59:59.263] Timer tick, asking Buildbucket for the build status
[16:59:59.544] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:59.544] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:76:0) after 1m0s
[17:00:59.852] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:76:0)
[17:00:59.852] Timer tick, asking Buildbucket for the build status
[17:01:00.522] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:00.522] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:77:0) after 1m0s
[17:02:00.767] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:77:0)
[17:02:00.767] Timer tick, asking Buildbucket for the build status
[17:02:01.940] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:01.940] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:78:0) after 1m0s
[17:03:02.009] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:78:0)
[17:03:02.009] Timer tick, asking Buildbucket for the build status
[17:03:02.392] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:02.392] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:79:0) after 1m0s
[17:04:02.538] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:79:0)
[17:04:02.538] Timer tick, asking Buildbucket for the build status
[17:04:02.743] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:02.743] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:80:0) after 1m0s
[17:05:02.847] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:80:0)
[17:05:02.847] Timer tick, asking Buildbucket for the build status
[17:05:03.352] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:03.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:81:0) after 1m0s
[17:06:03.716] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:81:0)
[17:06:03.716] Timer tick, asking Buildbucket for the build status
[17:06:04.557] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:04.557] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:82:0) after 1m0s
[17:07:04.616] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:82:0)
[17:07:04.616] Timer tick, asking Buildbucket for the build status
[17:07:04.824] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:04.825] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:83:0) after 1m0s
[17:08:04.951] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:83:0)
[17:08:04.951] Timer tick, asking Buildbucket for the build status
[17:08:05.236] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:05.237] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:84:0) after 1m0s
[17:09:05.408] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:84:0)
[17:09:05.408] Timer tick, asking Buildbucket for the build status
[17:09:05.887] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:05.887] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:85:0) after 1m0s
[17:10:06.217] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:85:0)
[17:10:06.217] Timer tick, asking Buildbucket for the build status
[17:10:06.879] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:06.879] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:86:0) after 1m0s
[17:11:07.287] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:86:0)
[17:11:07.287] Timer tick, asking Buildbucket for the build status
[17:11:07.860] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:07.860] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:87:0) after 1m0s
[17:12:08.208] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:87:0)
[17:12:08.208] Timer tick, asking Buildbucket for the build status
[17:12:08.500] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:08.500] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:88:0) after 1m0s
[17:13:08.566] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:88:0)
[17:13:08.566] Timer tick, asking Buildbucket for the build status
[17:13:08.817] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:08.817] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:89:0) after 1m0s
[17:14:08.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:89:0)
[17:14:08.963] Timer tick, asking Buildbucket for the build status
[17:14:09.820] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:09.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:90:0) after 1m0s
[17:15:09.867] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:90:0)
[17:15:09.867] Timer tick, asking Buildbucket for the build status
[17:15:10.167] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:10.167] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:91:0) after 1m0s
[17:16:10.294] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:91:0)
[17:16:10.294] Timer tick, asking Buildbucket for the build status
[17:16:10.622] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:10.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:92:0) after 1m0s
[17:17:10.663] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:92:0)
[17:17:10.663] Timer tick, asking Buildbucket for the build status
[17:17:10.892] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:10.892] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:93:0) after 1m0s
[17:18:11.457] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:93:0)
[17:18:11.457] Timer tick, asking Buildbucket for the build status
[17:18:13.638] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:13.638] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:94:0) after 1m0s
[17:19:13.819] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:94:0)
[17:19:13.819] Timer tick, asking Buildbucket for the build status
[17:19:14.109] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:14.109] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:95:0) after 1m0s
[17:20:14.471] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:95:0)
[17:20:14.471] Timer tick, asking Buildbucket for the build status
[17:20:14.734] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:14.734] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:96:0) after 1m0s
[17:21:15.061] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:96:0)
[17:21:15.061] Timer tick, asking Buildbucket for the build status
[17:21:15.771] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:15.771] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:97:0) after 1m0s
[17:22:15.901] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:97:0)
[17:22:15.901] Timer tick, asking Buildbucket for the build status
[17:22:16.321] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:16.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:98:0) after 1m0s
[17:23:16.491] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:98:0)
[17:23:16.491] Timer tick, asking Buildbucket for the build status
[17:23:16.838] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:16.838] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:99:0) after 1m0s
[17:24:16.865] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:99:0)
[17:24:16.865] Timer tick, asking Buildbucket for the build status
[17:24:17.435] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:17.435] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:100:0) after 1m0s
[17:25:17.461] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:100:0)
[17:25:17.461] Timer tick, asking Buildbucket for the build status
[17:25:17.871] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:17.871] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:101:0) after 1m0s
[17:26:18.008] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:101:0)
[17:26:18.008] Timer tick, asking Buildbucket for the build status
[17:26:18.175] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:18.175] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:102:0) after 1m0s
[17:27:18.251] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:102:0)
[17:27:18.251] Timer tick, asking Buildbucket for the build status
[17:27:18.468] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:18.468] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:103:0) after 1m0s
[17:28:18.671] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:103:0)
[17:28:18.671] Timer tick, asking Buildbucket for the build status
[17:28:18.916] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:18.916] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:104:0) after 1m0s
[17:29:19.044] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:104:0)
[17:29:19.044] Timer tick, asking Buildbucket for the build status
[17:29:19.902] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:19.902] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:105:0) after 1m0s
[17:30:19.916] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:105:0)
[17:30:19.916] Timer tick, asking Buildbucket for the build status
[17:30:20.136] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:20.136] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:106:0) after 1m0s
[17:31:20.394] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:106:0)
[17:31:20.394] Timer tick, asking Buildbucket for the build status
[17:31:20.623] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:20.623] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:107:0) after 1m0s
[17:32:20.697] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:107:0)
[17:32:20.697] Timer tick, asking Buildbucket for the build status
[17:32:21.069] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:21.069] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:108:0) after 1m0s
[17:33:21.215] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:108:0)
[17:33:21.215] Timer tick, asking Buildbucket for the build status
[17:33:22.472] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:22.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:109:0) after 1m0s
[17:34:22.682] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:109:0)
[17:34:22.682] Timer tick, asking Buildbucket for the build status
[17:34:23.025] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:23.025] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:110:0) after 1m0s
[17:35:23.057] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:110:0)
[17:35:23.057] Timer tick, asking Buildbucket for the build status
[17:35:23.518] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:23.518] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:111:0) after 1m0s
[17:36:23.609] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:111:0)
[17:36:23.609] Timer tick, asking Buildbucket for the build status
[17:36:23.849] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:23.850] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:112:0) after 1m0s
[17:37:24.179] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:112:0)
[17:37:24.179] Timer tick, asking Buildbucket for the build status
[17:37:24.885] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:24.885] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:113:0) after 1m0s
[17:38:24.919] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:113:0)
[17:38:24.919] Timer tick, asking Buildbucket for the build status
[17:38:25.267] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:25.267] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:114:0) after 1m0s
[17:39:25.391] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:114:0)
[17:39:25.391] Timer tick, asking Buildbucket for the build status
[17:39:25.644] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:25.644] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:115:0) after 1m0s
[17:40:25.760] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:115:0)
[17:40:25.760] Timer tick, asking Buildbucket for the build status
[17:40:28.167] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:28.167] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:116:0) after 1m0s
[17:41:28.282] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:116:0)
[17:41:28.282] Timer tick, asking Buildbucket for the build status
[17:41:28.554] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:28.554] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:117:0) after 1m0s
[17:42:28.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:117:0)
[17:42:28.637] Timer tick, asking Buildbucket for the build status
[17:42:28.825] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:28.825] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:118:0) after 1m0s
[17:43:28.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:118:0)
[17:43:28.869] Timer tick, asking Buildbucket for the build status
[17:43:29.125] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:29.125] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:119:0) after 1m0s
[17:44:29.213] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:119:0)
[17:44:29.213] Timer tick, asking Buildbucket for the build status
[17:44:29.823] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:29.823] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:120:0) after 1m0s
[17:45:29.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:120:0)
[17:45:29.886] Timer tick, asking Buildbucket for the build status
[17:45:30.535] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:30.535] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:121:0) after 1m0s
[17:46:30.620] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:121:0)
[17:46:30.621] Timer tick, asking Buildbucket for the build status
[17:46:31.188] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:31.188] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:122:0) after 1m0s
[17:47:31.295] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:122:0)
[17:47:31.295] Timer tick, asking Buildbucket for the build status
[17:47:32.963] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:32.963] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:123:0) after 1m0s
[17:48:33.006] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:123:0)
[17:48:33.006] Timer tick, asking Buildbucket for the build status
[17:48:33.234] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:33.234] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:124:0) after 1m0s
[17:49:33.329] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:124:0)
[17:49:33.329] Timer tick, asking Buildbucket for the build status
[17:49:33.477] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:33.477] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:125:0) after 1m0s
[17:50:33.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:125:0)
[17:50:33.514] Timer tick, asking Buildbucket for the build status
[17:50:34.326] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:34.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:126:0) after 1m0s
[17:51:34.422] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:126:0)
[17:51:34.422] Timer tick, asking Buildbucket for the build status
[17:51:34.896] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:34.896] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:127:0) after 1m0s
[17:52:34.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:127:0)
[17:52:34.927] Timer tick, asking Buildbucket for the build status
[17:52:35.778] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:35.778] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:128:0) after 1m0s
[17:53:35.826] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:128:0)
[17:53:35.826] Timer tick, asking Buildbucket for the build status
[17:53:38.274] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:38.274] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:129:0) after 1m0s
[17:54:38.312] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:129:0)
[17:54:38.312] Timer tick, asking Buildbucket for the build status
[17:54:38.530] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:38.530] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:130:0) after 1m0s
[17:55:38.564] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:130:0)
[17:55:38.564] Timer tick, asking Buildbucket for the build status
[17:55:39.221] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:39.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:131:0) after 1m0s
[17:56:39.289] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:131:0)
[17:56:39.289] Timer tick, asking Buildbucket for the build status
[17:56:39.861] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:39.861] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:132:0) after 1m0s
[17:57:39.898] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:132:0)
[17:57:39.898] Timer tick, asking Buildbucket for the build status
[17:57:40.158] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:40.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:133:0) after 1m0s
[17:58:40.218] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:133:0)
[17:58:40.218] Timer tick, asking Buildbucket for the build status
[17:58:40.491] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:40.491] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:134:0) after 1m0s
[17:59:40.793] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:134:0)
[17:59:40.793] Timer tick, asking Buildbucket for the build status
[17:59:41.550] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:41.550] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:135:0) after 1m0s
[18:00:41.601] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:135:0)
[18:00:41.601] Timer tick, asking Buildbucket for the build status
[18:00:42.073] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:42.073] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:136:0) after 1m0s
[18:01:42.061] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:136:0)
[18:01:42.061] Timer tick, asking Buildbucket for the build status
[18:01:42.817] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:42.817] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:137:0) after 1m0s
[18:02:42.866] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:137:0)
[18:02:42.866] Timer tick, asking Buildbucket for the build status
[18:02:43.583] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:43.583] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:138:0) after 1m0s
[18:03:43.613] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:138:0)
[18:03:43.613] Timer tick, asking Buildbucket for the build status
[18:03:43.955] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:43.955] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:139:0) after 1m0s
[18:04:43.986] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:139:0)
[18:04:43.986] Timer tick, asking Buildbucket for the build status
[18:04:44.243] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:44.243] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:140:0) after 1m0s
[18:05:44.343] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:140:0)
[18:05:44.343] Timer tick, asking Buildbucket for the build status
[18:05:44.660] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:44.660] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:141:0) after 1m0s
[18:06:44.797] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:141:0)
[18:06:44.797] Timer tick, asking Buildbucket for the build status
[18:06:44.926] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:44.926] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:142:0) after 1m0s
[18:07:45.065] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:142:0)
[18:07:45.065] Timer tick, asking Buildbucket for the build status
[18:07:45.513] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:45.513] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:143:0) after 1m0s
[18:08:45.638] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:143:0)
[18:08:45.638] Timer tick, asking Buildbucket for the build status
[18:08:46.630] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:46.630] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:144:0) after 1m0s
[18:09:46.745] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:144:0)
[18:09:46.745] Timer tick, asking Buildbucket for the build status
[18:09:46.969] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:46.969] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:145:0) after 1m0s
[18:10:47.276] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:145:0)
[18:10:47.276] Timer tick, asking Buildbucket for the build status
[18:10:47.597] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:47.597] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:146:0) after 1m0s
[18:11:47.777] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:146:0)
[18:11:47.777] Timer tick, asking Buildbucket for the build status
[18:11:48.048] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:48.048] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:147:0) after 1m0s
[18:12:48.215] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:147:0)
[18:12:48.215] Timer tick, asking Buildbucket for the build status
[18:12:48.794] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:48.794] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:148:0) after 1m0s
[18:13:48.864] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:148:0)
[18:13:48.864] Timer tick, asking Buildbucket for the build status
[18:13:50.127] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:50.127] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:149:0) after 1m0s
[18:14:50.269] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:149:0)
[18:14:50.269] Timer tick, asking Buildbucket for the build status
[18:14:50.793] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:50.793] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:150:0) after 1m0s
[18:15:50.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:150:0)
[18:15:50.905] Timer tick, asking Buildbucket for the build status
[18:15:52.112] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:52.112] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:151:0) after 1m0s
[18:16:52.238] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:151:0)
[18:16:52.238] Timer tick, asking Buildbucket for the build status
[18:16:54.170] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:54.170] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:152:0) after 1m0s
[18:17:54.233] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:152:0)
[18:17:54.233] Timer tick, asking Buildbucket for the build status
[18:17:57.018] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:57.018] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:153:0) after 1m0s
[18:18:57.372] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:153:0)
[18:18:57.372] Timer tick, asking Buildbucket for the build status
[18:18:57.719] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:57.719] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:154:0) after 1m0s
[18:19:57.878] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:154:0)
[18:19:57.878] Timer tick, asking Buildbucket for the build status
[18:19:58.060] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:58.061] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:155:0) after 1m0s
[18:20:58.217] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:155:0)
[18:20:58.217] Timer tick, asking Buildbucket for the build status
[18:20:58.798] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:58.798] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:156:0) after 1m0s
[18:21:58.954] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:156:0)
[18:21:58.954] Timer tick, asking Buildbucket for the build status
[18:22:00.172] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:00.172] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:157:0) after 1m0s
[18:23:00.255] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:157:0)
[18:23:00.255] Timer tick, asking Buildbucket for the build status
[18:23:00.500] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:00.500] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:158:0) after 1m0s
[18:24:00.696] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:158:0)
[18:24:00.696] Timer tick, asking Buildbucket for the build status
[18:24:00.871] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:00.871] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:159:0) after 1m0s
[18:25:00.908] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:159:0)
[18:25:00.908] Timer tick, asking Buildbucket for the build status
[18:25:01.273] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:01.273] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:160:0) after 1m0s
[18:26:01.316] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:160:0)
[18:26:01.316] Timer tick, asking Buildbucket for the build status
[18:26:01.548] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:01.548] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:161:0) after 1m0s
[18:27:01.659] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:161:0)
[18:27:01.659] Timer tick, asking Buildbucket for the build status
[18:27:01.860] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:01.860] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:162:0) after 1m0s
[18:28:01.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:162:0)
[18:28:01.980] Timer tick, asking Buildbucket for the build status
[18:28:02.958] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:02.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:163:0) after 1m0s
[18:29:03.019] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:163:0)
[18:29:03.019] Timer tick, asking Buildbucket for the build status
[18:29:03.211] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:03.211] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:164:0) after 1m0s
[18:30:03.379] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:164:0)
[18:30:03.379] Timer tick, asking Buildbucket for the build status
[18:30:03.603] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:03.603] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:165:0) after 1m0s
[18:31:03.716] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:165:0)
[18:31:03.716] Timer tick, asking Buildbucket for the build status
[18:31:04.525] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:04.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:166:0) after 1m0s
[18:32:04.681] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:166:0)
[18:32:04.681] Timer tick, asking Buildbucket for the build status
[18:32:05.549] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:05.549] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:167:0) after 1m0s
[18:33:05.706] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:167:0)
[18:33:05.706] Timer tick, asking Buildbucket for the build status
[18:33:05.937] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:05.937] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:168:0) after 1m0s
[18:34:06.092] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:168:0)
[18:34:06.092] Timer tick, asking Buildbucket for the build status
[18:34:06.273] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:06.273] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:169:0) after 1m0s
[18:35:06.312] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:169:0)
[18:35:06.312] Timer tick, asking Buildbucket for the build status
[18:35:06.935] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:06.935] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:170:0) after 1m0s
[18:36:07.043] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:170:0)
[18:36:07.043] Timer tick, asking Buildbucket for the build status
[18:36:07.352] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:07.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:171:0) after 1m0s
[18:37:07.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:171:0)
[18:37:07.588] Timer tick, asking Buildbucket for the build status
[18:37:07.966] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:07.966] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:172:0) after 1m0s
[18:38:07.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:172:0)
[18:38:07.978] Timer tick, asking Buildbucket for the build status
[18:38:08.466] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:08.466] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:173:0) after 1m0s
[18:39:08.539] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:173:0)
[18:39:08.539] Timer tick, asking Buildbucket for the build status
[18:39:08.723] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:08.723] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:174:0) after 1m0s
[18:40:08.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:174:0)
[18:40:08.763] Timer tick, asking Buildbucket for the build status
[18:40:09.429] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:09.429] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:175:0) after 1m0s
[18:41:09.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:175:0)
[18:41:09.637] Timer tick, asking Buildbucket for the build status
[18:41:10.680] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:10.680] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:176:0) after 1m0s
[18:42:10.750] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:176:0)
[18:42:10.750] Timer tick, asking Buildbucket for the build status
[18:42:11.168] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:11.168] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:177:0) after 1m0s
[18:43:11.212] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:177:0)
[18:43:11.212] Timer tick, asking Buildbucket for the build status
[18:43:11.968] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:11.968] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:178:0) after 1m0s
[18:44:12.055] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:178:0)
[18:44:12.055] Timer tick, asking Buildbucket for the build status
[18:44:12.249] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:12.249] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:179:0) after 1m0s
[18:45:12.335] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:179:0)
[18:45:12.335] Timer tick, asking Buildbucket for the build status
[18:45:12.600] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:12.600] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:180:0) after 1m0s
[18:46:12.667] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:180:0)
[18:46:12.667] Timer tick, asking Buildbucket for the build status
[18:46:12.849] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:12.849] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:181:0) after 1m0s
[18:47:12.947] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:181:0)
[18:47:12.947] Timer tick, asking Buildbucket for the build status
[18:47:13.624] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:13.624] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:182:0) after 1m0s
[18:48:13.793] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:182:0)
[18:48:13.793] Timer tick, asking Buildbucket for the build status
[18:48:14.611] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:14.611] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:183:0) after 1m0s
[18:49:14.635] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:183:0)
[18:49:14.635] Timer tick, asking Buildbucket for the build status
[18:49:15.567] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:15.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:184:0) after 1m0s
[18:50:15.654] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:184:0)
[18:50:15.654] Timer tick, asking Buildbucket for the build status
[18:50:16.527] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:16.527] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:185:0) after 1m0s
[18:51:16.555] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:185:0)
[18:51:16.555] Timer tick, asking Buildbucket for the build status
[18:51:17.167] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:17.167] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:186:0) after 1m0s
[18:52:17.365] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:186:0)
[18:52:17.365] Timer tick, asking Buildbucket for the build status
[18:52:18.196] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:18.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:187:0) after 1m0s
[18:53:18.253] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:187:0)
[18:53:18.253] Timer tick, asking Buildbucket for the build status
[18:53:19.269] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:19.269] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:188:0) after 1m0s
[18:54:19.314] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:188:0)
[18:54:19.314] Timer tick, asking Buildbucket for the build status
[18:54:19.985] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:19.985] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:189:0) after 1m0s
[18:55:20.085] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:189:0)
[18:55:20.085] Timer tick, asking Buildbucket for the build status
[18:55:20.439] Build 8897344630327085008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:20.439] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9062774692676751872:190:0) after 1m0s
[18:56:04.324] Received PubSub notification, asking Buildbucket for the build status
[18:56:04.766] Build 8897344630327085008: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:56:04.766] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1573239363321840",
"created_by": "project:chromium",
"created_ts": "1573227964049691",
"id": "8897344630327085008",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7647346881f453eca014eeaae93b9e5c65c17cb4\"}}",
"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-xdeu\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 15150, \"got_angle_revision\": \"dc98ca69edd0a803eed76e16d650fa69ea5610f0\", \"got_dawn_revision\": \"16787735601c0b5fe9b485fb79f6c647a51281fd\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"7647346881f453eca014eeaae93b9e5c65c17cb4\", \"got_revision_cp\": \"refs/heads/master@{#713836}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"d9ed1c2732ba9b1bd36461367d348e3030043a25\", \"got_v8_revision\": \"4e6e728c709cf90f23caa83e9813626ed61ae5ad\", \"got_v8_revision_cp\": \"refs/heads/8.0.202@{#1}\", \"got_webrtc_revision\": \"2bc811ea07b7ba4817bcb20035998a23cb4aa71a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29743}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7647346881f453eca014eeaae93b9e5c65c17cb4\"}, \"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-xdeu\"], \"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\": [\"4621-f8a5b1c\"], \"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": "1573228107616212",
"status": "COMPLETED",
"status_changed_ts": "1573239363798257",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/15150",
"builder:Windows deterministic",
"buildset:commit/git/7647346881f453eca014eeaae93b9e5c65c17cb4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7647346881f453eca014eeaae93b9e5c65c17cb4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9062774692676751872",
"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/8897344630327085008/+/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:4864836a9f7d1810",
"user_agent:luci-scheduler"
],
"updated_ts": "1573239363878607",
"url": "https://ci.chromium.org/b/8897344630327085008",
"utcnow_ts": "1573239364746749"
}
}
[18:56:04.766] Invocation finished in 3h10m2.341487545s with status SUCCEEDED