[09:01:21.119] New invocation is queued and will start shortly
[09:01:22.210] Starting the invocation (attempt 1)
[09:01:22.247] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:01:22.247] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:01:22.247] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9073490595526164224",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"e469307ad838ec0643f0e3e9ec4828b066f4e8e1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9073490595526164224",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/e469307ad838ec0643f0e3e9ec4828b066f4e8e1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e469307ad838ec0643f0e3e9ec4828b066f4e8e1",
"gitiles_ref:refs/heads/master"
]
}
[09:01:25.039] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1563008483225246",
"id": "8908060532651963728",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e469307ad838ec0643f0e3e9ec4828b066f4e8e1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1563008484768341",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14115",
"builder:Windows deterministic",
"buildset:commit/git/e469307ad838ec0643f0e3e9ec4828b066f4e8e1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e469307ad838ec0643f0e3e9ec4828b066f4e8e1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9073490595526164224",
"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/8908060532651963728/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1563008484768436",
"url": "https://ci.chromium.org/b/8908060532651963728",
"utcnow_ts": "1563008485010525"
}
}
[09:01:25.039] Task URL: https://ci.chromium.org/b/8908060532651963728
[09:01:25.039] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:2:0) after 1m0s
[09:02:25.115] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:2:0)
[09:02:25.115] Timer tick, asking Buildbucket for the build status
[09:02:27.227] Build 8908060532651963728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:02:27.227] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:3:0) after 1m0s
[09:02:31.579] Received PubSub notification, asking Buildbucket for the build status
[09:02:32.643] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:27.413] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:3:0)
[09:03:27.413] Timer tick, asking Buildbucket for the build status
[09:03:33.335] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:33.335] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:5:0) after 1m0s
[09:04:33.449] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:5:0)
[09:04:33.449] Timer tick, asking Buildbucket for the build status
[09:04:34.045] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:34.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:6:0) after 1m0s
[09:05:34.098] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:6:0)
[09:05:34.098] Timer tick, asking Buildbucket for the build status
[09:05:36.958] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:36.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:7:0) after 1m0s
[09:06:36.942] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:7:0)
[09:06:36.942] Timer tick, asking Buildbucket for the build status
[09:06:38.915] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:38.915] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:8:0) after 1m0s
[09:07:39.116] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:8:0)
[09:07:39.116] Timer tick, asking Buildbucket for the build status
[09:07:39.315] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:39.315] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:9:0) after 1m0s
[09:08:39.310] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:9:0)
[09:08:39.310] Timer tick, asking Buildbucket for the build status
[09:08:41.813] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:41.813] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:10:0) after 1m0s
[09:09:41.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:10:0)
[09:09:41.886] Timer tick, asking Buildbucket for the build status
[09:09:45.410] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:45.410] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:11:0) after 1m0s
[09:10:45.432] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:11:0)
[09:10:45.432] Timer tick, asking Buildbucket for the build status
[09:10:46.612] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:46.612] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:12:0) after 1m0s
[09:11:46.630] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:12:0)
[09:11:46.631] Timer tick, asking Buildbucket for the build status
[09:11:55.161] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:55.161] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:13:0) after 1m0s
[09:12:55.163] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:13:0)
[09:12:55.163] Timer tick, asking Buildbucket for the build status
[09:12:56.803] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:56.803] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:14:0) after 1m0s
[09:13:56.858] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:14:0)
[09:13:56.858] Timer tick, asking Buildbucket for the build status
[09:13:58.074] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:58.074] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:15:0) after 1m0s
[09:14:58.136] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:15:0)
[09:14:58.136] Timer tick, asking Buildbucket for the build status
[09:14:59.588] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:59.588] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:16:0) after 1m0s
[09:15:59.610] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:16:0)
[09:15:59.610] Timer tick, asking Buildbucket for the build status
[09:16:03.244] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:03.244] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:17:0) after 1m0s
[09:17:03.316] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:17:0)
[09:17:03.316] Timer tick, asking Buildbucket for the build status
[09:17:07.859] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:07.859] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:18:0) after 1m0s
[09:18:07.846] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:18:0)
[09:18:07.846] Timer tick, asking Buildbucket for the build status
[09:18:09.797] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:09.797] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:19:0) after 1m0s
[09:19:09.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:19:0)
[09:19:09.792] Timer tick, asking Buildbucket for the build status
[09:19:12.811] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:12.811] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:20:0) after 1m0s
[09:20:12.983] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:20:0)
[09:20:12.983] Timer tick, asking Buildbucket for the build status
[09:20:13.791] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:13.792] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:21:0) after 1m0s
[09:21:13.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:21:0)
[09:21:13.815] Timer tick, asking Buildbucket for the build status
[09:21:14.730] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:14.730] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:22:0) after 1m0s
[09:22:14.723] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:22:0)
[09:22:14.723] Timer tick, asking Buildbucket for the build status
[09:22:14.900] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:14.900] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:23:0) after 1m0s
[09:23:14.943] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:23:0)
[09:23:14.943] Timer tick, asking Buildbucket for the build status
[09:23:15.123] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:15.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:24:0) after 1m0s
[09:24:15.149] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:24:0)
[09:24:15.149] Timer tick, asking Buildbucket for the build status
[09:24:15.816] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:15.816] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:25:0) after 1m0s
[09:25:15.841] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:25:0)
[09:25:15.841] Timer tick, asking Buildbucket for the build status
[09:25:16.054] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:16.054] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:26:0) after 1m0s
[09:26:16.092] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:26:0)
[09:26:16.092] Timer tick, asking Buildbucket for the build status
[09:26:16.292] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:16.292] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:27:0) after 1m0s
[09:27:16.327] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:27:0)
[09:27:16.327] Timer tick, asking Buildbucket for the build status
[09:27:17.221] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:17.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:28:0) after 1m0s
[09:28:17.278] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:28:0)
[09:28:17.278] Timer tick, asking Buildbucket for the build status
[09:28:17.746] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:17.746] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:29:0) after 1m0s
[09:29:17.768] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:29:0)
[09:29:17.768] Timer tick, asking Buildbucket for the build status
[09:29:18.037] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:18.037] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:30:0) after 1m0s
[09:30:18.082] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:30:0)
[09:30:18.082] Timer tick, asking Buildbucket for the build status
[09:30:20.251] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:20.251] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:31:0) after 1m0s
[09:31:20.278] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:31:0)
[09:31:20.278] Timer tick, asking Buildbucket for the build status
[09:31:22.128] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:22.128] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:32:0) after 1m0s
[09:32:22.158] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:32:0)
[09:32:22.158] Timer tick, asking Buildbucket for the build status
[09:32:24.814] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:24.814] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:33:0) after 1m0s
[09:33:24.887] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:33:0)
[09:33:24.887] Timer tick, asking Buildbucket for the build status
[09:33:26.316] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:26.316] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:34:0) after 1m0s
[09:34:26.372] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:34:0)
[09:34:26.372] Timer tick, asking Buildbucket for the build status
[09:34:28.306] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:28.306] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:35:0) after 1m0s
[09:35:28.332] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:35:0)
[09:35:28.332] Timer tick, asking Buildbucket for the build status
[09:35:28.561] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:28.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:36:0) after 1m0s
[09:36:28.576] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:36:0)
[09:36:28.576] Timer tick, asking Buildbucket for the build status
[09:36:29.528] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:29.528] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:37:0) after 1m0s
[09:37:29.610] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:37:0)
[09:37:29.610] Timer tick, asking Buildbucket for the build status
[09:37:29.915] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:29.915] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:38:0) after 1m0s
[09:38:30.356] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:38:0)
[09:38:30.356] Timer tick, asking Buildbucket for the build status
[09:38:32.549] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:32.549] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:39:0) after 1m0s
[09:39:32.786] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:39:0)
[09:39:32.786] Timer tick, asking Buildbucket for the build status
[09:39:35.652] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:35.652] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:40:0) after 1m0s
[09:40:35.737] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:40:0)
[09:40:35.737] Timer tick, asking Buildbucket for the build status
[09:40:36.015] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:36.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:41:0) after 1m0s
[09:41:36.319] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:41:0)
[09:41:36.319] Timer tick, asking Buildbucket for the build status
[09:41:36.623] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:36.623] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:42:0) after 1m0s
[09:42:36.825] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:42:0)
[09:42:36.825] Timer tick, asking Buildbucket for the build status
[09:42:38.991] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:38.991] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:43:0) after 1m0s
[09:43:39.174] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:43:0)
[09:43:39.174] Timer tick, asking Buildbucket for the build status
[09:43:39.825] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:39.825] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:44:0) after 1m0s
[09:44:39.847] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:44:0)
[09:44:39.848] Timer tick, asking Buildbucket for the build status
[09:44:43.172] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:43.172] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:45:0) after 1m0s
[09:45:43.331] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:45:0)
[09:45:43.331] Timer tick, asking Buildbucket for the build status
[09:45:43.508] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:43.508] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:46:0) after 1m0s
[09:46:43.534] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:46:0)
[09:46:43.534] Timer tick, asking Buildbucket for the build status
[09:46:45.312] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:45.312] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:47:0) after 1m0s
[09:47:45.581] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:47:0)
[09:47:45.581] Timer tick, asking Buildbucket for the build status
[09:47:46.135] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:46.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:48:0) after 1m0s
[09:48:46.276] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:48:0)
[09:48:46.276] Timer tick, asking Buildbucket for the build status
[09:48:46.628] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:46.628] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:49:0) after 1m0s
[09:49:46.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:49:0)
[09:49:46.705] Timer tick, asking Buildbucket for the build status
[09:49:47.052] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:47.052] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:50:0) after 1m0s
[09:50:47.076] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:50:0)
[09:50:47.076] Timer tick, asking Buildbucket for the build status
[09:50:47.275] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:47.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:51:0) after 1m0s
[09:51:47.317] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:51:0)
[09:51:47.317] Timer tick, asking Buildbucket for the build status
[09:51:48.747] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:48.747] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:52:0) after 1m0s
[09:52:48.775] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:52:0)
[09:52:48.775] Timer tick, asking Buildbucket for the build status
[09:52:49.086] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:49.086] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:53:0) after 1m0s
[09:53:49.247] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:53:0)
[09:53:49.247] Timer tick, asking Buildbucket for the build status
[09:53:50.451] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:50.451] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:54:0) after 1m0s
[09:54:50.520] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:54:0)
[09:54:50.520] Timer tick, asking Buildbucket for the build status
[09:54:51.738] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:51.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:55:0) after 1m0s
[09:55:51.902] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:55:0)
[09:55:51.902] Timer tick, asking Buildbucket for the build status
[09:55:54.025] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:54.025] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:56:0) after 1m0s
[09:56:54.313] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:56:0)
[09:56:54.313] Timer tick, asking Buildbucket for the build status
[09:56:55.224] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:55.224] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:57:0) after 1m0s
[09:57:55.200] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:57:0)
[09:57:55.200] Timer tick, asking Buildbucket for the build status
[09:57:55.337] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:55.337] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:58:0) after 1m0s
[09:58:55.358] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:58:0)
[09:58:55.358] Timer tick, asking Buildbucket for the build status
[09:58:57.856] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:57.856] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:59:0) after 1m0s
[09:59:57.884] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:59:0)
[09:59:57.884] Timer tick, asking Buildbucket for the build status
[09:59:59.646] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:59.646] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:60:0) after 1m0s
[10:00:59.623] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:60:0)
[10:00:59.623] Timer tick, asking Buildbucket for the build status
[10:01:00.818] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:00.818] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:61:0) after 1m0s
[10:02:00.923] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:61:0)
[10:02:00.923] Timer tick, asking Buildbucket for the build status
[10:02:03.842] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:03.842] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:62:0) after 1m0s
[10:03:03.877] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:62:0)
[10:03:03.877] Timer tick, asking Buildbucket for the build status
[10:03:06.545] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:06.545] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:63:0) after 1m0s
[10:04:06.581] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:63:0)
[10:04:06.581] Timer tick, asking Buildbucket for the build status
[10:04:08.413] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:08.413] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:64:0) after 1m0s
[10:05:08.535] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:64:0)
[10:05:08.535] Timer tick, asking Buildbucket for the build status
[10:05:10.268] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:10.268] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:65:0) after 1m0s
[10:06:10.299] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:65:0)
[10:06:10.299] Timer tick, asking Buildbucket for the build status
[10:06:11.838] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:11.838] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:66:0) after 1m0s
[10:07:12.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:66:0)
[10:07:12.083] Timer tick, asking Buildbucket for the build status
[10:07:13.850] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:13.850] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:67:0) after 1m0s
[10:08:13.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:67:0)
[10:08:13.963] Timer tick, asking Buildbucket for the build status
[10:08:14.290] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:14.290] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:68:0) after 1m0s
[10:09:14.555] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:68:0)
[10:09:14.555] Timer tick, asking Buildbucket for the build status
[10:09:16.853] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:16.853] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:69:0) after 1m0s
[10:10:16.882] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:69:0)
[10:10:16.882] Timer tick, asking Buildbucket for the build status
[10:10:19.252] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:19.252] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:70:0) after 1m0s
[10:11:19.274] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:70:0)
[10:11:19.274] Timer tick, asking Buildbucket for the build status
[10:11:20.659] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:20.659] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:71:0) after 1m0s
[10:12:20.839] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:71:0)
[10:12:20.839] Timer tick, asking Buildbucket for the build status
[10:12:23.534] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:23.534] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:72:0) after 1m0s
[10:13:23.638] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:72:0)
[10:13:23.638] Timer tick, asking Buildbucket for the build status
[10:13:24.297] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:24.297] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:73:0) after 1m0s
[10:14:26.884] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:73:0)
[10:14:26.884] Timer tick, asking Buildbucket for the build status
[10:14:27.465] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:27.465] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:74:0) after 1m0s
[10:15:27.755] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:74:0)
[10:15:27.755] Timer tick, asking Buildbucket for the build status
[10:15:28.086] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:28.086] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:75:0) after 1m0s
[10:16:28.346] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:75:0)
[10:16:28.346] Timer tick, asking Buildbucket for the build status
[10:16:33.221] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:33.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:76:0) after 1m0s
[10:17:33.433] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:76:0)
[10:17:33.433] Timer tick, asking Buildbucket for the build status
[10:17:33.640] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:33.640] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:77:0) after 1m0s
[10:18:33.843] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:77:0)
[10:18:33.843] Timer tick, asking Buildbucket for the build status
[10:18:34.089] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:34.089] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:78:0) after 1m0s
[10:19:34.262] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:78:0)
[10:19:34.262] Timer tick, asking Buildbucket for the build status
[10:19:34.513] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:34.513] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:79:0) after 1m0s
[10:20:34.670] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:79:0)
[10:20:34.670] Timer tick, asking Buildbucket for the build status
[10:20:36.229] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:36.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:80:0) after 1m0s
[10:21:36.361] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:80:0)
[10:21:36.361] Timer tick, asking Buildbucket for the build status
[10:21:37.999] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:37.999] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:81:0) after 1m0s
[10:22:38.226] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:81:0)
[10:22:38.226] Timer tick, asking Buildbucket for the build status
[10:22:38.502] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:38.502] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:82:0) after 1m0s
[10:23:38.665] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:82:0)
[10:23:38.665] Timer tick, asking Buildbucket for the build status
[10:23:38.827] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:38.827] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:83:0) after 1m0s
[10:24:39.111] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:83:0)
[10:24:39.111] Timer tick, asking Buildbucket for the build status
[10:24:39.714] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:39.714] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:84:0) after 1m0s
[10:25:39.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:84:0)
[10:25:39.989] Timer tick, asking Buildbucket for the build status
[10:25:42.101] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:42.101] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:85:0) after 1m0s
[10:26:42.182] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:85:0)
[10:26:42.182] Timer tick, asking Buildbucket for the build status
[10:26:43.657] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:43.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:86:0) after 1m0s
[10:27:43.752] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:86:0)
[10:27:43.752] Timer tick, asking Buildbucket for the build status
[10:27:44.514] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:44.514] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:87:0) after 1m0s
[10:28:44.547] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:87:0)
[10:28:44.547] Timer tick, asking Buildbucket for the build status
[10:28:48.937] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:48.937] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:88:0) after 1m0s
[10:29:48.961] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:88:0)
[10:29:48.961] Timer tick, asking Buildbucket for the build status
[10:29:49.286] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:49.286] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:89:0) after 1m0s
[10:30:49.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:89:0)
[10:30:49.345] Timer tick, asking Buildbucket for the build status
[10:30:50.772] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:50.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:90:0) after 1m0s
[10:31:50.781] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:90:0)
[10:31:50.781] Timer tick, asking Buildbucket for the build status
[10:31:52.151] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:52.151] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:91:0) after 1m0s
[10:32:52.187] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:91:0)
[10:32:52.187] Timer tick, asking Buildbucket for the build status
[10:32:54.212] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:54.212] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:92:0) after 1m0s
[10:33:54.261] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:92:0)
[10:33:54.261] Timer tick, asking Buildbucket for the build status
[10:33:55.466] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:55.466] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:93:0) after 1m0s
[10:34:55.493] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:93:0)
[10:34:55.493] Timer tick, asking Buildbucket for the build status
[10:34:56.060] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:56.060] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:94:0) after 1m0s
[10:35:56.182] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:94:0)
[10:35:56.182] Timer tick, asking Buildbucket for the build status
[10:35:56.666] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:56.666] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:95:0) after 1m0s
[10:36:56.706] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:95:0)
[10:36:56.706] Timer tick, asking Buildbucket for the build status
[10:36:56.911] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:56.911] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:96:0) after 1m0s
[10:37:57.013] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:96:0)
[10:37:57.013] Timer tick, asking Buildbucket for the build status
[10:38:01.231] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:01.231] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:97:0) after 1m0s
[10:39:01.333] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:97:0)
[10:39:01.333] Timer tick, asking Buildbucket for the build status
[10:39:03.486] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:03.486] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:98:0) after 1m0s
[10:40:03.522] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:98:0)
[10:40:03.522] Timer tick, asking Buildbucket for the build status
[10:40:07.159] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:07.159] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:99:0) after 1m0s
[10:41:07.175] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:99:0)
[10:41:07.175] Timer tick, asking Buildbucket for the build status
[10:41:07.312] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:07.312] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:100:0) after 1m0s
[10:42:07.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:100:0)
[10:42:07.344] Timer tick, asking Buildbucket for the build status
[10:42:07.551] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:07.551] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:101:0) after 1m0s
[10:43:07.623] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:101:0)
[10:43:07.623] Timer tick, asking Buildbucket for the build status
[10:43:07.829] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:07.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:102:0) after 1m0s
[10:44:07.855] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:102:0)
[10:44:07.855] Timer tick, asking Buildbucket for the build status
[10:44:08.960] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:08.960] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:103:0) after 1m0s
[10:45:08.955] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:103:0)
[10:45:08.955] Timer tick, asking Buildbucket for the build status
[10:45:09.274] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:09.274] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:104:0) after 1m0s
[10:46:09.359] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:104:0)
[10:46:09.359] Timer tick, asking Buildbucket for the build status
[10:46:09.563] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:09.563] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:105:0) after 1m0s
[10:47:09.642] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:105:0)
[10:47:09.642] Timer tick, asking Buildbucket for the build status
[10:47:09.768] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:09.768] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:106:0) after 1m0s
[10:48:09.863] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:106:0)
[10:48:09.863] Timer tick, asking Buildbucket for the build status
[10:48:10.155] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:10.155] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:107:0) after 1m0s
[10:49:10.172] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:107:0)
[10:49:10.172] Timer tick, asking Buildbucket for the build status
[10:49:13.684] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:13.684] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:108:0) after 1m0s
[10:50:13.711] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:108:0)
[10:50:13.711] Timer tick, asking Buildbucket for the build status
[10:50:14.317] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:14.317] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:109:0) after 1m0s
[10:51:14.349] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:109:0)
[10:51:14.349] Timer tick, asking Buildbucket for the build status
[10:51:14.537] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:14.537] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:110:0) after 1m0s
[10:52:14.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:110:0)
[10:52:14.592] Timer tick, asking Buildbucket for the build status
[10:52:16.928] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:16.928] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:111:0) after 1m0s
[10:53:16.937] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:111:0)
[10:53:16.937] Timer tick, asking Buildbucket for the build status
[10:53:17.647] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:17.647] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:112:0) after 1m0s
[10:54:17.681] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:112:0)
[10:54:17.681] Timer tick, asking Buildbucket for the build status
[10:54:17.920] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:17.920] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:113:0) after 1m0s
[10:55:17.948] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:113:0)
[10:55:17.948] Timer tick, asking Buildbucket for the build status
[10:55:18.291] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:18.291] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:114:0) after 1m0s
[10:56:18.372] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:114:0)
[10:56:18.372] Timer tick, asking Buildbucket for the build status
[10:56:18.876] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:18.876] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:115:0) after 1m0s
[10:57:18.889] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:115:0)
[10:57:18.889] Timer tick, asking Buildbucket for the build status
[10:57:19.111] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:19.111] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:116:0) after 1m0s
[10:58:19.147] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:116:0)
[10:58:19.147] Timer tick, asking Buildbucket for the build status
[10:58:19.450] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:19.450] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:117:0) after 1m0s
[10:59:19.475] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:117:0)
[10:59:19.475] Timer tick, asking Buildbucket for the build status
[10:59:19.629] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:19.629] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:118:0) after 1m0s
[11:00:19.651] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:118:0)
[11:00:19.651] Timer tick, asking Buildbucket for the build status
[11:00:19.794] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:19.794] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:119:0) after 1m0s
[11:01:19.818] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:119:0)
[11:01:19.818] Timer tick, asking Buildbucket for the build status
[11:01:20.320] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:20.320] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:120:0) after 1m0s
[11:02:20.347] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:120:0)
[11:02:20.347] Timer tick, asking Buildbucket for the build status
[11:02:22.429] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:22.429] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:121:0) after 1m0s
[11:03:22.574] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:121:0)
[11:03:22.574] Timer tick, asking Buildbucket for the build status
[11:03:22.930] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:22.931] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:122:0) after 1m0s
[11:04:22.957] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:122:0)
[11:04:22.957] Timer tick, asking Buildbucket for the build status
[11:04:25.355] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:25.355] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:123:0) after 1m0s
[11:05:25.411] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:123:0)
[11:05:25.411] Timer tick, asking Buildbucket for the build status
[11:05:25.715] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:25.715] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:124:0) after 1m0s
[11:06:25.878] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:124:0)
[11:06:25.878] Timer tick, asking Buildbucket for the build status
[11:06:27.834] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:27.834] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:125:0) after 1m0s
[11:07:27.902] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:125:0)
[11:07:27.902] Timer tick, asking Buildbucket for the build status
[11:07:28.333] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:28.333] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:126:0) after 1m0s
[11:08:28.433] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:126:0)
[11:08:28.433] Timer tick, asking Buildbucket for the build status
[11:08:29.159] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:29.159] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:127:0) after 1m0s
[11:09:29.244] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:127:0)
[11:09:29.244] Timer tick, asking Buildbucket for the build status
[11:09:29.786] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:29.786] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:128:0) after 1m0s
[11:10:29.863] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:128:0)
[11:10:29.863] Timer tick, asking Buildbucket for the build status
[11:10:30.041] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:30.041] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:129:0) after 1m0s
[11:11:30.110] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:129:0)
[11:11:30.110] Timer tick, asking Buildbucket for the build status
[11:11:33.371] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:33.371] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:130:0) after 1m0s
[11:12:33.396] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:130:0)
[11:12:33.396] Timer tick, asking Buildbucket for the build status
[11:12:33.842] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:33.842] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:131:0) after 1m0s
[11:13:33.921] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:131:0)
[11:13:33.921] Timer tick, asking Buildbucket for the build status
[11:13:34.869] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:34.869] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:132:0) after 1m0s
[11:14:34.902] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:132:0)
[11:14:34.902] Timer tick, asking Buildbucket for the build status
[11:14:35.068] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:35.068] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:133:0) after 1m0s
[11:15:35.111] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:133:0)
[11:15:35.111] Timer tick, asking Buildbucket for the build status
[11:15:35.974] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:35.974] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:134:0) after 1m0s
[11:16:35.999] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:134:0)
[11:16:35.999] Timer tick, asking Buildbucket for the build status
[11:16:36.913] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:36.913] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:135:0) after 1m0s
[11:17:36.998] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:135:0)
[11:17:36.998] Timer tick, asking Buildbucket for the build status
[11:17:37.179] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:37.179] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:136:0) after 1m0s
[11:18:37.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:136:0)
[11:18:37.228] Timer tick, asking Buildbucket for the build status
[11:18:38.540] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:38.540] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:137:0) after 1m0s
[11:19:38.603] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:137:0)
[11:19:38.603] Timer tick, asking Buildbucket for the build status
[11:19:39.317] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:39.317] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:138:0) after 1m0s
[11:20:39.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:138:0)
[11:20:39.344] Timer tick, asking Buildbucket for the build status
[11:20:39.924] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:39.924] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:139:0) after 1m0s
[11:21:39.958] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:139:0)
[11:21:39.958] Timer tick, asking Buildbucket for the build status
[11:21:40.797] Build 8908060532651963728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:40.797] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073490595526164224:140:0) after 1m0s
[11:22:15.375] Received PubSub notification, asking Buildbucket for the build status
[11:22:15.648] Build 8908060532651963728: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:22:15.648] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1563016879539171",
"created_by": "project:chromium",
"created_ts": "1563008483225246",
"id": "8908060532651963728",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e469307ad838ec0643f0e3e9ec4828b066f4e8e1\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"windows-deterministic-0-xbv6\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 14115, \"got_angle_revision\": \"c3085ead7055e45b2d24189d5eee86753ea5c2f3\", \"got_dawn_revision\": \"2a1d8c2b1d32f4fe27f4bdc35287e1ebdeb8a86a\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"e469307ad838ec0643f0e3e9ec4828b066f4e8e1\", \"got_revision_cp\": \"refs/heads/master@{#677193}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"6e961cd4e639f72b48e3a0f038fef3cb0ecf2cc4\", \"got_v8_revision_cp\": \"refs/heads/7.7.219@{#2}\", \"got_webrtc_revision\": \"5a29d526be7589f5ba7fb824a749f9088b305070\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28568}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e469307ad838ec0643f0e3e9ec4828b066f4e8e1\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Windows deterministic\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"windows-deterministic-0-xbv6\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4370-0e704a3\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1563008500043672",
"status": "COMPLETED",
"status_changed_ts": "1563016934403057",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14115",
"builder:Windows deterministic",
"buildset:commit/git/e469307ad838ec0643f0e3e9ec4828b066f4e8e1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e469307ad838ec0643f0e3e9ec4828b066f4e8e1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9073490595526164224",
"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/8908060532651963728/+/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:4603626009e56410",
"user_agent:luci-scheduler"
],
"updated_ts": "1563016934449399",
"url": "https://ci.chromium.org/b/8908060532651963728",
"utcnow_ts": "1563016935640923"
}
}
[11:22:15.648] Invocation finished in 2h20m54.546661766s with status SUCCEEDED