[07:46:54.543] New invocation is queued and will start shortly
[07:46:55.718] Starting the invocation (attempt 1)
[07:46:55.763] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:46:55.763] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:46:55.763] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9079565275828066976",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"0d359b321c0e40032edf71c9f7bf42a26bec03ee\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9079565275828066976",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/0d359b321c0e40032edf71c9f7bf42a26bec03ee",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0d359b321c0e40032edf71c9f7bf42a26bec03ee",
"gitiles_ref:refs/heads/master"
]
}
[07:46:57.602] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1557215216216737",
"id": "8914135213398840672",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0d359b321c0e40032edf71c9f7bf42a26bec03ee\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557215217328445",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13256",
"builder:Windows deterministic",
"buildset:commit/git/0d359b321c0e40032edf71c9f7bf42a26bec03ee",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0d359b321c0e40032edf71c9f7bf42a26bec03ee",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079565275828066976",
"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/8914135213398840672/+/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": "1557215217328589",
"url": "https://ci.chromium.org/b/8914135213398840672",
"utcnow_ts": "1557215217591039"
}
}
[07:46:57.602] Task URL: https://ci.chromium.org/b/8914135213398840672
[07:46:57.602] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:2:0) after 1m0s
[07:47:31.394] Received PubSub notification, asking Buildbucket for the build status
[07:47:31.556] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:57.614] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:2:0)
[07:47:57.614] Timer tick, asking Buildbucket for the build status
[07:47:57.978] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:57.978] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:4:0) after 1m0s
[07:48:57.997] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:4:0)
[07:48:57.997] Timer tick, asking Buildbucket for the build status
[07:48:59.336] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:59.336] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:5:0) after 1m0s
[07:49:59.354] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:5:0)
[07:49:59.354] Timer tick, asking Buildbucket for the build status
[07:49:59.485] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:59.485] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:6:0) after 1m0s
[07:50:59.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:6:0)
[07:50:59.495] Timer tick, asking Buildbucket for the build status
[07:50:59.619] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:59.619] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:7:0) after 1m0s
[07:51:59.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:7:0)
[07:51:59.637] Timer tick, asking Buildbucket for the build status
[07:51:59.826] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:59.826] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:8:0) after 1m0s
[07:52:59.843] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:8:0)
[07:52:59.843] Timer tick, asking Buildbucket for the build status
[07:53:02.243] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:02.243] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:9:0) after 1m0s
[07:54:02.266] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:9:0)
[07:54:02.266] Timer tick, asking Buildbucket for the build status
[07:54:03.390] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:03.390] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:10:0) after 1m0s
[07:55:03.406] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:10:0)
[07:55:03.406] Timer tick, asking Buildbucket for the build status
[07:55:04.926] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:04.926] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:11:0) after 1m0s
[07:56:04.952] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:11:0)
[07:56:04.952] Timer tick, asking Buildbucket for the build status
[07:56:05.271] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:05.271] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:12:0) after 1m0s
[07:57:05.289] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:12:0)
[07:57:05.289] Timer tick, asking Buildbucket for the build status
[07:57:06.225] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:06.225] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:13:0) after 1m0s
[07:58:06.255] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:13:0)
[07:58:06.255] Timer tick, asking Buildbucket for the build status
[07:58:08.545] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:08.545] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:14:0) after 1m0s
[07:59:08.566] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:14:0)
[07:59:08.566] Timer tick, asking Buildbucket for the build status
[07:59:16.647] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:16.647] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:15:0) after 1m0s
[08:00:16.670] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:15:0)
[08:00:16.670] Timer tick, asking Buildbucket for the build status
[08:00:17.765] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:17.765] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:16:0) after 1m0s
[08:01:17.747] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:16:0)
[08:01:17.747] Timer tick, asking Buildbucket for the build status
[08:01:18.110] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:18.110] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:17:0) after 1m0s
[08:02:18.086] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:17:0)
[08:02:18.086] Timer tick, asking Buildbucket for the build status
[08:02:18.290] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:18.290] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:18:0) after 1m0s
[08:03:18.264] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:18:0)
[08:03:18.264] Timer tick, asking Buildbucket for the build status
[08:03:19.504] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:19.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:19:0) after 1m0s
[08:04:19.529] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:19:0)
[08:04:19.529] Timer tick, asking Buildbucket for the build status
[08:04:19.699] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:19.699] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:20:0) after 1m0s
[08:05:19.718] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:20:0)
[08:05:19.718] Timer tick, asking Buildbucket for the build status
[08:05:21.617] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:21.617] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:21:0) after 1m0s
[08:06:21.617] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:21:0)
[08:06:21.617] Timer tick, asking Buildbucket for the build status
[08:06:23.707] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:23.707] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:22:0) after 1m0s
[08:07:23.857] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:22:0)
[08:07:23.857] Timer tick, asking Buildbucket for the build status
[08:07:26.575] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:26.575] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:23:0) after 1m0s
[08:08:26.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:23:0)
[08:08:26.602] Timer tick, asking Buildbucket for the build status
[08:08:26.724] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:26.724] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:24:0) after 1m0s
[08:09:26.709] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:24:0)
[08:09:26.709] Timer tick, asking Buildbucket for the build status
[08:09:27.315] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:27.315] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:25:0) after 1m0s
[08:10:27.302] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:25:0)
[08:10:27.302] Timer tick, asking Buildbucket for the build status
[08:10:28.060] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:28.060] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:26:0) after 1m0s
[08:11:28.085] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:26:0)
[08:11:28.085] Timer tick, asking Buildbucket for the build status
[08:11:30.422] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:30.422] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:27:0) after 1m0s
[08:12:30.423] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:27:0)
[08:12:30.423] Timer tick, asking Buildbucket for the build status
[08:12:31.618] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:31.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:28:0) after 1m0s
[08:13:31.628] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:28:0)
[08:13:31.628] Timer tick, asking Buildbucket for the build status
[08:13:32.139] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:32.139] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:29:0) after 1m0s
[08:14:32.147] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:29:0)
[08:14:32.147] Timer tick, asking Buildbucket for the build status
[08:14:32.621] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:32.621] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:30:0) after 1m0s
[08:15:32.642] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:30:0)
[08:15:32.642] Timer tick, asking Buildbucket for the build status
[08:15:32.798] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:32.798] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:31:0) after 1m0s
[08:16:32.814] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:31:0)
[08:16:32.814] Timer tick, asking Buildbucket for the build status
[08:16:34.518] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:34.518] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:32:0) after 1m0s
[08:17:34.520] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:32:0)
[08:17:34.520] Timer tick, asking Buildbucket for the build status
[08:17:34.747] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:34.747] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:33:0) after 1m0s
[08:18:34.723] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:33:0)
[08:18:34.723] Timer tick, asking Buildbucket for the build status
[08:18:35.346] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:35.346] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:34:0) after 1m0s
[08:19:35.385] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:34:0)
[08:19:35.385] Timer tick, asking Buildbucket for the build status
[08:19:35.524] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:35.524] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:35:0) after 1m0s
[08:20:35.545] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:35:0)
[08:20:35.545] Timer tick, asking Buildbucket for the build status
[08:20:35.729] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:35.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:36:0) after 1m0s
[08:21:35.747] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:36:0)
[08:21:35.747] Timer tick, asking Buildbucket for the build status
[08:21:36.103] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:36.103] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:37:0) after 1m0s
[08:22:36.131] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:37:0)
[08:22:36.131] Timer tick, asking Buildbucket for the build status
[08:22:38.775] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:38.775] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:38:0) after 1m0s
[08:23:38.760] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:38:0)
[08:23:38.760] Timer tick, asking Buildbucket for the build status
[08:23:38.831] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:38.831] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:39:0) after 1m0s
[08:24:38.844] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:39:0)
[08:24:38.844] Timer tick, asking Buildbucket for the build status
[08:24:40.023] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:40.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:40:0) after 1m0s
[08:25:40.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:40:0)
[08:25:40.045] Timer tick, asking Buildbucket for the build status
[08:25:40.152] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:40.152] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:41:0) after 1m0s
[08:26:40.181] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:41:0)
[08:26:40.181] Timer tick, asking Buildbucket for the build status
[08:26:41.973] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:41.973] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:42:0) after 1m0s
[08:27:41.992] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:42:0)
[08:27:41.992] Timer tick, asking Buildbucket for the build status
[08:27:42.115] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:42.115] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:43:0) after 1m0s
[08:28:42.143] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:43:0)
[08:28:42.143] Timer tick, asking Buildbucket for the build status
[08:28:42.550] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:42.550] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:44:0) after 1m0s
[08:29:42.571] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:44:0)
[08:29:42.571] Timer tick, asking Buildbucket for the build status
[08:29:43.212] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:43.212] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:45:0) after 1m0s
[08:30:43.233] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:45:0)
[08:30:43.233] Timer tick, asking Buildbucket for the build status
[08:30:43.582] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:43.582] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:46:0) after 1m0s
[08:31:43.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:46:0)
[08:31:43.602] Timer tick, asking Buildbucket for the build status
[08:31:44.196] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:44.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:47:0) after 1m0s
[08:32:44.190] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:47:0)
[08:32:44.190] Timer tick, asking Buildbucket for the build status
[08:32:50.360] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:50.360] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:48:0) after 1m0s
[08:33:50.373] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:48:0)
[08:33:50.373] Timer tick, asking Buildbucket for the build status
[08:33:52.596] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:52.596] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:49:0) after 1m0s
[08:34:52.585] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:49:0)
[08:34:52.585] Timer tick, asking Buildbucket for the build status
[08:34:52.883] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:52.883] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:50:0) after 1m0s
[08:35:52.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:50:0)
[08:35:52.910] Timer tick, asking Buildbucket for the build status
[08:35:54.467] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:54.467] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:51:0) after 1m0s
[08:36:54.448] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:51:0)
[08:36:54.448] Timer tick, asking Buildbucket for the build status
[08:36:56.555] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:56.555] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:52:0) after 1m0s
[08:37:56.574] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:52:0)
[08:37:56.574] Timer tick, asking Buildbucket for the build status
[08:37:56.963] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:56.963] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:53:0) after 1m0s
[08:38:56.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:53:0)
[08:38:56.990] Timer tick, asking Buildbucket for the build status
[08:38:59.409] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:59.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:54:0) after 1m0s
[08:39:59.430] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:54:0)
[08:39:59.430] Timer tick, asking Buildbucket for the build status
[08:39:59.559] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:59.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:55:0) after 1m0s
[08:40:59.546] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:55:0)
[08:40:59.546] Timer tick, asking Buildbucket for the build status
[08:41:01.816] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:01.816] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:56:0) after 1m0s
[08:42:01.838] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:56:0)
[08:42:01.838] Timer tick, asking Buildbucket for the build status
[08:42:02.287] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:02.287] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:57:0) after 1m0s
[08:43:02.288] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:57:0)
[08:43:02.288] Timer tick, asking Buildbucket for the build status
[08:43:03.871] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:03.871] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:58:0) after 1m0s
[08:44:03.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:58:0)
[08:44:03.910] Timer tick, asking Buildbucket for the build status
[08:44:06.770] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:06.770] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:59:0) after 1m0s
[08:45:06.794] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:59:0)
[08:45:06.794] Timer tick, asking Buildbucket for the build status
[08:45:06.947] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:06.947] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:60:0) after 1m0s
[08:46:06.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:60:0)
[08:46:06.968] Timer tick, asking Buildbucket for the build status
[08:46:09.502] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:09.502] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:61:0) after 1m0s
[08:47:09.554] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:61:0)
[08:47:09.554] Timer tick, asking Buildbucket for the build status
[08:47:09.932] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:09.932] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:62:0) after 1m0s
[08:48:09.933] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:62:0)
[08:48:09.933] Timer tick, asking Buildbucket for the build status
[08:48:12.107] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:12.107] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:63:0) after 1m0s
[08:49:12.110] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:63:0)
[08:49:12.110] Timer tick, asking Buildbucket for the build status
[08:49:12.304] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:12.304] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:64:0) after 1m0s
[08:50:12.326] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:64:0)
[08:50:12.326] Timer tick, asking Buildbucket for the build status
[08:50:13.602] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:13.602] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:65:0) after 1m0s
[08:51:13.642] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:65:0)
[08:51:13.642] Timer tick, asking Buildbucket for the build status
[08:51:14.155] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:14.155] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:66:0) after 1m0s
[08:52:14.180] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:66:0)
[08:52:14.180] Timer tick, asking Buildbucket for the build status
[08:52:14.725] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:14.725] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:67:0) after 1m0s
[08:53:14.746] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:67:0)
[08:53:14.746] Timer tick, asking Buildbucket for the build status
[08:53:14.888] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:14.888] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:68:0) after 1m0s
[08:54:14.861] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:68:0)
[08:54:14.861] Timer tick, asking Buildbucket for the build status
[08:54:15.021] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:15.021] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:69:0) after 1m0s
[08:55:15.022] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:69:0)
[08:55:15.022] Timer tick, asking Buildbucket for the build status
[08:55:15.499] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:15.499] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:70:0) after 1m0s
[08:56:15.539] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:70:0)
[08:56:15.539] Timer tick, asking Buildbucket for the build status
[08:56:16.470] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:16.470] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:71:0) after 1m0s
[08:57:16.492] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:71:0)
[08:57:16.492] Timer tick, asking Buildbucket for the build status
[08:57:16.685] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:16.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:72:0) after 1m0s
[08:58:16.660] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:72:0)
[08:58:16.660] Timer tick, asking Buildbucket for the build status
[08:58:17.626] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:17.626] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:73:0) after 1m0s
[08:59:17.642] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:73:0)
[08:59:17.642] Timer tick, asking Buildbucket for the build status
[08:59:19.778] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:19.778] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:74:0) after 1m0s
[09:00:19.847] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:74:0)
[09:00:19.847] Timer tick, asking Buildbucket for the build status
[09:00:19.996] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:19.996] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:75:0) after 1m0s
[09:01:20.042] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:75:0)
[09:01:20.042] Timer tick, asking Buildbucket for the build status
[09:01:21.329] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:21.329] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:76:0) after 1m0s
[09:02:21.349] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:76:0)
[09:02:21.349] Timer tick, asking Buildbucket for the build status
[09:02:21.989] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:21.989] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:77:0) after 1m0s
[09:03:22.009] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:77:0)
[09:03:22.009] Timer tick, asking Buildbucket for the build status
[09:03:22.483] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:22.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:78:0) after 1m0s
[09:04:22.484] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:78:0)
[09:04:22.484] Timer tick, asking Buildbucket for the build status
[09:04:22.666] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:22.666] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:79:0) after 1m0s
[09:05:22.670] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:79:0)
[09:05:22.670] Timer tick, asking Buildbucket for the build status
[09:05:22.946] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:22.947] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:80:0) after 1m0s
[09:06:22.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:80:0)
[09:06:22.972] Timer tick, asking Buildbucket for the build status
[09:06:24.397] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:24.397] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:81:0) after 1m0s
[09:07:24.394] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:81:0)
[09:07:24.394] Timer tick, asking Buildbucket for the build status
[09:07:26.622] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:26.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:82:0) after 1m0s
[09:08:26.630] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:82:0)
[09:08:26.630] Timer tick, asking Buildbucket for the build status
[09:08:27.073] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:27.073] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:83:0) after 1m0s
[09:09:27.098] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:83:0)
[09:09:27.098] Timer tick, asking Buildbucket for the build status
[09:09:29.275] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:29.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:84:0) after 1m0s
[09:10:29.297] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:84:0)
[09:10:29.297] Timer tick, asking Buildbucket for the build status
[09:10:30.654] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:30.654] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:85:0) after 1m0s
[09:11:30.631] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:85:0)
[09:11:30.631] Timer tick, asking Buildbucket for the build status
[09:11:34.356] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:34.356] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:86:0) after 1m0s
[09:12:34.379] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:86:0)
[09:12:34.379] Timer tick, asking Buildbucket for the build status
[09:12:36.929] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:36.929] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:87:0) after 1m0s
[09:13:36.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:87:0)
[09:13:36.968] Timer tick, asking Buildbucket for the build status
[09:13:38.896] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:38.896] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:88:0) after 1m0s
[09:14:38.916] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:88:0)
[09:14:38.916] Timer tick, asking Buildbucket for the build status
[09:14:39.797] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:39.797] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:89:0) after 1m0s
[09:15:39.825] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:89:0)
[09:15:39.825] Timer tick, asking Buildbucket for the build status
[09:15:41.003] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:41.003] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:90:0) after 1m0s
[09:16:41.026] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:90:0)
[09:16:41.026] Timer tick, asking Buildbucket for the build status
[09:16:41.485] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:41.485] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:91:0) after 1m0s
[09:17:41.505] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:91:0)
[09:17:41.505] Timer tick, asking Buildbucket for the build status
[09:17:42.065] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:42.065] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:92:0) after 1m0s
[09:18:42.065] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:92:0)
[09:18:42.065] Timer tick, asking Buildbucket for the build status
[09:18:43.468] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:43.468] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:93:0) after 1m0s
[09:19:43.490] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:93:0)
[09:19:43.490] Timer tick, asking Buildbucket for the build status
[09:19:43.796] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:43.796] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:94:0) after 1m0s
[09:20:43.806] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:94:0)
[09:20:43.806] Timer tick, asking Buildbucket for the build status
[09:20:44.177] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:44.178] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:95:0) after 1m0s
[09:21:44.200] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:95:0)
[09:21:44.200] Timer tick, asking Buildbucket for the build status
[09:21:44.392] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:44.392] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:96:0) after 1m0s
[09:22:44.415] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:96:0)
[09:22:44.415] Timer tick, asking Buildbucket for the build status
[09:22:46.268] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:46.268] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:97:0) after 1m0s
[09:23:46.288] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:97:0)
[09:23:46.288] Timer tick, asking Buildbucket for the build status
[09:23:47.507] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:47.508] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:98:0) after 1m0s
[09:24:47.529] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:98:0)
[09:24:47.529] Timer tick, asking Buildbucket for the build status
[09:24:50.406] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:50.406] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:99:0) after 1m0s
[09:25:50.432] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:99:0)
[09:25:50.432] Timer tick, asking Buildbucket for the build status
[09:25:51.062] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:51.062] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:100:0) after 1m0s
[09:26:51.085] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:100:0)
[09:26:51.085] Timer tick, asking Buildbucket for the build status
[09:26:52.376] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:52.376] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:101:0) after 1m0s
[09:27:52.400] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:101:0)
[09:27:52.400] Timer tick, asking Buildbucket for the build status
[09:27:54.177] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:54.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:102:0) after 1m0s
[09:28:54.219] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:102:0)
[09:28:54.219] Timer tick, asking Buildbucket for the build status
[09:28:56.246] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:56.246] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:103:0) after 1m0s
[09:29:56.258] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:103:0)
[09:29:56.258] Timer tick, asking Buildbucket for the build status
[09:29:57.262] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:57.262] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:104:0) after 1m0s
[09:30:57.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:104:0)
[09:30:57.273] Timer tick, asking Buildbucket for the build status
[09:30:59.965] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:59.965] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:105:0) after 1m0s
[09:32:00.088] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:105:0)
[09:32:00.088] Timer tick, asking Buildbucket for the build status
[09:32:02.657] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:02.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:106:0) after 1m0s
[09:33:02.700] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:106:0)
[09:33:02.700] Timer tick, asking Buildbucket for the build status
[09:33:03.294] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:03.294] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:107:0) after 1m0s
[09:34:03.318] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:107:0)
[09:34:03.318] Timer tick, asking Buildbucket for the build status
[09:34:10.233] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:10.233] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:108:0) after 1m0s
[09:35:10.249] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:108:0)
[09:35:10.249] Timer tick, asking Buildbucket for the build status
[09:35:10.354] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:10.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:109:0) after 1m0s
[09:36:10.391] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:109:0)
[09:36:10.392] Timer tick, asking Buildbucket for the build status
[09:36:12.505] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:12.505] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:110:0) after 1m0s
[09:37:12.536] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:110:0)
[09:37:12.536] Timer tick, asking Buildbucket for the build status
[09:37:14.623] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:14.623] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:111:0) after 1m0s
[09:38:14.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:111:0)
[09:38:14.602] Timer tick, asking Buildbucket for the build status
[09:38:14.910] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:14.910] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:112:0) after 1m0s
[09:39:14.921] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:112:0)
[09:39:14.921] Timer tick, asking Buildbucket for the build status
[09:39:17.081] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:17.081] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:113:0) after 1m0s
[09:40:17.109] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:113:0)
[09:40:17.109] Timer tick, asking Buildbucket for the build status
[09:40:17.753] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:17.753] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:114:0) after 1m0s
[09:41:17.782] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:114:0)
[09:41:17.782] Timer tick, asking Buildbucket for the build status
[09:41:17.897] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:17.897] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:115:0) after 1m0s
[09:42:17.921] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:115:0)
[09:42:17.921] Timer tick, asking Buildbucket for the build status
[09:42:20.415] Build 8914135213398840672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:20.416] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079565275828066976:116:0) after 1m0s
[09:42:23.598] Received PubSub notification, asking Buildbucket for the build status
[09:42:23.910] Build 8914135213398840672: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:42:23.910] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1557222142211267",
"created_by": "project:chromium",
"created_ts": "1557215216216737",
"id": "8914135213398840672",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0d359b321c0e40032edf71c9f7bf42a26bec03ee\"}}",
"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-2l8d\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 13256, \"got_angle_revision\": \"0f4d72e63b920036fd3800d26a3b7bd202e40759\", \"got_dawn_revision\": \"8804bc541ebb71b1646bc1c842c65c85e46d9ef3\", \"got_nacl_revision\": \"ca374f5112fca6b36fc5d73e117a60b82f394993\", \"got_revision\": \"0d359b321c0e40032edf71c9f7bf42a26bec03ee\", \"got_revision_cp\": \"refs/heads/master@{#657156}\", \"got_swarming_client_revision\": \"1b65f4e862045f3ba430a4cbd0643f5b1b66c230\", \"got_v8_revision\": \"500b858e326993ada2963b6ac399123a8acf731b\", \"got_v8_revision_cp\": \"refs/heads/7.6.107@{#1}\", \"got_webrtc_revision\": \"4fb12b0caec9faa57cfbceb0f86b0e10c32a0cc2\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27828}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0d359b321c0e40032edf71c9f7bf42a26bec03ee\"}, \"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-2l8d\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4289-d945138\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1557215241355470",
"status": "COMPLETED",
"status_changed_ts": "1557222143193501",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13256",
"builder:Windows deterministic",
"buildset:commit/git/0d359b321c0e40032edf71c9f7bf42a26bec03ee",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0d359b321c0e40032edf71c9f7bf42a26bec03ee",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079565275828066976",
"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/8914135213398840672/+/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:44aa142f06b2c610",
"user_agent:luci-scheduler"
],
"updated_ts": "1557222143211399",
"url": "https://ci.chromium.org/b/8914135213398840672",
"utcnow_ts": "1557222143894903"
}
}
[09:42:23.910] Invocation finished in 1h55m29.394486168s with status SUCCEEDED