[10:22:54.075] New invocation is queued and will start shortly
[10:22:55.338] Starting the invocation (attempt 1)
[10:22:55.385] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:22:55.385] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:22:55.385] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9087618591645505264",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"395c752c29117df16f39406439be6cc4f25d7f39\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9087618591645505264",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/395c752c29117df16f39406439be6cc4f25d7f39",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/395c752c29117df16f39406439be6cc4f25d7f39",
"gitiles_ref:refs/heads/master"
]
}
[10:22:56.902] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1549534975475796",
"id": "8922188529514749632",
"lease_expiration_ts": "1549653776575256",
"lease_key": "1830298187",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"395c752c29117df16f39406439be6cc4f25d7f39\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1549534976718616",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11876",
"builder:Windows deterministic",
"buildset:commit/git/395c752c29117df16f39406439be6cc4f25d7f39",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/395c752c29117df16f39406439be6cc4f25d7f39",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087618591645505264",
"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/8922188529514749632/+/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:42e04ce0ff4bb610",
"user_agent:luci-scheduler"
],
"updated_ts": "1549534976718830",
"url": "https://ci.chromium.org/b/8922188529514749632",
"utcnow_ts": "1549534976889599"
}
}
[10:22:56.902] Task URL: https://ci.chromium.org/b/8922188529514749632
[10:22:56.902] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:2:0) after 1m0s
[10:22:58.093] Received PubSub notification, asking Buildbucket for the build status
[10:22:58.155] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:56.921] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:2:0)
[10:23:56.921] Timer tick, asking Buildbucket for the build status
[10:23:57.024] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:57.024] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:4:0) after 1m0s
[10:24:57.043] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:4:0)
[10:24:57.043] Timer tick, asking Buildbucket for the build status
[10:24:57.226] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:57.226] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:5:0) after 1m0s
[10:25:57.244] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:5:0)
[10:25:57.244] Timer tick, asking Buildbucket for the build status
[10:25:57.403] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:57.403] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:6:0) after 1m0s
[10:26:57.419] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:6:0)
[10:26:57.419] Timer tick, asking Buildbucket for the build status
[10:26:57.479] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:57.479] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:7:0) after 1m0s
[10:27:57.502] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:7:0)
[10:27:57.502] Timer tick, asking Buildbucket for the build status
[10:27:57.564] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:57.565] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:8:0) after 1m0s
[10:28:57.616] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:8:0)
[10:28:57.616] Timer tick, asking Buildbucket for the build status
[10:28:57.876] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:57.876] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:9:0) after 1m0s
[10:29:57.895] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:9:0)
[10:29:57.895] Timer tick, asking Buildbucket for the build status
[10:29:58.376] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:58.376] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:10:0) after 1m0s
[10:30:58.411] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:10:0)
[10:30:58.411] Timer tick, asking Buildbucket for the build status
[10:30:58.459] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:58.459] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:11:0) after 1m0s
[10:31:58.486] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:11:0)
[10:31:58.486] Timer tick, asking Buildbucket for the build status
[10:31:58.571] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:58.571] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:12:0) after 1m0s
[10:32:58.590] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:12:0)
[10:32:58.590] Timer tick, asking Buildbucket for the build status
[10:32:58.641] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:58.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:13:0) after 1m0s
[10:33:58.660] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:13:0)
[10:33:58.660] Timer tick, asking Buildbucket for the build status
[10:33:58.780] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:58.780] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:14:0) after 1m0s
[10:34:58.803] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:14:0)
[10:34:58.803] Timer tick, asking Buildbucket for the build status
[10:34:58.902] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:58.902] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:15:0) after 1m0s
[10:35:58.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:15:0)
[10:35:58.927] Timer tick, asking Buildbucket for the build status
[10:35:59.027] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:59.027] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:16:0) after 1m0s
[10:36:59.056] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:16:0)
[10:36:59.057] Timer tick, asking Buildbucket for the build status
[10:36:59.231] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:59.231] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:17:0) after 1m0s
[10:37:59.275] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:17:0)
[10:37:59.275] Timer tick, asking Buildbucket for the build status
[10:37:59.468] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:59.468] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:18:0) after 1m0s
[10:38:59.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:18:0)
[10:38:59.495] Timer tick, asking Buildbucket for the build status
[10:38:59.672] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:59.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:19:0) after 1m0s
[10:39:59.696] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:19:0)
[10:39:59.696] Timer tick, asking Buildbucket for the build status
[10:39:59.983] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:59.983] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:20:0) after 1m0s
[10:41:00.012] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:20:0)
[10:41:00.012] Timer tick, asking Buildbucket for the build status
[10:41:00.247] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:00.247] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:21:0) after 1m0s
[10:42:00.271] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:21:0)
[10:42:00.271] Timer tick, asking Buildbucket for the build status
[10:42:00.348] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:00.348] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:22:0) after 1m0s
[10:43:00.370] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:22:0)
[10:43:00.370] Timer tick, asking Buildbucket for the build status
[10:43:00.493] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:00.493] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:23:0) after 1m0s
[10:44:00.516] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:23:0)
[10:44:00.516] Timer tick, asking Buildbucket for the build status
[10:44:00.673] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:00.673] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:24:0) after 1m0s
[10:45:00.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:24:0)
[10:45:00.708] Timer tick, asking Buildbucket for the build status
[10:45:00.888] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:00.888] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:25:0) after 1m0s
[10:46:00.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:25:0)
[10:46:00.911] Timer tick, asking Buildbucket for the build status
[10:46:01.012] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:01.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:26:0) after 1m0s
[10:47:01.050] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:26:0)
[10:47:01.050] Timer tick, asking Buildbucket for the build status
[10:47:01.144] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:01.144] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:27:0) after 1m0s
[10:48:01.213] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:27:0)
[10:48:01.213] Timer tick, asking Buildbucket for the build status
[10:48:01.299] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:01.299] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:28:0) after 1m0s
[10:49:01.330] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:28:0)
[10:49:01.330] Timer tick, asking Buildbucket for the build status
[10:49:01.393] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:01.393] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:29:0) after 1m0s
[10:50:01.418] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:29:0)
[10:50:01.418] Timer tick, asking Buildbucket for the build status
[10:50:01.689] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:01.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:30:0) after 1m0s
[10:51:01.760] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:30:0)
[10:51:01.760] Timer tick, asking Buildbucket for the build status
[10:51:02.017] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:02.017] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:31:0) after 1m0s
[10:52:02.114] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:31:0)
[10:52:02.114] Timer tick, asking Buildbucket for the build status
[10:52:02.217] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:02.217] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:32:0) after 1m0s
[10:53:02.261] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:32:0)
[10:53:02.261] Timer tick, asking Buildbucket for the build status
[10:53:02.377] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:02.377] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:33:0) after 1m0s
[10:54:02.492] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:33:0)
[10:54:02.492] Timer tick, asking Buildbucket for the build status
[10:54:02.564] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:02.564] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:34:0) after 1m0s
[10:55:02.615] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:34:0)
[10:55:02.615] Timer tick, asking Buildbucket for the build status
[10:55:02.711] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:02.712] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:35:0) after 1m0s
[10:56:02.808] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:35:0)
[10:56:02.808] Timer tick, asking Buildbucket for the build status
[10:56:02.991] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:02.991] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:36:0) after 1m0s
[10:57:03.074] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:36:0)
[10:57:03.075] Timer tick, asking Buildbucket for the build status
[10:57:03.125] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:03.125] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:37:0) after 1m0s
[10:58:03.166] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:37:0)
[10:58:03.166] Timer tick, asking Buildbucket for the build status
[10:58:03.264] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:03.264] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:38:0) after 1m0s
[10:59:03.284] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:38:0)
[10:59:03.284] Timer tick, asking Buildbucket for the build status
[10:59:03.389] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:03.389] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:39:0) after 1m0s
[11:00:03.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:39:0)
[11:00:03.417] Timer tick, asking Buildbucket for the build status
[11:00:03.512] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:03.512] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:40:0) after 1m0s
[11:01:03.610] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:40:0)
[11:01:03.610] Timer tick, asking Buildbucket for the build status
[11:01:03.656] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:03.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:41:0) after 1m0s
[11:02:03.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:41:0)
[11:02:03.721] Timer tick, asking Buildbucket for the build status
[11:02:03.800] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:03.800] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:42:0) after 1m0s
[11:03:03.832] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:42:0)
[11:03:03.832] Timer tick, asking Buildbucket for the build status
[11:03:03.884] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:03.884] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:43:0) after 1m0s
[11:04:03.906] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:43:0)
[11:04:03.906] Timer tick, asking Buildbucket for the build status
[11:04:04.065] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:04.065] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:44:0) after 1m0s
[11:05:04.140] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:44:0)
[11:05:04.140] Timer tick, asking Buildbucket for the build status
[11:05:04.194] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:04.194] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:45:0) after 1m0s
[11:06:04.235] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:45:0)
[11:06:04.235] Timer tick, asking Buildbucket for the build status
[11:06:04.539] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:04.539] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:46:0) after 1m0s
[11:07:04.590] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:46:0)
[11:07:04.590] Timer tick, asking Buildbucket for the build status
[11:07:04.684] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:04.684] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:47:0) after 1m0s
[11:08:04.713] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:47:0)
[11:08:04.713] Timer tick, asking Buildbucket for the build status
[11:08:04.816] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:04.816] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:48:0) after 1m0s
[11:09:04.834] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:48:0)
[11:09:04.834] Timer tick, asking Buildbucket for the build status
[11:09:04.915] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:04.915] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:49:0) after 1m0s
[11:10:04.951] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:49:0)
[11:10:04.951] Timer tick, asking Buildbucket for the build status
[11:10:05.038] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:05.038] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:50:0) after 1m0s
[11:11:05.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:50:0)
[11:11:05.083] Timer tick, asking Buildbucket for the build status
[11:11:05.125] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:05.125] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:51:0) after 1m0s
[11:12:05.174] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:51:0)
[11:12:05.174] Timer tick, asking Buildbucket for the build status
[11:12:05.249] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:05.249] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:52:0) after 1m0s
[11:13:05.269] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:52:0)
[11:13:05.269] Timer tick, asking Buildbucket for the build status
[11:13:05.324] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:05.324] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:53:0) after 1m0s
[11:14:05.365] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:53:0)
[11:14:05.365] Timer tick, asking Buildbucket for the build status
[11:14:05.412] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:05.412] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:54:0) after 1m0s
[11:15:05.484] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:54:0)
[11:15:05.484] Timer tick, asking Buildbucket for the build status
[11:15:05.610] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:05.610] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:55:0) after 1m0s
[11:16:05.663] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:55:0)
[11:16:05.663] Timer tick, asking Buildbucket for the build status
[11:16:05.712] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:05.712] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:56:0) after 1m0s
[11:17:05.737] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:56:0)
[11:17:05.737] Timer tick, asking Buildbucket for the build status
[11:17:05.811] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:05.811] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:57:0) after 1m0s
[11:18:05.830] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:57:0)
[11:18:05.830] Timer tick, asking Buildbucket for the build status
[11:18:05.893] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:05.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:58:0) after 1m0s
[11:19:05.961] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:58:0)
[11:19:05.961] Timer tick, asking Buildbucket for the build status
[11:19:06.049] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:06.049] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:59:0) after 1m0s
[11:20:06.147] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:59:0)
[11:20:06.147] Timer tick, asking Buildbucket for the build status
[11:20:06.288] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:06.288] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:60:0) after 1m0s
[11:21:06.312] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:60:0)
[11:21:06.312] Timer tick, asking Buildbucket for the build status
[11:21:06.413] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:06.413] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:61:0) after 1m0s
[11:22:06.437] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:61:0)
[11:22:06.437] Timer tick, asking Buildbucket for the build status
[11:22:07.155] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:07.155] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:62:0) after 1m0s
[11:23:07.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:62:0)
[11:23:07.183] Timer tick, asking Buildbucket for the build status
[11:23:07.274] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:07.274] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:63:0) after 1m0s
[11:24:07.299] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:63:0)
[11:24:07.299] Timer tick, asking Buildbucket for the build status
[11:24:07.571] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:07.571] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:64:0) after 1m0s
[11:25:07.604] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:64:0)
[11:25:07.604] Timer tick, asking Buildbucket for the build status
[11:25:07.663] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:07.663] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:65:0) after 1m0s
[11:26:07.794] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:65:0)
[11:26:07.794] Timer tick, asking Buildbucket for the build status
[11:26:07.875] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:07.875] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:66:0) after 1m0s
[11:27:07.900] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:66:0)
[11:27:07.900] Timer tick, asking Buildbucket for the build status
[11:27:07.975] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:07.975] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:67:0) after 1m0s
[11:28:07.999] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:67:0)
[11:28:07.999] Timer tick, asking Buildbucket for the build status
[11:28:08.072] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:08.072] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:68:0) after 1m0s
[11:29:08.095] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:68:0)
[11:29:08.095] Timer tick, asking Buildbucket for the build status
[11:29:08.204] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:08.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:69:0) after 1m0s
[11:30:08.214] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:69:0)
[11:30:08.214] Timer tick, asking Buildbucket for the build status
[11:30:08.261] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:08.261] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:70:0) after 1m0s
[11:31:08.306] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:70:0)
[11:31:08.306] Timer tick, asking Buildbucket for the build status
[11:31:08.395] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:08.395] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:71:0) after 1m0s
[11:32:08.414] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:71:0)
[11:32:08.414] Timer tick, asking Buildbucket for the build status
[11:32:08.470] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:08.470] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:72:0) after 1m0s
[11:33:08.501] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:72:0)
[11:33:08.501] Timer tick, asking Buildbucket for the build status
[11:33:08.749] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:08.749] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:73:0) after 1m0s
[11:34:08.772] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:73:0)
[11:34:08.772] Timer tick, asking Buildbucket for the build status
[11:34:08.845] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:08.845] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:74:0) after 1m0s
[11:35:08.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:74:0)
[11:35:08.869] Timer tick, asking Buildbucket for the build status
[11:35:08.956] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:08.956] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:75:0) after 1m0s
[11:36:08.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:75:0)
[11:36:08.984] Timer tick, asking Buildbucket for the build status
[11:36:09.036] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:09.036] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:76:0) after 1m0s
[11:37:09.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:76:0)
[11:37:09.087] Timer tick, asking Buildbucket for the build status
[11:37:09.223] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:09.223] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:77:0) after 1m0s
[11:38:09.272] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:77:0)
[11:38:09.272] Timer tick, asking Buildbucket for the build status
[11:38:09.472] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:09.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:78:0) after 1m0s
[11:39:09.503] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:78:0)
[11:39:09.503] Timer tick, asking Buildbucket for the build status
[11:39:09.573] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:09.573] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:79:0) after 1m0s
[11:40:09.595] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:79:0)
[11:40:09.595] Timer tick, asking Buildbucket for the build status
[11:40:09.690] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:09.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:80:0) after 1m0s
[11:41:09.793] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:80:0)
[11:41:09.793] Timer tick, asking Buildbucket for the build status
[11:41:09.921] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:09.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:81:0) after 1m0s
[11:42:09.953] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:81:0)
[11:42:09.953] Timer tick, asking Buildbucket for the build status
[11:42:09.997] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:09.997] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:82:0) after 1m0s
[11:43:10.028] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:82:0)
[11:43:10.028] Timer tick, asking Buildbucket for the build status
[11:43:10.107] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:10.107] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:83:0) after 1m0s
[11:44:10.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:83:0)
[11:44:10.089] Timer tick, asking Buildbucket for the build status
[11:44:10.179] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:10.179] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:84:0) after 1m0s
[11:45:10.200] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:84:0)
[11:45:10.200] Timer tick, asking Buildbucket for the build status
[11:45:10.308] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:10.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:85:0) after 1m0s
[11:46:10.332] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:85:0)
[11:46:10.332] Timer tick, asking Buildbucket for the build status
[11:46:10.376] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:10.376] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:86:0) after 1m0s
[11:47:10.414] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:86:0)
[11:47:10.414] Timer tick, asking Buildbucket for the build status
[11:47:10.564] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:10.564] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:87:0) after 1m0s
[11:48:10.565] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:87:0)
[11:48:10.565] Timer tick, asking Buildbucket for the build status
[11:48:10.648] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:10.648] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:88:0) after 1m0s
[11:49:10.673] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:88:0)
[11:49:10.673] Timer tick, asking Buildbucket for the build status
[11:49:10.868] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:10.868] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:89:0) after 1m0s
[11:50:10.894] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:89:0)
[11:50:10.894] Timer tick, asking Buildbucket for the build status
[11:50:10.995] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:10.995] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:90:0) after 1m0s
[11:51:11.033] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:90:0)
[11:51:11.033] Timer tick, asking Buildbucket for the build status
[11:51:11.156] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:11.156] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:91:0) after 1m0s
[11:52:11.271] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:91:0)
[11:52:11.271] Timer tick, asking Buildbucket for the build status
[11:52:11.454] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:11.454] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:92:0) after 1m0s
[11:53:11.477] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:92:0)
[11:53:11.477] Timer tick, asking Buildbucket for the build status
[11:53:11.519] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:11.519] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:93:0) after 1m0s
[11:54:11.548] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:93:0)
[11:54:11.548] Timer tick, asking Buildbucket for the build status
[11:54:11.751] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:11.751] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:94:0) after 1m0s
[11:55:11.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:94:0)
[11:55:11.773] Timer tick, asking Buildbucket for the build status
[11:55:11.860] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:11.860] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:95:0) after 1m0s
[11:56:11.960] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:95:0)
[11:56:11.960] Timer tick, asking Buildbucket for the build status
[11:56:12.079] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:12.079] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:96:0) after 1m0s
[11:57:12.111] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:96:0)
[11:57:12.111] Timer tick, asking Buildbucket for the build status
[11:57:12.165] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:12.165] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:97:0) after 1m0s
[11:58:12.196] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:97:0)
[11:58:12.196] Timer tick, asking Buildbucket for the build status
[11:58:12.256] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:12.256] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:98:0) after 1m0s
[11:59:12.278] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:98:0)
[11:59:12.278] Timer tick, asking Buildbucket for the build status
[11:59:12.326] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:12.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:99:0) after 1m0s
[12:00:12.426] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:99:0)
[12:00:12.426] Timer tick, asking Buildbucket for the build status
[12:00:12.488] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:12.489] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:100:0) after 1m0s
[12:01:12.512] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:100:0)
[12:01:12.512] Timer tick, asking Buildbucket for the build status
[12:01:12.569] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:12.569] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:101:0) after 1m0s
[12:02:12.624] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:101:0)
[12:02:12.624] Timer tick, asking Buildbucket for the build status
[12:02:12.743] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:12.743] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:102:0) after 1m0s
[12:03:12.771] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:102:0)
[12:03:12.771] Timer tick, asking Buildbucket for the build status
[12:03:12.835] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:12.836] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:103:0) after 1m0s
[12:04:12.900] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:103:0)
[12:04:12.900] Timer tick, asking Buildbucket for the build status
[12:04:13.104] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:13.104] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:104:0) after 1m0s
[12:05:13.127] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:104:0)
[12:05:13.127] Timer tick, asking Buildbucket for the build status
[12:05:13.252] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:13.253] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:105:0) after 1m0s
[12:06:13.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:105:0)
[12:06:13.273] Timer tick, asking Buildbucket for the build status
[12:06:13.391] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:13.391] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:106:0) after 1m0s
[12:07:13.418] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:106:0)
[12:07:13.418] Timer tick, asking Buildbucket for the build status
[12:07:13.559] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:13.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:107:0) after 1m0s
[12:08:13.603] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:107:0)
[12:08:13.603] Timer tick, asking Buildbucket for the build status
[12:08:13.749] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:13.749] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:108:0) after 1m0s
[12:09:13.774] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:108:0)
[12:09:13.774] Timer tick, asking Buildbucket for the build status
[12:09:13.976] Build 8922188529514749632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:13.976] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087618591645505264:109:0) after 1m0s
[12:09:21.601] Received PubSub notification, asking Buildbucket for the build status
[12:09:21.644] Build 8922188529514749632: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:09:21.644] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1549541360751044",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1549534975475796",
"id": "8922188529514749632",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"395c752c29117df16f39406439be6cc4f25d7f39\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"swarm2173-c4\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4064-3687a02\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"got_nacl_revision\": \"ff15c51d20785fa51c0ef03ccb480b87299acbeb\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"395c752c29117df16f39406439be6cc4f25d7f39\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"01a3e9b6383f2169d46e64488a4adb5a0273c15c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26573}\", \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"got_revision_cp\": \"refs/heads/master@{#629922}\", \"branch\": \"refs/heads/master\", \"revision\": \"395c752c29117df16f39406439be6cc4f25d7f39\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"7ca375c8ca54f5ab093204b9aa2bf446be10bcbd\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"603ad164a6fcfac0fce282c604b292db5046b870\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1549534975475796, \"tags\": [\"build_address:luci.chromium.ci/Windows deterministic/11876\", \"builder:Windows deterministic\", \"buildset:commit/git/395c752c29117df16f39406439be6cc4f25d7f39\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/395c752c29117df16f39406439be6cc4f25d7f39\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9087618591645505264\", \"scheduler_job_id:chromium/Windows deterministic\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8922188529514749632\"}}, \"got_v8_revision\": \"69a830d35c1947dcc8d5570bbb023263651e1e46\", \"got_v8_revision_cp\": \"refs/heads/7.4.100@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 11876, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Windows deterministic\", \"key\": \"builder\"}, {\"value\": \"commit/git/395c752c29117df16f39406439be6cc4f25d7f39\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/395c752c29117df16f39406439be6cc4f25d7f39\", \"key\": \"buildset\"}, {\"value\": \"9087618591645505264\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"chromium/Windows deterministic\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"chromium\", \"builder\": \"Windows deterministic\", \"bucket\": \"ci\"}, \"number\": 11876, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"ef156230f5cd7f8c23f689fa4dc7f92742cd904d\"}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8922188529514749632\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"395c752c29117df16f39406439be6cc4f25d7f39\"}}, \"createTime\": \"2019-02-07T10:22:55.475796Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1549534977398602",
"status": "COMPLETED",
"status_changed_ts": "1549541361309390",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11876",
"builder:Windows deterministic",
"buildset:commit/git/395c752c29117df16f39406439be6cc4f25d7f39",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/395c752c29117df16f39406439be6cc4f25d7f39",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087618591645505264",
"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/8922188529514749632/+/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:42e04ce0ff4bb610",
"user_agent:luci-scheduler"
],
"updated_ts": "1549541361311386",
"url": "https://ci.chromium.org/b/8922188529514749632",
"utcnow_ts": "1549541361635117"
}
}
[12:09:21.644] Invocation finished in 1h46m27.585663538s with status SUCCEEDED