[13:42:32.215] New invocation is queued and will start shortly
[13:42:33.345] Starting the invocation (attempt 1)
[13:42:33.426] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:42:33.426] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:42:33.426] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9085703495361547744",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"72511a7e57f7353b83d96d35b603545efaf5fa6b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9085703495361547744",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/72511a7e57f7353b83d96d35b603545efaf5fa6b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/72511a7e57f7353b83d96d35b603545efaf5fa6b",
"gitiles_ref:refs/heads/master"
]
}
[13:42:35.305] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551361353532926",
"id": "8920273433316809680",
"lease_expiration_ts": "1551480154756095",
"lease_key": "2047025811",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"72511a7e57f7353b83d96d35b603545efaf5fa6b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551361354917667",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12241",
"builder:Windows deterministic",
"buildset:commit/git/72511a7e57f7353b83d96d35b603545efaf5fa6b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/72511a7e57f7353b83d96d35b603545efaf5fa6b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085703495361547744",
"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/8920273433316809680/+/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:434d29331bf90b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1551361354917869",
"url": "https://ci.chromium.org/b/8920273433316809680",
"utcnow_ts": "1551361355295813"
}
}
[13:42:35.306] Task URL: https://ci.chromium.org/b/8920273433316809680
[13:42:35.306] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:2:0) after 1m0s
[13:43:35.322] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:2:0)
[13:43:35.322] Timer tick, asking Buildbucket for the build status
[13:43:35.654] Build 8920273433316809680: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:43:35.654] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:3:0) after 1m0s
[13:44:23.077] Received PubSub notification, asking Buildbucket for the build status
[13:44:23.368] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:35.662] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:3:0)
[13:44:35.662] Timer tick, asking Buildbucket for the build status
[13:44:35.709] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:35.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:5:0) after 1m0s
[13:45:35.719] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:5:0)
[13:45:35.719] Timer tick, asking Buildbucket for the build status
[13:45:35.832] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:35.832] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:6:0) after 1m0s
[13:46:35.831] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:6:0)
[13:46:35.831] Timer tick, asking Buildbucket for the build status
[13:46:35.892] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:35.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:7:0) after 1m0s
[13:47:35.903] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:7:0)
[13:47:35.903] Timer tick, asking Buildbucket for the build status
[13:47:35.949] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:35.949] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:8:0) after 1m0s
[13:48:35.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:8:0)
[13:48:35.938] Timer tick, asking Buildbucket for the build status
[13:48:35.983] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:35.983] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:9:0) after 1m0s
[13:49:35.967] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:9:0)
[13:49:35.967] Timer tick, asking Buildbucket for the build status
[13:49:36.026] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:36.026] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:10:0) after 1m0s
[13:50:36.029] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:10:0)
[13:50:36.029] Timer tick, asking Buildbucket for the build status
[13:50:36.207] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:36.207] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:11:0) after 1m0s
[13:51:36.213] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:11:0)
[13:51:36.213] Timer tick, asking Buildbucket for the build status
[13:51:36.260] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:36.260] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:12:0) after 1m0s
[13:52:36.243] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:12:0)
[13:52:36.243] Timer tick, asking Buildbucket for the build status
[13:52:36.327] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:36.327] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:13:0) after 1m0s
[13:53:36.333] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:13:0)
[13:53:36.333] Timer tick, asking Buildbucket for the build status
[13:53:36.410] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:36.410] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:14:0) after 1m0s
[13:54:36.430] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:14:0)
[13:54:36.430] Timer tick, asking Buildbucket for the build status
[13:54:36.492] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:36.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:15:0) after 1m0s
[13:55:36.509] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:15:0)
[13:55:36.509] Timer tick, asking Buildbucket for the build status
[13:55:36.592] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:36.592] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:16:0) after 1m0s
[13:56:36.583] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:16:0)
[13:56:36.583] Timer tick, asking Buildbucket for the build status
[13:56:36.658] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:36.658] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:17:0) after 1m0s
[13:57:36.662] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:17:0)
[13:57:36.663] Timer tick, asking Buildbucket for the build status
[13:57:36.724] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:36.724] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:18:0) after 1m0s
[13:58:36.799] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:18:0)
[13:58:36.799] Timer tick, asking Buildbucket for the build status
[13:58:36.897] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:36.897] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:19:0) after 1m0s
[13:59:36.913] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:19:0)
[13:59:36.913] Timer tick, asking Buildbucket for the build status
[13:59:37.209] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:37.209] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:20:0) after 1m0s
[14:00:37.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:20:0)
[14:00:37.228] Timer tick, asking Buildbucket for the build status
[14:00:37.273] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:37.273] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:21:0) after 1m0s
[14:01:37.294] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:21:0)
[14:01:37.294] Timer tick, asking Buildbucket for the build status
[14:01:37.358] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:37.358] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:22:0) after 1m0s
[14:02:37.409] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:22:0)
[14:02:37.409] Timer tick, asking Buildbucket for the build status
[14:02:37.472] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:37.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:23:0) after 1m0s
[14:03:37.492] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:23:0)
[14:03:37.492] Timer tick, asking Buildbucket for the build status
[14:03:37.832] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:37.832] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:24:0) after 1m0s
[14:04:37.857] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:24:0)
[14:04:37.857] Timer tick, asking Buildbucket for the build status
[14:04:37.937] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:37.937] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:25:0) after 1m0s
[14:05:37.914] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:25:0)
[14:05:37.914] Timer tick, asking Buildbucket for the build status
[14:05:38.181] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:38.181] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:26:0) after 1m0s
[14:06:38.200] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:26:0)
[14:06:38.200] Timer tick, asking Buildbucket for the build status
[14:06:38.242] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:38.242] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:27:0) after 1m0s
[14:07:38.263] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:27:0)
[14:07:38.263] Timer tick, asking Buildbucket for the build status
[14:07:38.397] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:38.397] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:28:0) after 1m0s
[14:08:38.418] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:28:0)
[14:08:38.418] Timer tick, asking Buildbucket for the build status
[14:08:38.482] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:38.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:29:0) after 1m0s
[14:09:38.503] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:29:0)
[14:09:38.503] Timer tick, asking Buildbucket for the build status
[14:09:38.581] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:38.581] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:30:0) after 1m0s
[14:10:38.603] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:30:0)
[14:10:38.603] Timer tick, asking Buildbucket for the build status
[14:10:38.662] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:38.662] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:31:0) after 1m0s
[14:11:38.679] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:31:0)
[14:11:38.679] Timer tick, asking Buildbucket for the build status
[14:11:38.904] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:38.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:32:0) after 1m0s
[14:12:38.932] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:32:0)
[14:12:38.932] Timer tick, asking Buildbucket for the build status
[14:12:39.156] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:39.156] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:33:0) after 1m0s
[14:13:39.158] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:33:0)
[14:13:39.158] Timer tick, asking Buildbucket for the build status
[14:13:39.328] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:39.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:34:0) after 1m0s
[14:14:39.304] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:34:0)
[14:14:39.304] Timer tick, asking Buildbucket for the build status
[14:14:39.482] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:39.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:35:0) after 1m0s
[14:15:39.472] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:35:0)
[14:15:39.472] Timer tick, asking Buildbucket for the build status
[14:15:39.546] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:39.546] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:36:0) after 1m0s
[14:16:39.554] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:36:0)
[14:16:39.554] Timer tick, asking Buildbucket for the build status
[14:16:39.626] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:39.626] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:37:0) after 1m0s
[14:17:39.655] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:37:0)
[14:17:39.655] Timer tick, asking Buildbucket for the build status
[14:17:39.848] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:39.848] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:38:0) after 1m0s
[14:18:39.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:38:0)
[14:18:39.869] Timer tick, asking Buildbucket for the build status
[14:18:39.998] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:39.998] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:39:0) after 1m0s
[14:19:40.046] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:39:0)
[14:19:40.046] Timer tick, asking Buildbucket for the build status
[14:19:40.102] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:40.102] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:40:0) after 1m0s
[14:20:40.150] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:40:0)
[14:20:40.150] Timer tick, asking Buildbucket for the build status
[14:20:40.321] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:40.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:41:0) after 1m0s
[14:21:40.315] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:41:0)
[14:21:40.315] Timer tick, asking Buildbucket for the build status
[14:21:40.496] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:40.496] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:42:0) after 1m0s
[14:22:40.530] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:42:0)
[14:22:40.530] Timer tick, asking Buildbucket for the build status
[14:22:40.574] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:40.574] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:43:0) after 1m0s
[14:23:40.615] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:43:0)
[14:23:40.615] Timer tick, asking Buildbucket for the build status
[14:23:40.979] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:40.979] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:44:0) after 1m0s
[14:24:41.012] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:44:0)
[14:24:41.012] Timer tick, asking Buildbucket for the build status
[14:24:41.148] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:41.148] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:45:0) after 1m0s
[14:25:41.169] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:45:0)
[14:25:41.169] Timer tick, asking Buildbucket for the build status
[14:25:41.386] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:41.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:46:0) after 1m0s
[14:26:41.411] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:46:0)
[14:26:41.411] Timer tick, asking Buildbucket for the build status
[14:26:41.621] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:41.621] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:47:0) after 1m0s
[14:27:41.611] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:47:0)
[14:27:41.611] Timer tick, asking Buildbucket for the build status
[14:27:41.718] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:41.718] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:48:0) after 1m0s
[14:28:41.731] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:48:0)
[14:28:41.731] Timer tick, asking Buildbucket for the build status
[14:28:41.876] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:41.876] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:49:0) after 1m0s
[14:29:41.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:49:0)
[14:29:41.905] Timer tick, asking Buildbucket for the build status
[14:29:41.971] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:41.971] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:50:0) after 1m0s
[14:30:41.964] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:50:0)
[14:30:41.964] Timer tick, asking Buildbucket for the build status
[14:30:42.026] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:42.026] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:51:0) after 1m0s
[14:31:42.054] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:51:0)
[14:31:42.054] Timer tick, asking Buildbucket for the build status
[14:31:42.108] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:42.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:52:0) after 1m0s
[14:32:42.139] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:52:0)
[14:32:42.139] Timer tick, asking Buildbucket for the build status
[14:32:42.368] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:42.368] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:53:0) after 1m0s
[14:33:42.409] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:53:0)
[14:33:42.409] Timer tick, asking Buildbucket for the build status
[14:33:42.571] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:42.571] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:54:0) after 1m0s
[14:34:42.576] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:54:0)
[14:34:42.576] Timer tick, asking Buildbucket for the build status
[14:34:42.657] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:42.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:55:0) after 1m0s
[14:35:42.632] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:55:0)
[14:35:42.632] Timer tick, asking Buildbucket for the build status
[14:35:42.819] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:42.819] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:56:0) after 1m0s
[14:36:42.842] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:56:0)
[14:36:42.842] Timer tick, asking Buildbucket for the build status
[14:36:42.897] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:42.897] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:57:0) after 1m0s
[14:37:42.918] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:57:0)
[14:37:42.918] Timer tick, asking Buildbucket for the build status
[14:37:42.958] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:42.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:58:0) after 1m0s
[14:38:43.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:58:0)
[14:38:43.003] Timer tick, asking Buildbucket for the build status
[14:38:43.056] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:43.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:59:0) after 1m0s
[14:39:43.086] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:59:0)
[14:39:43.086] Timer tick, asking Buildbucket for the build status
[14:39:43.494] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:43.494] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:60:0) after 1m0s
[14:40:43.485] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:60:0)
[14:40:43.485] Timer tick, asking Buildbucket for the build status
[14:40:43.546] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:43.546] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:61:0) after 1m0s
[14:41:43.553] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:61:0)
[14:41:43.553] Timer tick, asking Buildbucket for the build status
[14:41:43.676] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:43.676] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:62:0) after 1m0s
[14:42:43.655] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:62:0)
[14:42:43.655] Timer tick, asking Buildbucket for the build status
[14:42:43.810] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:43.810] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:63:0) after 1m0s
[14:43:43.827] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:63:0)
[14:43:43.827] Timer tick, asking Buildbucket for the build status
[14:43:43.867] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:43.867] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:64:0) after 1m0s
[14:44:43.897] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:64:0)
[14:44:43.897] Timer tick, asking Buildbucket for the build status
[14:44:44.118] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:44.118] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:65:0) after 1m0s
[14:45:44.106] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:65:0)
[14:45:44.106] Timer tick, asking Buildbucket for the build status
[14:45:44.253] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:44.253] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:66:0) after 1m0s
[14:46:44.234] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:66:0)
[14:46:44.234] Timer tick, asking Buildbucket for the build status
[14:46:44.478] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:44.478] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:67:0) after 1m0s
[14:47:44.466] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:67:0)
[14:47:44.466] Timer tick, asking Buildbucket for the build status
[14:47:44.596] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:44.596] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:68:0) after 1m0s
[14:48:44.623] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:68:0)
[14:48:44.623] Timer tick, asking Buildbucket for the build status
[14:48:44.778] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:44.778] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:69:0) after 1m0s
[14:49:44.760] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:69:0)
[14:49:44.760] Timer tick, asking Buildbucket for the build status
[14:49:44.820] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:44.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:70:0) after 1m0s
[14:50:44.846] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:70:0)
[14:50:44.846] Timer tick, asking Buildbucket for the build status
[14:50:44.983] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:44.983] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:71:0) after 1m0s
[14:51:44.991] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:71:0)
[14:51:44.991] Timer tick, asking Buildbucket for the build status
[14:51:45.689] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:45.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:72:0) after 1m0s
[14:52:45.684] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:72:0)
[14:52:45.684] Timer tick, asking Buildbucket for the build status
[14:52:45.750] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:45.750] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:73:0) after 1m0s
[14:53:45.798] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:73:0)
[14:53:45.798] Timer tick, asking Buildbucket for the build status
[14:53:45.862] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:45.862] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:74:0) after 1m0s
[14:54:45.871] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:74:0)
[14:54:45.871] Timer tick, asking Buildbucket for the build status
[14:54:46.012] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:46.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:75:0) after 1m0s
[14:55:46.036] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:75:0)
[14:55:46.036] Timer tick, asking Buildbucket for the build status
[14:55:46.085] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:46.085] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:76:0) after 1m0s
[14:56:46.132] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:76:0)
[14:56:46.132] Timer tick, asking Buildbucket for the build status
[14:56:46.286] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:46.286] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:77:0) after 1m0s
[14:57:46.332] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:77:0)
[14:57:46.332] Timer tick, asking Buildbucket for the build status
[14:57:46.413] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:46.413] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:78:0) after 1m0s
[14:58:46.440] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:78:0)
[14:58:46.440] Timer tick, asking Buildbucket for the build status
[14:58:46.571] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:46.571] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:79:0) after 1m0s
[14:59:46.603] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:79:0)
[14:59:46.603] Timer tick, asking Buildbucket for the build status
[14:59:46.698] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:46.698] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:80:0) after 1m0s
[15:00:46.719] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:80:0)
[15:00:46.719] Timer tick, asking Buildbucket for the build status
[15:00:46.905] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:46.905] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:81:0) after 1m0s
[15:01:46.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:81:0)
[15:01:46.929] Timer tick, asking Buildbucket for the build status
[15:01:47.029] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:47.029] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:82:0) after 1m0s
[15:02:47.055] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:82:0)
[15:02:47.055] Timer tick, asking Buildbucket for the build status
[15:02:47.167] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:47.167] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:83:0) after 1m0s
[15:03:47.188] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:83:0)
[15:03:47.188] Timer tick, asking Buildbucket for the build status
[15:03:47.248] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:47.248] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:84:0) after 1m0s
[15:04:47.258] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:84:0)
[15:04:47.258] Timer tick, asking Buildbucket for the build status
[15:04:47.796] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:47.796] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:85:0) after 1m0s
[15:05:47.776] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:85:0)
[15:05:47.776] Timer tick, asking Buildbucket for the build status
[15:05:47.916] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:47.916] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:86:0) after 1m0s
[15:06:47.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:86:0)
[15:06:47.938] Timer tick, asking Buildbucket for the build status
[15:06:48.113] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:48.113] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:87:0) after 1m0s
[15:07:48.136] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:87:0)
[15:07:48.136] Timer tick, asking Buildbucket for the build status
[15:07:48.328] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:48.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:88:0) after 1m0s
[15:08:48.307] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:88:0)
[15:08:48.307] Timer tick, asking Buildbucket for the build status
[15:08:48.351] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:48.351] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:89:0) after 1m0s
[15:09:48.372] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:89:0)
[15:09:48.372] Timer tick, asking Buildbucket for the build status
[15:09:48.483] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:48.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:90:0) after 1m0s
[15:10:48.503] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:90:0)
[15:10:48.503] Timer tick, asking Buildbucket for the build status
[15:10:48.696] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:48.696] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:91:0) after 1m0s
[15:11:48.715] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:91:0)
[15:11:48.715] Timer tick, asking Buildbucket for the build status
[15:11:48.785] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:48.785] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:92:0) after 1m0s
[15:12:48.816] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:92:0)
[15:12:48.816] Timer tick, asking Buildbucket for the build status
[15:12:48.875] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:48.875] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:93:0) after 1m0s
[15:13:48.893] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:93:0)
[15:13:48.893] Timer tick, asking Buildbucket for the build status
[15:13:49.187] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:49.187] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:94:0) after 1m0s
[15:14:49.251] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:94:0)
[15:14:49.251] Timer tick, asking Buildbucket for the build status
[15:14:49.329] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:49.329] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:95:0) after 1m0s
[15:15:49.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:95:0)
[15:15:49.350] Timer tick, asking Buildbucket for the build status
[15:15:49.424] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:49.424] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:96:0) after 1m0s
[15:16:49.437] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:96:0)
[15:16:49.437] Timer tick, asking Buildbucket for the build status
[15:16:49.517] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:49.517] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:97:0) after 1m0s
[15:17:49.540] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:97:0)
[15:17:49.540] Timer tick, asking Buildbucket for the build status
[15:17:49.585] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:49.585] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:98:0) after 1m0s
[15:18:49.583] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:98:0)
[15:18:49.583] Timer tick, asking Buildbucket for the build status
[15:18:49.662] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:49.662] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:99:0) after 1m0s
[15:19:49.685] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:99:0)
[15:19:49.685] Timer tick, asking Buildbucket for the build status
[15:19:49.766] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:49.766] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:100:0) after 1m0s
[15:20:49.798] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:100:0)
[15:20:49.798] Timer tick, asking Buildbucket for the build status
[15:20:50.096] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:50.096] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:101:0) after 1m0s
[15:21:50.116] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:101:0)
[15:21:50.116] Timer tick, asking Buildbucket for the build status
[15:21:50.190] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:50.190] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:102:0) after 1m0s
[15:22:50.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:102:0)
[15:22:50.194] Timer tick, asking Buildbucket for the build status
[15:22:50.377] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:50.377] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:103:0) after 1m0s
[15:23:50.420] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:103:0)
[15:23:50.420] Timer tick, asking Buildbucket for the build status
[15:23:50.458] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:50.458] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:104:0) after 1m0s
[15:24:50.450] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:104:0)
[15:24:50.450] Timer tick, asking Buildbucket for the build status
[15:24:50.497] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:50.497] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:105:0) after 1m0s
[15:25:50.525] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:105:0)
[15:25:50.525] Timer tick, asking Buildbucket for the build status
[15:25:50.620] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:50.620] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:106:0) after 1m0s
[15:26:50.601] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:106:0)
[15:26:50.601] Timer tick, asking Buildbucket for the build status
[15:26:50.684] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:50.684] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:107:0) after 1m0s
[15:27:50.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:107:0)
[15:27:50.683] Timer tick, asking Buildbucket for the build status
[15:27:50.729] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:50.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:108:0) after 1m0s
[15:28:50.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:108:0)
[15:28:50.705] Timer tick, asking Buildbucket for the build status
[15:28:50.915] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:50.915] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:109:0) after 1m0s
[15:29:50.937] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:109:0)
[15:29:50.937] Timer tick, asking Buildbucket for the build status
[15:29:50.981] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:50.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:110:0) after 1m0s
[15:30:51.014] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:110:0)
[15:30:51.014] Timer tick, asking Buildbucket for the build status
[15:30:51.077] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:51.077] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:111:0) after 1m0s
[15:31:51.059] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:111:0)
[15:31:51.059] Timer tick, asking Buildbucket for the build status
[15:31:51.133] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:51.133] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:112:0) after 1m0s
[15:32:51.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:112:0)
[15:32:51.154] Timer tick, asking Buildbucket for the build status
[15:32:51.363] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:51.363] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:113:0) after 1m0s
[15:33:51.385] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:113:0)
[15:33:51.385] Timer tick, asking Buildbucket for the build status
[15:33:51.480] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:51.480] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:114:0) after 1m0s
[15:34:51.476] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:114:0)
[15:34:51.476] Timer tick, asking Buildbucket for the build status
[15:34:51.523] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:51.523] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:115:0) after 1m0s
[15:35:51.544] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:115:0)
[15:35:51.544] Timer tick, asking Buildbucket for the build status
[15:35:51.617] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:51.617] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:116:0) after 1m0s
[15:36:51.654] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:116:0)
[15:36:51.654] Timer tick, asking Buildbucket for the build status
[15:36:51.872] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:51.872] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:117:0) after 1m0s
[15:37:51.894] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:117:0)
[15:37:51.894] Timer tick, asking Buildbucket for the build status
[15:37:51.991] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:51.991] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:118:0) after 1m0s
[15:38:52.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:118:0)
[15:38:52.003] Timer tick, asking Buildbucket for the build status
[15:38:52.075] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:52.075] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:119:0) after 1m0s
[15:39:52.133] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:119:0)
[15:39:52.133] Timer tick, asking Buildbucket for the build status
[15:39:52.246] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:52.246] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:120:0) after 1m0s
[15:40:52.231] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:120:0)
[15:40:52.231] Timer tick, asking Buildbucket for the build status
[15:40:52.385] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:52.385] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:121:0) after 1m0s
[15:41:52.403] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:121:0)
[15:41:52.403] Timer tick, asking Buildbucket for the build status
[15:41:52.657] Build 8920273433316809680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:52.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085703495361547744:122:0) after 1m0s
[15:41:53.775] Received PubSub notification, asking Buildbucket for the build status
[15:41:53.912] Build 8920273433316809680: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:41:53.913] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1551368513039102",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551361353532926",
"id": "8920273433316809680",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"72511a7e57f7353b83d96d35b603545efaf5fa6b\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"win10-727f49a0-us-west1-c-gw0h\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4129-0985e08\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"got_nacl_revision\": \"1ed9878b8eb68c1e0d0268754dafcf83e747fa8f\", \"got_swarming_client_revision\": \"d50a88f50782ba29076061b94c7b9d08a6c7e424\", \"got_revision\": \"72511a7e57f7353b83d96d35b603545efaf5fa6b\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"31ac9850ad0f9594639ff035140766204de352e1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26879}\", \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"branch\": \"refs/heads/master\", \"revision\": \"72511a7e57f7353b83d96d35b603545efaf5fa6b\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"2c7964832eacfddceb467da385c0dfe89a5b8d7d\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"4b314eefc839f688cf2367b2cc288a8e954d1c4f\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1551361353532926, \"tags\": [\"build_address:luci.chromium.ci/Windows deterministic/12241\", \"builder:Windows deterministic\", \"buildset:commit/git/72511a7e57f7353b83d96d35b603545efaf5fa6b\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/72511a7e57f7353b83d96d35b603545efaf5fa6b\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9085703495361547744\", \"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\": \"8920273433316809680\"}}, \"got_v8_revision\": \"ef04c3c75da91a1f4e47355b25bbbac8db452bf6\", \"got_v8_revision_cp\": \"refs/heads/7.4.246@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 12241, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Windows deterministic\", \"key\": \"builder\"}, {\"value\": \"commit/git/72511a7e57f7353b83d96d35b603545efaf5fa6b\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/72511a7e57f7353b83d96d35b603545efaf5fa6b\", \"key\": \"buildset\"}, {\"value\": \"9085703495361547744\", \"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\": 12241, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"ca0db2d762966d4b32d49d86567d9d7697438caf\"}, \"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\": \"8920273433316809680\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"72511a7e57f7353b83d96d35b603545efaf5fa6b\"}}, \"createTime\": \"2019-02-28T13:42:33.532926Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"win10-727f49a0-us-west1-c-gw0h\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1551361462325424",
"status": "COMPLETED",
"status_changed_ts": "1551368513485226",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12241",
"builder:Windows deterministic",
"buildset:commit/git/72511a7e57f7353b83d96d35b603545efaf5fa6b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/72511a7e57f7353b83d96d35b603545efaf5fa6b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085703495361547744",
"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/8920273433316809680/+/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:434d29331bf90b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1551368513486539",
"url": "https://ci.chromium.org/b/8920273433316809680",
"utcnow_ts": "1551368513899681"
}
}
[15:41:53.913] Invocation finished in 1h59m21.713576228s with status SUCCEEDED