[21:06:14.114] New invocation is queued and will start shortly
[21:06:15.234] Starting the invocation (attempt 1)
[21:06:15.309] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:06:15.310] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:06:15.310] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9078337226006549232",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"7e876a53493506b3137d855e1f0f7a492af243d9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9078337226006549232",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/7e876a53493506b3137d855e1f0f7a492af243d9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7e876a53493506b3137d855e1f0f7a492af243d9",
"gitiles_ref:refs/heads/master"
]
}
[21:06:16.918] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1558386375586010",
"id": "8912907163791675152",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"7e876a53493506b3137d855e1f0f7a492af243d9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558386376606814",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13467",
"builder:Windows deterministic",
"buildset:commit/git/7e876a53493506b3137d855e1f0f7a492af243d9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7e876a53493506b3137d855e1f0f7a492af243d9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078337226006549232",
"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/8912907163791675152/+/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": "1558386376606947",
"url": "https://ci.chromium.org/b/8912907163791675152",
"utcnow_ts": "1558386376897182"
}
}
[21:06:16.918] Task URL: https://ci.chromium.org/b/8912907163791675152
[21:06:16.918] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:2:0) after 1m0s
[21:06:23.110] Received PubSub notification, asking Buildbucket for the build status
[21:06:24.131] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:16.940] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:2:0)
[21:07:16.940] Timer tick, asking Buildbucket for the build status
[21:07:17.711] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:17.712] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:4:0) after 1m0s
[21:08:17.701] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:4:0)
[21:08:17.701] Timer tick, asking Buildbucket for the build status
[21:08:17.883] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:17.884] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:5:0) after 1m0s
[21:09:17.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:5:0)
[21:09:17.905] Timer tick, asking Buildbucket for the build status
[21:09:18.005] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:18.005] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:6:0) after 1m0s
[21:10:18.046] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:6:0)
[21:10:18.046] Timer tick, asking Buildbucket for the build status
[21:10:18.386] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:18.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:7:0) after 1m0s
[21:11:18.360] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:7:0)
[21:11:18.360] Timer tick, asking Buildbucket for the build status
[21:11:18.591] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:18.591] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:8:0) after 1m0s
[21:12:18.698] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:8:0)
[21:12:18.698] Timer tick, asking Buildbucket for the build status
[21:12:31.816] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:31.816] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:9:0) after 1m0s
[21:13:31.873] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:9:0)
[21:13:31.873] Timer tick, asking Buildbucket for the build status
[21:13:32.835] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:32.835] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:10:0) after 1m0s
[21:14:32.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:10:0)
[21:14:32.815] Timer tick, asking Buildbucket for the build status
[21:14:33.159] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:33.159] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:11:0) after 1m0s
[21:15:33.193] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:11:0)
[21:15:33.193] Timer tick, asking Buildbucket for the build status
[21:15:33.439] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:33.439] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:12:0) after 1m0s
[21:16:33.462] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:12:0)
[21:16:33.462] Timer tick, asking Buildbucket for the build status
[21:16:33.673] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:33.673] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:13:0) after 1m0s
[21:17:33.698] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:13:0)
[21:17:33.698] Timer tick, asking Buildbucket for the build status
[21:17:34.069] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:34.069] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:14:0) after 1m0s
[21:18:34.067] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:14:0)
[21:18:34.067] Timer tick, asking Buildbucket for the build status
[21:18:34.865] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:34.865] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:15:0) after 1m0s
[21:19:34.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:15:0)
[21:19:34.910] Timer tick, asking Buildbucket for the build status
[21:19:37.109] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:37.109] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:16:0) after 1m0s
[21:20:37.129] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:16:0)
[21:20:37.129] Timer tick, asking Buildbucket for the build status
[21:20:38.745] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:38.745] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:17:0) after 1m0s
[21:21:39.024] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:17:0)
[21:21:39.024] Timer tick, asking Buildbucket for the build status
[21:21:39.932] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:39.932] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:18:0) after 1m0s
[21:22:39.954] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:18:0)
[21:22:39.954] Timer tick, asking Buildbucket for the build status
[21:22:40.093] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:40.093] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:19:0) after 1m0s
[21:23:40.127] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:19:0)
[21:23:40.127] Timer tick, asking Buildbucket for the build status
[21:23:40.229] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:40.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:20:0) after 1m0s
[21:24:40.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:20:0)
[21:24:40.274] Timer tick, asking Buildbucket for the build status
[21:24:40.386] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:40.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:21:0) after 1m0s
[21:25:40.399] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:21:0)
[21:25:40.399] Timer tick, asking Buildbucket for the build status
[21:25:42.304] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:42.304] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:22:0) after 1m0s
[21:26:42.395] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:22:0)
[21:26:42.395] Timer tick, asking Buildbucket for the build status
[21:26:42.721] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:42.721] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:23:0) after 1m0s
[21:27:42.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:23:0)
[21:27:42.757] Timer tick, asking Buildbucket for the build status
[21:27:44.996] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:44.996] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:24:0) after 1m0s
[21:28:44.981] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:24:0)
[21:28:44.981] Timer tick, asking Buildbucket for the build status
[21:28:46.857] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:46.857] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:25:0) after 1m0s
[21:29:46.836] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:25:0)
[21:29:46.836] Timer tick, asking Buildbucket for the build status
[21:29:46.979] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:46.979] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:26:0) after 1m0s
[21:30:47.008] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:26:0)
[21:30:47.008] Timer tick, asking Buildbucket for the build status
[21:30:47.390] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:47.390] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:27:0) after 1m0s
[21:31:47.415] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:27:0)
[21:31:47.415] Timer tick, asking Buildbucket for the build status
[21:31:49.984] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:49.984] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:28:0) after 1m0s
[21:32:50.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:28:0)
[21:32:50.004] Timer tick, asking Buildbucket for the build status
[21:32:50.441] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:50.441] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:29:0) after 1m0s
[21:33:50.460] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:29:0)
[21:33:50.460] Timer tick, asking Buildbucket for the build status
[21:33:50.595] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:50.595] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:30:0) after 1m0s
[21:34:50.576] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:30:0)
[21:34:50.576] Timer tick, asking Buildbucket for the build status
[21:34:50.697] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:50.697] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:31:0) after 1m0s
[21:35:50.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:31:0)
[21:35:50.683] Timer tick, asking Buildbucket for the build status
[21:35:50.844] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:50.844] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:32:0) after 1m0s
[21:36:50.842] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:32:0)
[21:36:50.842] Timer tick, asking Buildbucket for the build status
[21:36:51.876] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:51.876] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:33:0) after 1m0s
[21:37:51.887] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:33:0)
[21:37:51.887] Timer tick, asking Buildbucket for the build status
[21:37:53.059] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:53.059] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:34:0) after 1m0s
[21:38:53.051] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:34:0)
[21:38:53.051] Timer tick, asking Buildbucket for the build status
[21:38:54.907] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:54.907] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:35:0) after 1m0s
[21:39:54.949] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:35:0)
[21:39:54.949] Timer tick, asking Buildbucket for the build status
[21:39:55.286] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:55.286] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:36:0) after 1m0s
[21:40:55.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:36:0)
[21:40:55.308] Timer tick, asking Buildbucket for the build status
[21:40:55.733] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:55.733] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:37:0) after 1m0s
[21:41:55.762] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:37:0)
[21:41:55.762] Timer tick, asking Buildbucket for the build status
[21:41:57.851] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:57.851] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:38:0) after 1m0s
[21:42:58.011] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:38:0)
[21:42:58.011] Timer tick, asking Buildbucket for the build status
[21:42:58.299] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:58.299] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:39:0) after 1m0s
[21:43:58.325] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:39:0)
[21:43:58.325] Timer tick, asking Buildbucket for the build status
[21:43:58.745] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:58.745] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:40:0) after 1m0s
[21:44:58.824] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:40:0)
[21:44:58.824] Timer tick, asking Buildbucket for the build status
[21:44:58.970] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:58.970] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:41:0) after 1m0s
[21:45:58.983] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:41:0)
[21:45:58.983] Timer tick, asking Buildbucket for the build status
[21:46:00.784] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:00.784] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:42:0) after 1m0s
[21:47:00.821] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:42:0)
[21:47:00.821] Timer tick, asking Buildbucket for the build status
[21:47:00.984] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:00.984] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:43:0) after 1m0s
[21:48:01.011] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:43:0)
[21:48:01.011] Timer tick, asking Buildbucket for the build status
[21:48:01.490] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:01.490] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:44:0) after 1m0s
[21:49:01.471] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:44:0)
[21:49:01.471] Timer tick, asking Buildbucket for the build status
[21:49:02.056] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:02.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:45:0) after 1m0s
[21:50:02.073] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:45:0)
[21:50:02.073] Timer tick, asking Buildbucket for the build status
[21:50:02.951] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:02.951] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:46:0) after 1m0s
[21:51:02.934] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:46:0)
[21:51:02.934] Timer tick, asking Buildbucket for the build status
[21:51:04.551] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:04.551] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:47:0) after 1m0s
[21:52:04.583] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:47:0)
[21:52:04.583] Timer tick, asking Buildbucket for the build status
[21:52:04.729] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:04.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:48:0) after 1m0s
[21:53:04.796] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:48:0)
[21:53:04.796] Timer tick, asking Buildbucket for the build status
[21:53:06.569] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:06.569] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:49:0) after 1m0s
[21:54:06.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:49:0)
[21:54:06.598] Timer tick, asking Buildbucket for the build status
[21:54:06.869] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:06.869] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:50:0) after 1m0s
[21:55:06.933] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:50:0)
[21:55:06.933] Timer tick, asking Buildbucket for the build status
[21:55:07.198] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:07.198] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:51:0) after 1m0s
[21:56:07.846] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:51:0)
[21:56:07.846] Timer tick, asking Buildbucket for the build status
[21:56:07.965] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:07.965] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:52:0) after 1m0s
[21:57:07.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:52:0)
[21:57:07.989] Timer tick, asking Buildbucket for the build status
[21:57:08.079] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:08.079] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:53:0) after 1m0s
[21:58:08.066] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:53:0)
[21:58:08.066] Timer tick, asking Buildbucket for the build status
[21:58:08.217] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:08.217] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:54:0) after 1m0s
[21:59:08.257] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:54:0)
[21:59:08.257] Timer tick, asking Buildbucket for the build status
[21:59:08.368] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:08.368] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:55:0) after 1m0s
[22:00:08.405] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:55:0)
[22:00:08.405] Timer tick, asking Buildbucket for the build status
[22:00:08.567] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:08.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:56:0) after 1m0s
[22:01:08.678] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:56:0)
[22:01:08.678] Timer tick, asking Buildbucket for the build status
[22:01:08.816] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:08.816] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:57:0) after 1m0s
[22:02:08.854] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:57:0)
[22:02:08.854] Timer tick, asking Buildbucket for the build status
[22:02:09.042] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:09.042] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:58:0) after 1m0s
[22:03:09.064] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:58:0)
[22:03:09.064] Timer tick, asking Buildbucket for the build status
[22:03:09.360] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:09.360] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:59:0) after 1m0s
[22:04:09.494] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:59:0)
[22:04:09.494] Timer tick, asking Buildbucket for the build status
[22:04:10.081] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:10.081] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:60:0) after 1m0s
[22:05:10.229] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:60:0)
[22:05:10.229] Timer tick, asking Buildbucket for the build status
[22:05:10.411] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:10.411] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:61:0) after 1m0s
[22:06:10.452] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:61:0)
[22:06:10.452] Timer tick, asking Buildbucket for the build status
[22:06:11.416] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:11.416] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:62:0) after 1m0s
[22:07:11.441] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:62:0)
[22:07:11.441] Timer tick, asking Buildbucket for the build status
[22:07:11.580] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:11.580] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:63:0) after 1m0s
[22:08:11.672] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:63:0)
[22:08:11.672] Timer tick, asking Buildbucket for the build status
[22:08:12.039] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:12.039] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:64:0) after 1m0s
[22:09:12.201] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:64:0)
[22:09:12.201] Timer tick, asking Buildbucket for the build status
[22:09:12.427] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:12.427] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:65:0) after 1m0s
[22:10:12.659] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:65:0)
[22:10:12.659] Timer tick, asking Buildbucket for the build status
[22:10:13.058] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:13.058] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:66:0) after 1m0s
[22:11:13.059] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:66:0)
[22:11:13.059] Timer tick, asking Buildbucket for the build status
[22:11:13.162] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:13.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:67:0) after 1m0s
[22:12:13.235] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:67:0)
[22:12:13.235] Timer tick, asking Buildbucket for the build status
[22:12:13.936] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:13.936] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:68:0) after 1m0s
[22:13:14.071] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:68:0)
[22:13:14.071] Timer tick, asking Buildbucket for the build status
[22:13:14.229] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:14.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:69:0) after 1m0s
[22:14:14.260] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:69:0)
[22:14:14.260] Timer tick, asking Buildbucket for the build status
[22:14:16.174] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:16.174] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:70:0) after 1m0s
[22:15:16.155] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:70:0)
[22:15:16.155] Timer tick, asking Buildbucket for the build status
[22:15:16.265] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:16.265] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:71:0) after 1m0s
[22:16:16.303] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:71:0)
[22:16:16.303] Timer tick, asking Buildbucket for the build status
[22:16:16.420] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:16.420] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:72:0) after 1m0s
[22:17:16.427] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:72:0)
[22:17:16.427] Timer tick, asking Buildbucket for the build status
[22:17:16.523] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:16.523] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:73:0) after 1m0s
[22:18:16.546] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:73:0)
[22:18:16.546] Timer tick, asking Buildbucket for the build status
[22:18:16.723] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:16.723] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:74:0) after 1m0s
[22:19:16.744] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:74:0)
[22:19:16.744] Timer tick, asking Buildbucket for the build status
[22:19:17.214] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:17.214] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:75:0) after 1m0s
[22:20:17.217] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:75:0)
[22:20:17.217] Timer tick, asking Buildbucket for the build status
[22:20:17.599] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:17.599] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:76:0) after 1m0s
[22:21:17.627] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:76:0)
[22:21:17.627] Timer tick, asking Buildbucket for the build status
[22:21:18.789] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:18.789] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:77:0) after 1m0s
[22:22:18.811] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:77:0)
[22:22:18.811] Timer tick, asking Buildbucket for the build status
[22:22:18.915] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:18.915] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:78:0) after 1m0s
[22:23:18.983] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:78:0)
[22:23:18.983] Timer tick, asking Buildbucket for the build status
[22:23:19.184] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:19.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:79:0) after 1m0s
[22:24:19.204] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:79:0)
[22:24:19.204] Timer tick, asking Buildbucket for the build status
[22:24:19.669] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:19.669] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:80:0) after 1m0s
[22:25:19.692] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:80:0)
[22:25:19.692] Timer tick, asking Buildbucket for the build status
[22:25:19.793] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:19.793] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:81:0) after 1m0s
[22:26:19.831] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:81:0)
[22:26:19.831] Timer tick, asking Buildbucket for the build status
[22:26:19.917] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:19.917] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:82:0) after 1m0s
[22:27:19.896] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:82:0)
[22:27:19.896] Timer tick, asking Buildbucket for the build status
[22:27:19.986] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:19.986] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:83:0) after 1m0s
[22:28:20.012] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:83:0)
[22:28:20.012] Timer tick, asking Buildbucket for the build status
[22:28:20.165] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:20.165] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:84:0) after 1m0s
[22:29:20.146] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:84:0)
[22:29:20.146] Timer tick, asking Buildbucket for the build status
[22:29:21.081] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:21.081] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:85:0) after 1m0s
[22:30:21.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:85:0)
[22:30:21.087] Timer tick, asking Buildbucket for the build status
[22:30:21.418] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:21.418] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:86:0) after 1m0s
[22:31:21.445] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:86:0)
[22:31:21.445] Timer tick, asking Buildbucket for the build status
[22:31:22.197] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:22.197] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:87:0) after 1m0s
[22:32:22.170] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:87:0)
[22:32:22.170] Timer tick, asking Buildbucket for the build status
[22:32:22.370] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:22.370] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:88:0) after 1m0s
[22:33:22.398] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:88:0)
[22:33:22.398] Timer tick, asking Buildbucket for the build status
[22:33:22.575] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:22.575] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:89:0) after 1m0s
[22:34:22.625] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:89:0)
[22:34:22.625] Timer tick, asking Buildbucket for the build status
[22:34:22.820] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:22.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:90:0) after 1m0s
[22:35:22.874] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:90:0)
[22:35:22.874] Timer tick, asking Buildbucket for the build status
[22:35:22.984] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:22.984] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:91:0) after 1m0s
[22:36:22.964] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:91:0)
[22:36:22.964] Timer tick, asking Buildbucket for the build status
[22:36:23.074] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:23.074] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:92:0) after 1m0s
[22:37:23.120] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:92:0)
[22:37:23.120] Timer tick, asking Buildbucket for the build status
[22:37:23.202] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:23.202] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:93:0) after 1m0s
[22:38:23.226] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:93:0)
[22:38:23.226] Timer tick, asking Buildbucket for the build status
[22:38:23.337] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:23.337] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:94:0) after 1m0s
[22:39:23.370] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:94:0)
[22:39:23.370] Timer tick, asking Buildbucket for the build status
[22:39:24.814] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:24.814] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:95:0) after 1m0s
[22:40:24.837] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:95:0)
[22:40:24.837] Timer tick, asking Buildbucket for the build status
[22:40:25.377] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:25.377] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:96:0) after 1m0s
[22:41:25.378] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:96:0)
[22:41:25.378] Timer tick, asking Buildbucket for the build status
[22:41:25.894] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:25.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:97:0) after 1m0s
[22:42:25.943] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:97:0)
[22:42:25.943] Timer tick, asking Buildbucket for the build status
[22:42:26.183] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:26.183] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:98:0) after 1m0s
[22:43:27.983] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:98:0)
[22:43:27.983] Timer tick, asking Buildbucket for the build status
[22:43:28.998] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:28.998] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:99:0) after 1m0s
[22:44:29.962] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:99:0)
[22:44:29.962] Timer tick, asking Buildbucket for the build status
[22:44:30.527] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:30.527] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:100:0) after 1m0s
[22:45:30.558] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:100:0)
[22:45:30.558] Timer tick, asking Buildbucket for the build status
[22:45:30.834] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:30.834] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:101:0) after 1m0s
[22:46:30.913] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:101:0)
[22:46:30.913] Timer tick, asking Buildbucket for the build status
[22:46:31.776] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:31.776] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:102:0) after 1m0s
[22:47:31.760] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:102:0)
[22:47:31.760] Timer tick, asking Buildbucket for the build status
[22:47:32.020] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:32.020] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:103:0) after 1m0s
[22:48:32.075] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:103:0)
[22:48:32.075] Timer tick, asking Buildbucket for the build status
[22:48:32.277] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:32.277] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:104:0) after 1m0s
[22:49:32.413] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:104:0)
[22:49:32.413] Timer tick, asking Buildbucket for the build status
[22:49:33.149] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:33.149] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:105:0) after 1m0s
[22:50:33.178] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:105:0)
[22:50:33.178] Timer tick, asking Buildbucket for the build status
[22:50:33.738] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:33.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:106:0) after 1m0s
[22:51:34.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:106:0)
[22:51:34.087] Timer tick, asking Buildbucket for the build status
[22:51:34.194] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:34.194] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:107:0) after 1m0s
[22:52:34.214] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:107:0)
[22:52:34.214] Timer tick, asking Buildbucket for the build status
[22:52:34.313] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:34.313] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:108:0) after 1m0s
[22:53:34.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:108:0)
[22:53:34.308] Timer tick, asking Buildbucket for the build status
[22:53:34.693] Build 8912907163791675152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:34.693] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078337226006549232:109:0) after 1m0s
[22:53:44.579] Received PubSub notification, asking Buildbucket for the build status
[22:53:45.012] Build 8912907163791675152: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:53:45.012] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1558392823800037",
"created_by": "project:chromium",
"created_ts": "1558386375586010",
"id": "8912907163791675152",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"7e876a53493506b3137d855e1f0f7a492af243d9\"}}",
"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-7052\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 13467, \"got_angle_revision\": \"7a026133ee45d4088d7a86fd464116fc3a186c36\", \"got_dawn_revision\": \"2bc3169f0d64a07469b73c318a2e4c887f8eb17a\", \"got_nacl_revision\": \"ca374f5112fca6b36fc5d73e117a60b82f394993\", \"got_revision\": \"7e876a53493506b3137d855e1f0f7a492af243d9\", \"got_revision_cp\": \"refs/heads/master@{#661467}\", \"got_swarming_client_revision\": \"1b65f4e862045f3ba430a4cbd0643f5b1b66c230\", \"got_v8_revision\": \"71714da93ff99adc9e79a7813328ea91660573e5\", \"got_v8_revision_cp\": \"refs/heads/7.6.222@{#1}\", \"got_webrtc_revision\": \"94079f84523b8c12f1732594cb5995a7fdaa9d36\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27990}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"7e876a53493506b3137d855e1f0f7a492af243d9\"}, \"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-7052\"], \"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\": [\"4302-e8210c4\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1558386380875673",
"status": "COMPLETED",
"status_changed_ts": "1558392824194710",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13467",
"builder:Windows deterministic",
"buildset:commit/git/7e876a53493506b3137d855e1f0f7a492af243d9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7e876a53493506b3137d855e1f0f7a492af243d9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078337226006549232",
"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/8912907163791675152/+/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:44efe28abd40d110",
"user_agent:luci-scheduler"
],
"updated_ts": "1558392824204936",
"url": "https://ci.chromium.org/b/8912907163791675152",
"utcnow_ts": "1558392824997543"
}
}
[22:53:45.012] Invocation finished in 1h47m30.922167798s with status SUCCEEDED