[16:57:30.173] New invocation is queued and will start shortly
[16:57:31.286] Starting the invocation (attempt 1)
[16:57:31.338] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:57:31.338] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:57:31.338] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9084422871633924880",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"892ebabe71d120271cf4ae3a43d0c2ca15faad69\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9084422871633924880",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/892ebabe71d120271cf4ae3a43d0c2ca15faad69",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/892ebabe71d120271cf4ae3a43d0c2ca15faad69",
"gitiles_ref:refs/heads/master"
]
}
[16:57:33.377] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552582651422530",
"id": "8918992809660856624",
"lease_expiration_ts": "1552701453119763",
"lease_key": "1591757673",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"892ebabe71d120271cf4ae3a43d0c2ca15faad69\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552582653264069",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12427",
"builder:Windows deterministic",
"buildset:commit/git/892ebabe71d120271cf4ae3a43d0c2ca15faad69",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/892ebabe71d120271cf4ae3a43d0c2ca15faad69",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084422871633924880",
"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/8918992809660856624/+/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:4395f4bb70b62010",
"user_agent:luci-scheduler"
],
"updated_ts": "1552582653264174",
"url": "https://ci.chromium.org/b/8918992809660856624",
"utcnow_ts": "1552582653368792"
}
}
[16:57:33.377] Task URL: https://ci.chromium.org/b/8918992809660856624
[16:57:33.378] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:2:0) after 1m0s
[16:57:42.135] Received PubSub notification, asking Buildbucket for the build status
[16:57:42.211] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:33.371] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:2:0)
[16:58:33.371] Timer tick, asking Buildbucket for the build status
[16:58:33.477] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:33.477] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:4:0) after 1m0s
[16:59:33.508] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:4:0)
[16:59:33.508] Timer tick, asking Buildbucket for the build status
[16:59:33.600] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:33.600] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:5:0) after 1m0s
[17:00:33.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:5:0)
[17:00:33.591] Timer tick, asking Buildbucket for the build status
[17:00:33.628] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:33.628] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:6:0) after 1m0s
[17:01:33.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:6:0)
[17:01:33.599] Timer tick, asking Buildbucket for the build status
[17:01:33.805] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:33.805] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:7:0) after 1m0s
[17:02:33.796] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:7:0)
[17:02:33.796] Timer tick, asking Buildbucket for the build status
[17:02:33.897] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:33.897] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:8:0) after 1m0s
[17:03:33.915] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:8:0)
[17:03:33.915] Timer tick, asking Buildbucket for the build status
[17:03:33.952] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:33.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:9:0) after 1m0s
[17:04:33.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:9:0)
[17:04:33.964] Timer tick, asking Buildbucket for the build status
[17:04:34.018] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:34.018] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:10:0) after 1m0s
[17:05:34.018] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:10:0)
[17:05:34.018] Timer tick, asking Buildbucket for the build status
[17:05:34.056] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:34.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:11:0) after 1m0s
[17:06:34.037] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:11:0)
[17:06:34.037] Timer tick, asking Buildbucket for the build status
[17:06:34.073] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:34.073] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:12:0) after 1m0s
[17:07:34.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:12:0)
[17:07:34.117] Timer tick, asking Buildbucket for the build status
[17:07:34.182] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:34.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:13:0) after 1m0s
[17:08:34.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:13:0)
[17:08:34.194] Timer tick, asking Buildbucket for the build status
[17:08:34.444] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:34.444] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:14:0) after 1m0s
[17:09:34.465] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:14:0)
[17:09:34.465] Timer tick, asking Buildbucket for the build status
[17:09:34.508] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:34.508] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:15:0) after 1m0s
[17:10:34.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:15:0)
[17:10:34.528] Timer tick, asking Buildbucket for the build status
[17:10:34.573] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:34.573] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:16:0) after 1m0s
[17:11:34.557] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:16:0)
[17:11:34.557] Timer tick, asking Buildbucket for the build status
[17:11:34.632] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:34.632] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:17:0) after 1m0s
[17:12:34.653] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:17:0)
[17:12:34.653] Timer tick, asking Buildbucket for the build status
[17:12:36.159] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:36.159] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:18:0) after 1m0s
[17:13:36.179] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:18:0)
[17:13:36.179] Timer tick, asking Buildbucket for the build status
[17:13:36.266] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:36.266] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:19:0) after 1m0s
[17:14:36.261] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:19:0)
[17:14:36.261] Timer tick, asking Buildbucket for the build status
[17:14:36.316] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:36.316] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:20:0) after 1m0s
[17:15:36.303] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:20:0)
[17:15:36.303] Timer tick, asking Buildbucket for the build status
[17:15:36.394] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:36.394] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:21:0) after 1m0s
[17:16:36.414] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:21:0)
[17:16:36.414] Timer tick, asking Buildbucket for the build status
[17:16:36.483] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:36.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:22:0) after 1m0s
[17:17:36.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:22:0)
[17:17:36.470] Timer tick, asking Buildbucket for the build status
[17:17:36.695] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:36.695] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:23:0) after 1m0s
[17:18:36.717] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:23:0)
[17:18:36.717] Timer tick, asking Buildbucket for the build status
[17:18:36.780] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:36.780] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:24:0) after 1m0s
[17:19:36.760] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:24:0)
[17:19:36.760] Timer tick, asking Buildbucket for the build status
[17:19:36.797] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:36.797] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:25:0) after 1m0s
[17:20:36.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:25:0)
[17:20:36.792] Timer tick, asking Buildbucket for the build status
[17:20:36.914] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:36.914] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:26:0) after 1m0s
[17:21:36.935] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:26:0)
[17:21:36.935] Timer tick, asking Buildbucket for the build status
[17:21:36.972] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:36.972] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:27:0) after 1m0s
[17:22:36.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:27:0)
[17:22:36.990] Timer tick, asking Buildbucket for the build status
[17:22:37.108] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:37.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:28:0) after 1m0s
[17:23:37.129] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:28:0)
[17:23:37.129] Timer tick, asking Buildbucket for the build status
[17:23:37.213] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:37.213] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:29:0) after 1m0s
[17:24:37.192] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:29:0)
[17:24:37.192] Timer tick, asking Buildbucket for the build status
[17:24:37.238] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:37.238] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:30:0) after 1m0s
[17:25:37.233] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:30:0)
[17:25:37.233] Timer tick, asking Buildbucket for the build status
[17:25:37.299] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:37.299] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:31:0) after 1m0s
[17:26:37.299] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:31:0)
[17:26:37.299] Timer tick, asking Buildbucket for the build status
[17:26:37.391] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:37.391] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:32:0) after 1m0s
[17:27:37.387] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:32:0)
[17:27:37.387] Timer tick, asking Buildbucket for the build status
[17:27:37.424] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:37.424] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:33:0) after 1m0s
[17:28:37.398] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:33:0)
[17:28:37.398] Timer tick, asking Buildbucket for the build status
[17:28:37.563] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:37.563] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:34:0) after 1m0s
[17:29:37.552] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:34:0)
[17:29:37.552] Timer tick, asking Buildbucket for the build status
[17:29:37.591] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:37.591] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:35:0) after 1m0s
[17:30:37.577] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:35:0)
[17:30:37.577] Timer tick, asking Buildbucket for the build status
[17:30:37.688] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:37.688] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:36:0) after 1m0s
[17:31:37.668] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:36:0)
[17:31:37.668] Timer tick, asking Buildbucket for the build status
[17:31:37.775] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:37.775] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:37:0) after 1m0s
[17:32:37.795] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:37:0)
[17:32:37.795] Timer tick, asking Buildbucket for the build status
[17:32:37.829] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:37.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:38:0) after 1m0s
[17:33:37.850] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:38:0)
[17:33:37.850] Timer tick, asking Buildbucket for the build status
[17:33:37.904] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:37.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:39:0) after 1m0s
[17:34:37.909] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:39:0)
[17:34:37.909] Timer tick, asking Buildbucket for the build status
[17:34:37.963] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:37.963] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:40:0) after 1m0s
[17:35:37.939] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:40:0)
[17:35:37.939] Timer tick, asking Buildbucket for the build status
[17:35:37.995] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:37.995] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:41:0) after 1m0s
[17:36:38.023] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:41:0)
[17:36:38.023] Timer tick, asking Buildbucket for the build status
[17:36:38.061] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:38.061] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:42:0) after 1m0s
[17:37:38.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:42:0)
[17:37:38.083] Timer tick, asking Buildbucket for the build status
[17:37:38.122] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:38.122] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:43:0) after 1m0s
[17:38:38.146] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:43:0)
[17:38:38.146] Timer tick, asking Buildbucket for the build status
[17:38:38.385] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:38.385] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:44:0) after 1m0s
[17:39:38.360] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:44:0)
[17:39:38.360] Timer tick, asking Buildbucket for the build status
[17:39:38.461] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:38.461] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:45:0) after 1m0s
[17:40:38.485] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:45:0)
[17:40:38.485] Timer tick, asking Buildbucket for the build status
[17:40:38.563] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:38.563] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:46:0) after 1m0s
[17:41:38.556] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:46:0)
[17:41:38.556] Timer tick, asking Buildbucket for the build status
[17:41:38.593] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:38.593] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:47:0) after 1m0s
[17:42:38.616] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:47:0)
[17:42:38.616] Timer tick, asking Buildbucket for the build status
[17:42:38.717] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:38.717] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:48:0) after 1m0s
[17:43:38.692] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:48:0)
[17:43:38.692] Timer tick, asking Buildbucket for the build status
[17:43:38.794] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:38.794] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:49:0) after 1m0s
[17:44:38.778] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:49:0)
[17:44:38.778] Timer tick, asking Buildbucket for the build status
[17:44:38.885] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:38.885] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:50:0) after 1m0s
[17:45:38.868] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:50:0)
[17:45:38.868] Timer tick, asking Buildbucket for the build status
[17:45:38.904] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:38.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:51:0) after 1m0s
[17:46:38.932] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:51:0)
[17:46:38.932] Timer tick, asking Buildbucket for the build status
[17:46:39.023] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:39.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:52:0) after 1m0s
[17:47:39.040] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:52:0)
[17:47:39.040] Timer tick, asking Buildbucket for the build status
[17:47:39.123] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:39.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:53:0) after 1m0s
[17:48:39.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:53:0)
[17:48:39.117] Timer tick, asking Buildbucket for the build status
[17:48:39.173] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:39.173] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:54:0) after 1m0s
[17:49:39.185] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:54:0)
[17:49:39.185] Timer tick, asking Buildbucket for the build status
[17:49:39.223] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:39.223] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:55:0) after 1m0s
[17:50:39.203] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:55:0)
[17:50:39.203] Timer tick, asking Buildbucket for the build status
[17:50:39.245] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:39.245] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:56:0) after 1m0s
[17:51:39.242] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:56:0)
[17:51:39.242] Timer tick, asking Buildbucket for the build status
[17:51:39.296] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:39.296] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:57:0) after 1m0s
[17:52:39.316] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:57:0)
[17:52:39.316] Timer tick, asking Buildbucket for the build status
[17:52:39.370] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:39.370] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:58:0) after 1m0s
[17:53:39.395] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:58:0)
[17:53:39.395] Timer tick, asking Buildbucket for the build status
[17:53:39.467] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:39.467] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:59:0) after 1m0s
[17:54:39.473] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:59:0)
[17:54:39.473] Timer tick, asking Buildbucket for the build status
[17:54:39.537] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:39.537] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:60:0) after 1m0s
[17:55:39.581] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:60:0)
[17:55:39.581] Timer tick, asking Buildbucket for the build status
[17:55:39.614] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:39.614] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:61:0) after 1m0s
[17:56:39.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:61:0)
[17:56:39.602] Timer tick, asking Buildbucket for the build status
[17:56:39.663] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:39.663] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:62:0) after 1m0s
[17:57:39.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:62:0)
[17:57:39.683] Timer tick, asking Buildbucket for the build status
[17:57:39.719] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:39.719] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:63:0) after 1m0s
[17:58:39.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:63:0)
[17:58:39.708] Timer tick, asking Buildbucket for the build status
[17:58:39.751] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:39.751] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:64:0) after 1m0s
[17:59:39.778] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:64:0)
[17:59:39.778] Timer tick, asking Buildbucket for the build status
[17:59:39.816] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:39.816] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:65:0) after 1m0s
[18:00:39.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:65:0)
[18:00:39.856] Timer tick, asking Buildbucket for the build status
[18:00:39.909] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:39.909] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:66:0) after 1m0s
[18:01:39.902] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:66:0)
[18:01:39.902] Timer tick, asking Buildbucket for the build status
[18:01:39.958] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:39.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:67:0) after 1m0s
[18:02:39.961] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:67:0)
[18:02:39.961] Timer tick, asking Buildbucket for the build status
[18:02:40.053] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:40.053] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:68:0) after 1m0s
[18:03:40.025] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:68:0)
[18:03:40.025] Timer tick, asking Buildbucket for the build status
[18:03:40.065] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:40.065] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:69:0) after 1m0s
[18:04:40.040] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:69:0)
[18:04:40.040] Timer tick, asking Buildbucket for the build status
[18:04:40.096] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:40.096] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:70:0) after 1m0s
[18:05:40.119] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:70:0)
[18:05:40.119] Timer tick, asking Buildbucket for the build status
[18:05:40.165] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:40.165] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:71:0) after 1m0s
[18:06:40.187] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:71:0)
[18:06:40.187] Timer tick, asking Buildbucket for the build status
[18:06:40.257] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:40.257] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:72:0) after 1m0s
[18:07:40.243] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:72:0)
[18:07:40.243] Timer tick, asking Buildbucket for the build status
[18:07:40.312] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:40.312] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:73:0) after 1m0s
[18:08:40.342] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:73:0)
[18:08:40.342] Timer tick, asking Buildbucket for the build status
[18:08:42.050] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:42.050] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:74:0) after 1m0s
[18:09:42.029] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:74:0)
[18:09:42.029] Timer tick, asking Buildbucket for the build status
[18:09:42.177] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:42.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:75:0) after 1m0s
[18:10:42.199] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:75:0)
[18:10:42.199] Timer tick, asking Buildbucket for the build status
[18:10:42.628] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:42.628] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:76:0) after 1m0s
[18:11:42.650] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:76:0)
[18:11:42.650] Timer tick, asking Buildbucket for the build status
[18:11:42.683] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:42.683] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:77:0) after 1m0s
[18:12:42.724] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:77:0)
[18:12:42.724] Timer tick, asking Buildbucket for the build status
[18:12:42.898] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:42.898] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:78:0) after 1m0s
[18:13:42.920] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:78:0)
[18:13:42.920] Timer tick, asking Buildbucket for the build status
[18:13:42.958] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:42.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:79:0) after 1m0s
[18:14:42.983] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:79:0)
[18:14:42.983] Timer tick, asking Buildbucket for the build status
[18:14:43.052] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:43.052] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:80:0) after 1m0s
[18:15:43.078] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:80:0)
[18:15:43.078] Timer tick, asking Buildbucket for the build status
[18:15:43.160] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:43.160] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:81:0) after 1m0s
[18:16:43.198] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:81:0)
[18:16:43.198] Timer tick, asking Buildbucket for the build status
[18:16:43.291] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:43.291] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:82:0) after 1m0s
[18:17:43.302] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:82:0)
[18:17:43.302] Timer tick, asking Buildbucket for the build status
[18:17:43.363] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:43.363] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:83:0) after 1m0s
[18:18:43.383] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:83:0)
[18:18:43.383] Timer tick, asking Buildbucket for the build status
[18:18:43.555] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:43.555] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:84:0) after 1m0s
[18:19:43.542] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:84:0)
[18:19:43.542] Timer tick, asking Buildbucket for the build status
[18:19:43.584] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:43.584] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:85:0) after 1m0s
[18:20:43.600] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:85:0)
[18:20:43.600] Timer tick, asking Buildbucket for the build status
[18:20:43.642] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:43.642] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:86:0) after 1m0s
[18:21:43.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:86:0)
[18:21:43.721] Timer tick, asking Buildbucket for the build status
[18:21:43.794] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:43.794] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:87:0) after 1m0s
[18:22:43.819] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:87:0)
[18:22:43.819] Timer tick, asking Buildbucket for the build status
[18:22:44.117] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:44.117] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:88:0) after 1m0s
[18:23:44.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:88:0)
[18:23:44.117] Timer tick, asking Buildbucket for the build status
[18:23:44.162] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:44.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:89:0) after 1m0s
[18:24:44.168] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:89:0)
[18:24:44.168] Timer tick, asking Buildbucket for the build status
[18:24:44.221] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:44.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:90:0) after 1m0s
[18:25:44.222] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:90:0)
[18:25:44.222] Timer tick, asking Buildbucket for the build status
[18:25:44.298] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:44.298] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:91:0) after 1m0s
[18:26:44.301] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:91:0)
[18:26:44.301] Timer tick, asking Buildbucket for the build status
[18:26:44.421] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:44.421] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:92:0) after 1m0s
[18:27:44.420] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:92:0)
[18:27:44.420] Timer tick, asking Buildbucket for the build status
[18:27:44.493] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:44.493] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:93:0) after 1m0s
[18:28:44.485] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:93:0)
[18:28:44.485] Timer tick, asking Buildbucket for the build status
[18:28:44.855] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:44.855] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:94:0) after 1m0s
[18:29:44.844] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:94:0)
[18:29:44.844] Timer tick, asking Buildbucket for the build status
[18:29:44.909] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:44.909] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:95:0) after 1m0s
[18:30:44.888] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:95:0)
[18:30:44.888] Timer tick, asking Buildbucket for the build status
[18:30:44.991] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:44.991] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:96:0) after 1m0s
[18:31:44.985] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:96:0)
[18:31:44.985] Timer tick, asking Buildbucket for the build status
[18:31:45.020] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:45.020] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:97:0) after 1m0s
[18:32:45.047] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:97:0)
[18:32:45.047] Timer tick, asking Buildbucket for the build status
[18:32:45.087] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:45.087] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:98:0) after 1m0s
[18:33:45.118] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:98:0)
[18:33:45.118] Timer tick, asking Buildbucket for the build status
[18:33:45.259] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:45.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:99:0) after 1m0s
[18:34:45.237] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:99:0)
[18:34:45.237] Timer tick, asking Buildbucket for the build status
[18:34:45.272] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:45.272] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:100:0) after 1m0s
[18:35:45.298] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:100:0)
[18:35:45.298] Timer tick, asking Buildbucket for the build status
[18:35:45.407] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:45.407] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:101:0) after 1m0s
[18:36:45.426] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:101:0)
[18:36:45.426] Timer tick, asking Buildbucket for the build status
[18:36:45.525] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:45.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:102:0) after 1m0s
[18:37:45.556] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:102:0)
[18:37:45.556] Timer tick, asking Buildbucket for the build status
[18:37:45.611] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:45.611] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:103:0) after 1m0s
[18:38:45.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:103:0)
[18:38:45.599] Timer tick, asking Buildbucket for the build status
[18:38:45.639] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:45.639] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:104:0) after 1m0s
[18:39:45.649] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:104:0)
[18:39:45.649] Timer tick, asking Buildbucket for the build status
[18:39:45.693] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:45.694] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:105:0) after 1m0s
[18:40:45.717] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:105:0)
[18:40:45.717] Timer tick, asking Buildbucket for the build status
[18:40:45.754] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:45.754] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:106:0) after 1m0s
[18:41:45.776] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:106:0)
[18:41:45.776] Timer tick, asking Buildbucket for the build status
[18:41:45.817] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:45.817] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:107:0) after 1m0s
[18:42:45.833] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:107:0)
[18:42:45.833] Timer tick, asking Buildbucket for the build status
[18:42:46.068] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:46.068] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:108:0) after 1m0s
[18:43:46.093] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:108:0)
[18:43:46.093] Timer tick, asking Buildbucket for the build status
[18:43:46.252] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:46.252] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:109:0) after 1m0s
[18:44:46.245] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:109:0)
[18:44:46.245] Timer tick, asking Buildbucket for the build status
[18:44:46.306] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:46.306] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:110:0) after 1m0s
[18:45:46.291] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:110:0)
[18:45:46.291] Timer tick, asking Buildbucket for the build status
[18:45:46.379] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:46.379] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:111:0) after 1m0s
[18:46:46.360] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:111:0)
[18:46:46.360] Timer tick, asking Buildbucket for the build status
[18:46:46.402] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:46.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:112:0) after 1m0s
[18:47:46.431] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:112:0)
[18:47:46.431] Timer tick, asking Buildbucket for the build status
[18:47:46.476] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:46.476] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:113:0) after 1m0s
[18:48:47.937] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:113:0)
[18:48:47.937] Timer tick, asking Buildbucket for the build status
[18:48:47.991] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:47.991] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:114:0) after 1m0s
[18:49:47.995] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:114:0)
[18:49:47.995] Timer tick, asking Buildbucket for the build status
[18:49:48.188] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:48.188] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:115:0) after 1m0s
[18:50:48.214] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:115:0)
[18:50:48.214] Timer tick, asking Buildbucket for the build status
[18:50:48.286] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:48.286] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:116:0) after 1m0s
[18:51:48.288] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:116:0)
[18:51:48.288] Timer tick, asking Buildbucket for the build status
[18:51:48.330] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:48.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:117:0) after 1m0s
[18:52:48.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:117:0)
[18:52:48.345] Timer tick, asking Buildbucket for the build status
[18:52:48.384] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:48.384] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:118:0) after 1m0s
[18:53:48.483] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:118:0)
[18:53:48.483] Timer tick, asking Buildbucket for the build status
[18:53:48.682] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:48.682] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:119:0) after 1m0s
[18:54:48.693] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:119:0)
[18:54:48.693] Timer tick, asking Buildbucket for the build status
[18:54:48.777] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:48.777] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:120:0) after 1m0s
[18:55:48.847] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:120:0)
[18:55:48.847] Timer tick, asking Buildbucket for the build status
[18:55:48.882] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:48.882] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:121:0) after 1m0s
[18:56:48.864] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:121:0)
[18:56:48.864] Timer tick, asking Buildbucket for the build status
[18:56:48.898] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:48.898] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:122:0) after 1m0s
[18:57:48.899] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:122:0)
[18:57:48.899] Timer tick, asking Buildbucket for the build status
[18:57:48.937] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:48.937] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:123:0) after 1m0s
[18:58:48.966] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:123:0)
[18:58:48.966] Timer tick, asking Buildbucket for the build status
[18:58:49.001] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:49.001] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:124:0) after 1m0s
[18:59:49.026] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:124:0)
[18:59:49.026] Timer tick, asking Buildbucket for the build status
[18:59:49.068] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:49.068] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:125:0) after 1m0s
[19:00:49.057] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:125:0)
[19:00:49.057] Timer tick, asking Buildbucket for the build status
[19:00:49.183] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:49.183] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:126:0) after 1m0s
[19:01:49.188] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:126:0)
[19:01:49.188] Timer tick, asking Buildbucket for the build status
[19:01:49.482] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:49.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:127:0) after 1m0s
[19:02:49.780] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:127:0)
[19:02:49.780] Timer tick, asking Buildbucket for the build status
[19:02:50.327] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:50.327] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:128:0) after 1m0s
[19:03:50.352] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:128:0)
[19:03:50.352] Timer tick, asking Buildbucket for the build status
[19:03:50.659] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:50.659] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:129:0) after 1m0s
[19:04:50.686] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:129:0)
[19:04:50.686] Timer tick, asking Buildbucket for the build status
[19:04:50.814] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:50.814] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:130:0) after 1m0s
[19:05:50.860] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:130:0)
[19:05:50.860] Timer tick, asking Buildbucket for the build status
[19:05:52.924] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:52.924] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:131:0) after 1m0s
[19:06:52.928] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:131:0)
[19:06:52.928] Timer tick, asking Buildbucket for the build status
[19:06:57.406] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:57.406] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:132:0) after 1m0s
[19:07:57.406] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:132:0)
[19:07:57.406] Timer tick, asking Buildbucket for the build status
[19:07:57.751] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:57.751] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:133:0) after 1m0s
[19:08:57.781] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:133:0)
[19:08:57.781] Timer tick, asking Buildbucket for the build status
[19:08:57.890] Build 8918992809660856624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:57.890] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084422871633924880:134:0) after 1m0s
[19:09:33.357] Received PubSub notification, asking Buildbucket for the build status
[19:09:33.472] Build 8918992809660856624: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:09:33.472] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1552590572156100",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552582651422530",
"id": "8918992809660856624",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"892ebabe71d120271cf4ae3a43d0c2ca15faad69\"}}",
"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\": [\"windows-deterministic-0-v3qq\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4196-ca608a7\"], \"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\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"got_nacl_revision\": \"1ed9878b8eb68c1e0d0268754dafcf83e747fa8f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27121}\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"d841ea6b586a81065f78d3eae7ab82c505cda1d8\", \"got_revision\": \"892ebabe71d120271cf4ae3a43d0c2ca15faad69\", \"got_swarming_client_revision\": \"7a61cf37d6a0163f0ec02d495289a1d038e62457\", \"recipe\": \"swarming/deterministic_build\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"094c40dce6015add9e9524364a4e9b0523b7c31e\", \"got_v8_revision\": \"a4404f08610f8dde2b745329ac5813c4a6a84c70\", \"got_revision_cp\": \"refs/heads/master@{#640801}\", \"got_dawn_revision\": \"5625b63202797c54ff1f5a40d9ee664ca23cfce8\", \"branch\": \"refs/heads/master\", \"buildnumber\": 12427, \"path_config\": \"generic\", \"revision\": \"892ebabe71d120271cf4ae3a43d0c2ca15faad69\", \"got_v8_revision_cp\": \"refs/heads/7.5.38@{#1}\", \"bot_id\": \"windows-deterministic-0-v3qq\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1552582661422078",
"status": "COMPLETED",
"status_changed_ts": "1552590572928276",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12427",
"builder:Windows deterministic",
"buildset:commit/git/892ebabe71d120271cf4ae3a43d0c2ca15faad69",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/892ebabe71d120271cf4ae3a43d0c2ca15faad69",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084422871633924880",
"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/8918992809660856624/+/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:4395f4bb70b62010",
"user_agent:luci-scheduler"
],
"updated_ts": "1552590572934294",
"url": "https://ci.chromium.org/b/8918992809660856624",
"utcnow_ts": "1552590573461900"
}
}
[19:09:33.472] Invocation finished in 2h12m3.315303775s with status SUCCEEDED