[05:38:16.116] New invocation is queued and will start shortly
[05:38:17.203] Starting the invocation (attempt 1)
[05:38:17.324] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:38:17.324] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:38:17.324] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9074499939058507472",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"8d604f7179b4aca2ab4aa2f61d7df284f4ec32e5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9074499939058507472",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/8d604f7179b4aca2ab4aa2f61d7df284f4ec32e5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d604f7179b4aca2ab4aa2f61d7df284f4ec32e5",
"gitiles_ref:refs/heads/master"
]
}
[05:38:18.713] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1562045897749143",
"id": "8909069876679904816",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"8d604f7179b4aca2ab4aa2f61d7df284f4ec32e5\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1562045898592802",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13997",
"builder:Windows deterministic",
"buildset:commit/git/8d604f7179b4aca2ab4aa2f61d7df284f4ec32e5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d604f7179b4aca2ab4aa2f61d7df284f4ec32e5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9074499939058507472",
"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/8909069876679904816/+/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": "1562045898592913",
"url": "https://ci.chromium.org/b/8909069876679904816",
"utcnow_ts": "1562045898703519"
}
}
[05:38:18.713] Task URL: https://ci.chromium.org/b/8909069876679904816
[05:38:18.713] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:2:0) after 1m0s
[05:38:24.675] Received PubSub notification, asking Buildbucket for the build status
[05:38:24.802] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:18.839] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:2:0)
[05:39:18.839] Timer tick, asking Buildbucket for the build status
[05:39:19.023] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:19.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:4:0) after 1m0s
[05:40:19.096] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:4:0)
[05:40:19.096] Timer tick, asking Buildbucket for the build status
[05:40:20.319] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:20.319] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:5:0) after 1m0s
[05:41:20.436] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:5:0)
[05:41:20.436] Timer tick, asking Buildbucket for the build status
[05:41:20.553] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:20.554] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:6:0) after 1m0s
[05:42:20.821] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:6:0)
[05:42:20.821] Timer tick, asking Buildbucket for the build status
[05:42:20.958] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:20.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:7:0) after 1m0s
[05:43:21.124] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:7:0)
[05:43:21.124] Timer tick, asking Buildbucket for the build status
[05:43:21.380] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:21.380] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:8:0) after 1m0s
[05:44:21.502] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:8:0)
[05:44:21.502] Timer tick, asking Buildbucket for the build status
[05:44:21.765] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:21.765] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:9:0) after 1m0s
[05:45:21.836] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:9:0)
[05:45:21.836] Timer tick, asking Buildbucket for the build status
[05:45:22.342] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:22.342] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:10:0) after 1m0s
[05:46:22.364] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:10:0)
[05:46:22.364] Timer tick, asking Buildbucket for the build status
[05:46:23.109] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:23.109] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:11:0) after 1m0s
[05:47:23.141] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:11:0)
[05:47:23.141] Timer tick, asking Buildbucket for the build status
[05:47:23.919] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:23.919] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:12:0) after 1m0s
[05:48:23.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:12:0)
[05:48:23.968] Timer tick, asking Buildbucket for the build status
[05:48:24.159] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:24.159] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:13:0) after 1m0s
[05:49:24.209] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:13:0)
[05:49:24.209] Timer tick, asking Buildbucket for the build status
[05:49:24.432] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:24.432] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:14:0) after 1m0s
[05:50:24.549] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:14:0)
[05:50:24.549] Timer tick, asking Buildbucket for the build status
[05:50:24.922] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:24.922] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:15:0) after 1m0s
[05:51:24.949] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:15:0)
[05:51:24.949] Timer tick, asking Buildbucket for the build status
[05:51:25.803] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:25.803] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:16:0) after 1m0s
[05:52:25.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:16:0)
[05:52:25.938] Timer tick, asking Buildbucket for the build status
[05:52:26.796] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:26.796] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:17:0) after 1m0s
[05:53:26.816] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:17:0)
[05:53:26.816] Timer tick, asking Buildbucket for the build status
[05:53:27.303] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:27.303] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:18:0) after 1m0s
[05:54:27.422] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:18:0)
[05:54:27.422] Timer tick, asking Buildbucket for the build status
[05:54:27.619] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:27.619] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:19:0) after 1m0s
[05:55:27.688] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:19:0)
[05:55:27.688] Timer tick, asking Buildbucket for the build status
[05:55:27.828] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:27.828] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:20:0) after 1m0s
[05:56:27.899] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:20:0)
[05:56:27.899] Timer tick, asking Buildbucket for the build status
[05:56:28.829] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:28.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:21:0) after 1m0s
[05:57:28.871] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:21:0)
[05:57:28.871] Timer tick, asking Buildbucket for the build status
[05:57:28.973] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:28.973] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:22:0) after 1m0s
[05:58:29.082] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:22:0)
[05:58:29.082] Timer tick, asking Buildbucket for the build status
[05:58:29.992] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:29.992] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:23:0) after 1m0s
[05:59:29.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:23:0)
[05:59:29.988] Timer tick, asking Buildbucket for the build status
[05:59:30.242] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:30.242] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:24:0) after 1m0s
[06:00:30.274] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:24:0)
[06:00:30.274] Timer tick, asking Buildbucket for the build status
[06:00:30.403] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:30.403] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:25:0) after 1m0s
[06:01:30.479] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:25:0)
[06:01:30.479] Timer tick, asking Buildbucket for the build status
[06:01:31.949] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:31.949] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:26:0) after 1m0s
[06:02:32.055] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:26:0)
[06:02:32.055] Timer tick, asking Buildbucket for the build status
[06:02:32.266] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:32.266] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:27:0) after 1m0s
[06:03:32.718] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:27:0)
[06:03:32.718] Timer tick, asking Buildbucket for the build status
[06:03:33.162] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:33.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:28:0) after 1m0s
[06:04:33.185] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:28:0)
[06:04:33.185] Timer tick, asking Buildbucket for the build status
[06:04:33.289] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:33.289] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:29:0) after 1m0s
[06:05:33.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:29:0)
[06:05:33.579] Timer tick, asking Buildbucket for the build status
[06:05:34.063] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:34.063] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:30:0) after 1m0s
[06:06:34.339] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:30:0)
[06:06:34.339] Timer tick, asking Buildbucket for the build status
[06:06:34.931] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:34.931] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:31:0) after 1m0s
[06:07:35.074] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:31:0)
[06:07:35.074] Timer tick, asking Buildbucket for the build status
[06:07:35.483] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:35.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:32:0) after 1m0s
[06:08:35.593] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:32:0)
[06:08:35.593] Timer tick, asking Buildbucket for the build status
[06:08:36.075] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:36.075] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:33:0) after 1m0s
[06:09:36.286] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:33:0)
[06:09:36.286] Timer tick, asking Buildbucket for the build status
[06:09:36.494] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:36.494] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:34:0) after 1m0s
[06:10:36.563] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:34:0)
[06:10:36.563] Timer tick, asking Buildbucket for the build status
[06:10:38.939] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:38.939] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:35:0) after 1m0s
[06:11:39.022] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:35:0)
[06:11:39.022] Timer tick, asking Buildbucket for the build status
[06:11:40.126] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:40.126] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:36:0) after 1m0s
[06:12:40.171] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:36:0)
[06:12:40.171] Timer tick, asking Buildbucket for the build status
[06:12:40.722] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:40.722] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:37:0) after 1m0s
[06:13:40.840] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:37:0)
[06:13:40.840] Timer tick, asking Buildbucket for the build status
[06:13:44.982] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:44.982] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:38:0) after 1m0s
[06:14:45.033] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:38:0)
[06:14:45.033] Timer tick, asking Buildbucket for the build status
[06:14:45.327] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:45.327] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:39:0) after 1m0s
[06:15:45.346] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:39:0)
[06:15:45.346] Timer tick, asking Buildbucket for the build status
[06:15:45.556] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:45.556] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:40:0) after 1m0s
[06:16:45.844] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:40:0)
[06:16:45.844] Timer tick, asking Buildbucket for the build status
[06:16:46.046] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:46.046] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:41:0) after 1m0s
[06:17:46.140] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:41:0)
[06:17:46.140] Timer tick, asking Buildbucket for the build status
[06:17:46.305] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:46.305] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:42:0) after 1m0s
[06:18:46.475] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:42:0)
[06:18:46.475] Timer tick, asking Buildbucket for the build status
[06:18:47.375] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:47.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:43:0) after 1m0s
[06:19:47.543] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:43:0)
[06:19:47.543] Timer tick, asking Buildbucket for the build status
[06:19:47.886] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:47.886] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:44:0) after 1m0s
[06:20:48.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:44:0)
[06:20:48.087] Timer tick, asking Buildbucket for the build status
[06:20:48.808] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:48.808] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:45:0) after 1m0s
[06:21:48.880] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:45:0)
[06:21:48.880] Timer tick, asking Buildbucket for the build status
[06:21:51.716] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:51.716] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:46:0) after 1m0s
[06:22:51.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:46:0)
[06:22:51.869] Timer tick, asking Buildbucket for the build status
[06:22:54.381] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:54.381] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:47:0) after 1m0s
[06:23:54.541] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:47:0)
[06:23:54.541] Timer tick, asking Buildbucket for the build status
[06:23:55.312] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:55.312] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:48:0) after 1m0s
[06:24:55.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:48:0)
[06:24:55.417] Timer tick, asking Buildbucket for the build status
[06:24:55.577] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:55.577] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:49:0) after 1m0s
[06:25:55.639] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:49:0)
[06:25:55.639] Timer tick, asking Buildbucket for the build status
[06:25:57.548] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:57.548] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:50:0) after 1m0s
[06:26:57.962] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:50:0)
[06:26:57.962] Timer tick, asking Buildbucket for the build status
[06:27:00.175] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:00.175] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:51:0) after 1m0s
[06:28:00.422] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:51:0)
[06:28:00.422] Timer tick, asking Buildbucket for the build status
[06:28:01.027] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:01.027] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:52:0) after 1m0s
[06:29:01.150] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:52:0)
[06:29:01.150] Timer tick, asking Buildbucket for the build status
[06:29:01.410] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:01.410] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:53:0) after 1m0s
[06:30:01.515] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:53:0)
[06:30:01.515] Timer tick, asking Buildbucket for the build status
[06:30:01.894] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:01.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:54:0) after 1m0s
[06:31:02.099] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:54:0)
[06:31:02.099] Timer tick, asking Buildbucket for the build status
[06:31:02.333] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:02.333] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:55:0) after 1m0s
[06:32:02.479] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:55:0)
[06:32:02.479] Timer tick, asking Buildbucket for the build status
[06:32:03.804] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:03.804] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:56:0) after 1m0s
[06:33:03.826] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:56:0)
[06:33:03.826] Timer tick, asking Buildbucket for the build status
[06:33:03.990] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:03.990] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:57:0) after 1m0s
[06:34:04.015] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:57:0)
[06:34:04.015] Timer tick, asking Buildbucket for the build status
[06:34:04.197] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:04.197] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:58:0) after 1m0s
[06:35:04.293] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:58:0)
[06:35:04.293] Timer tick, asking Buildbucket for the build status
[06:35:04.913] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:04.913] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:59:0) after 1m0s
[06:36:05.037] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:59:0)
[06:36:05.037] Timer tick, asking Buildbucket for the build status
[06:36:06.930] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:06.930] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:60:0) after 1m0s
[06:37:07.055] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:60:0)
[06:37:07.055] Timer tick, asking Buildbucket for the build status
[06:37:08.703] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:08.703] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:61:0) after 1m0s
[06:38:08.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:61:0)
[06:38:08.773] Timer tick, asking Buildbucket for the build status
[06:38:09.196] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:09.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:62:0) after 1m0s
[06:39:09.332] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:62:0)
[06:39:09.332] Timer tick, asking Buildbucket for the build status
[06:39:10.533] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:10.533] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:63:0) after 1m0s
[06:40:10.537] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:63:0)
[06:40:10.537] Timer tick, asking Buildbucket for the build status
[06:40:10.909] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:10.909] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:64:0) after 1m0s
[06:41:11.215] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:64:0)
[06:41:11.215] Timer tick, asking Buildbucket for the build status
[06:41:11.485] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:11.485] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:65:0) after 1m0s
[06:42:12.069] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:65:0)
[06:42:12.069] Timer tick, asking Buildbucket for the build status
[06:42:13.504] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:13.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:66:0) after 1m0s
[06:43:13.916] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:66:0)
[06:43:13.916] Timer tick, asking Buildbucket for the build status
[06:43:14.255] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:14.255] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:67:0) after 1m0s
[06:44:14.287] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:67:0)
[06:44:14.287] Timer tick, asking Buildbucket for the build status
[06:44:14.509] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:14.509] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:68:0) after 1m0s
[06:45:14.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:68:0)
[06:45:14.598] Timer tick, asking Buildbucket for the build status
[06:45:14.881] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:14.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:69:0) after 1m0s
[06:46:14.996] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:69:0)
[06:46:14.996] Timer tick, asking Buildbucket for the build status
[06:46:15.158] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:15.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:70:0) after 1m0s
[06:47:15.454] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:70:0)
[06:47:15.454] Timer tick, asking Buildbucket for the build status
[06:47:16.126] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:16.126] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:71:0) after 1m0s
[06:48:16.257] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:71:0)
[06:48:16.257] Timer tick, asking Buildbucket for the build status
[06:48:16.416] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:16.416] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:72:0) after 1m0s
[06:49:16.439] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:72:0)
[06:49:16.439] Timer tick, asking Buildbucket for the build status
[06:49:16.727] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:16.727] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:73:0) after 1m0s
[06:50:16.833] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:73:0)
[06:50:16.833] Timer tick, asking Buildbucket for the build status
[06:50:16.946] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:16.946] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:74:0) after 1m0s
[06:51:17.024] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:74:0)
[06:51:17.024] Timer tick, asking Buildbucket for the build status
[06:51:17.462] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:17.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:75:0) after 1m0s
[06:52:17.613] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:75:0)
[06:52:17.613] Timer tick, asking Buildbucket for the build status
[06:52:17.721] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:17.721] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:76:0) after 1m0s
[06:53:17.878] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:76:0)
[06:53:17.878] Timer tick, asking Buildbucket for the build status
[06:53:18.069] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:18.069] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:77:0) after 1m0s
[06:54:18.244] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:77:0)
[06:54:18.244] Timer tick, asking Buildbucket for the build status
[06:54:18.359] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:18.360] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:78:0) after 1m0s
[06:55:18.370] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:78:0)
[06:55:18.370] Timer tick, asking Buildbucket for the build status
[06:55:18.762] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:18.762] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:79:0) after 1m0s
[06:56:19.097] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:79:0)
[06:56:19.097] Timer tick, asking Buildbucket for the build status
[06:56:19.262] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:19.262] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:80:0) after 1m0s
[06:57:19.406] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:80:0)
[06:57:19.406] Timer tick, asking Buildbucket for the build status
[06:57:19.557] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:19.557] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:81:0) after 1m0s
[06:58:19.581] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:81:0)
[06:58:19.581] Timer tick, asking Buildbucket for the build status
[06:58:20.707] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:20.707] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:82:0) after 1m0s
[06:59:20.914] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:82:0)
[06:59:20.914] Timer tick, asking Buildbucket for the build status
[06:59:21.202] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:21.202] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:83:0) after 1m0s
[07:00:21.306] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:83:0)
[07:00:21.306] Timer tick, asking Buildbucket for the build status
[07:00:21.448] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:21.448] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:84:0) after 1m0s
[07:01:21.464] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:84:0)
[07:01:21.464] Timer tick, asking Buildbucket for the build status
[07:01:21.709] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:21.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:85:0) after 1m0s
[07:02:21.832] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:85:0)
[07:02:21.832] Timer tick, asking Buildbucket for the build status
[07:02:22.533] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:22.533] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:86:0) after 1m0s
[07:03:22.781] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:86:0)
[07:03:22.781] Timer tick, asking Buildbucket for the build status
[07:03:25.366] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:25.366] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:87:0) after 1m0s
[07:04:25.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:87:0)
[07:04:25.588] Timer tick, asking Buildbucket for the build status
[07:04:25.733] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:25.733] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:88:0) after 1m0s
[07:05:26.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:88:0)
[07:05:26.350] Timer tick, asking Buildbucket for the build status
[07:05:26.623] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:26.623] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:89:0) after 1m0s
[07:06:26.697] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:89:0)
[07:06:26.697] Timer tick, asking Buildbucket for the build status
[07:06:27.822] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:27.822] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:90:0) after 1m0s
[07:07:27.854] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:90:0)
[07:07:27.854] Timer tick, asking Buildbucket for the build status
[07:07:27.974] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:27.974] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:91:0) after 1m0s
[07:08:28.145] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:91:0)
[07:08:28.145] Timer tick, asking Buildbucket for the build status
[07:08:28.339] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:28.339] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:92:0) after 1m0s
[07:09:28.486] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:92:0)
[07:09:28.486] Timer tick, asking Buildbucket for the build status
[07:09:30.688] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:30.688] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:93:0) after 1m0s
[07:10:30.691] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:93:0)
[07:10:30.691] Timer tick, asking Buildbucket for the build status
[07:10:31.127] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:31.127] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:94:0) after 1m0s
[07:11:31.213] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:94:0)
[07:11:31.213] Timer tick, asking Buildbucket for the build status
[07:11:31.665] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:31.665] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:95:0) after 1m0s
[07:12:31.647] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:95:0)
[07:12:31.647] Timer tick, asking Buildbucket for the build status
[07:12:31.938] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:31.938] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:96:0) after 1m0s
[07:13:31.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:96:0)
[07:13:31.963] Timer tick, asking Buildbucket for the build status
[07:13:32.219] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:32.219] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:97:0) after 1m0s
[07:14:32.323] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:97:0)
[07:14:32.323] Timer tick, asking Buildbucket for the build status
[07:14:32.518] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:32.518] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:98:0) after 1m0s
[07:15:32.611] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:98:0)
[07:15:32.611] Timer tick, asking Buildbucket for the build status
[07:15:32.713] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:32.714] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:99:0) after 1m0s
[07:16:32.740] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:99:0)
[07:16:32.740] Timer tick, asking Buildbucket for the build status
[07:16:34.145] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:34.145] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:100:0) after 1m0s
[07:17:34.169] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:100:0)
[07:17:34.169] Timer tick, asking Buildbucket for the build status
[07:17:36.560] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:36.560] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:101:0) after 1m0s
[07:18:36.629] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:101:0)
[07:18:36.629] Timer tick, asking Buildbucket for the build status
[07:18:37.384] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:37.384] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:102:0) after 1m0s
[07:19:37.545] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:102:0)
[07:19:37.545] Timer tick, asking Buildbucket for the build status
[07:19:37.924] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:37.924] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:103:0) after 1m0s
[07:20:37.951] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:103:0)
[07:20:37.951] Timer tick, asking Buildbucket for the build status
[07:20:39.566] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:39.566] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:104:0) after 1m0s
[07:21:39.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:104:0)
[07:21:39.598] Timer tick, asking Buildbucket for the build status
[07:21:42.134] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:42.134] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:105:0) after 1m0s
[07:22:42.540] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:105:0)
[07:22:42.540] Timer tick, asking Buildbucket for the build status
[07:22:42.830] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:42.830] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:106:0) after 1m0s
[07:23:43.170] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:106:0)
[07:23:43.170] Timer tick, asking Buildbucket for the build status
[07:23:45.109] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:45.109] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:107:0) after 1m0s
[07:24:45.266] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:107:0)
[07:24:45.266] Timer tick, asking Buildbucket for the build status
[07:24:45.382] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:45.382] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:108:0) after 1m0s
[07:25:45.476] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:108:0)
[07:25:45.476] Timer tick, asking Buildbucket for the build status
[07:25:45.656] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:45.656] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:109:0) after 1m0s
[07:26:45.936] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:109:0)
[07:26:45.936] Timer tick, asking Buildbucket for the build status
[07:26:46.134] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:46.134] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:110:0) after 1m0s
[07:27:46.231] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:110:0)
[07:27:46.231] Timer tick, asking Buildbucket for the build status
[07:27:46.641] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:46.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:111:0) after 1m0s
[07:28:47.129] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:111:0)
[07:28:47.129] Timer tick, asking Buildbucket for the build status
[07:28:47.546] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:47.546] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:112:0) after 1m0s
[07:29:47.542] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:112:0)
[07:29:47.542] Timer tick, asking Buildbucket for the build status
[07:29:47.952] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:47.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:113:0) after 1m0s
[07:30:47.947] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:113:0)
[07:30:47.947] Timer tick, asking Buildbucket for the build status
[07:30:48.332] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:48.332] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:114:0) after 1m0s
[07:31:48.329] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:114:0)
[07:31:48.329] Timer tick, asking Buildbucket for the build status
[07:31:48.610] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:48.610] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:115:0) after 1m0s
[07:32:48.666] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:115:0)
[07:32:48.666] Timer tick, asking Buildbucket for the build status
[07:32:48.938] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:48.938] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:116:0) after 1m0s
[07:33:48.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:116:0)
[07:33:48.972] Timer tick, asking Buildbucket for the build status
[07:33:50.005] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:50.005] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:117:0) after 1m0s
[07:34:50.038] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:117:0)
[07:34:50.038] Timer tick, asking Buildbucket for the build status
[07:34:51.883] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:51.883] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:118:0) after 1m0s
[07:35:52.061] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:118:0)
[07:35:52.061] Timer tick, asking Buildbucket for the build status
[07:35:52.385] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:52.385] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:119:0) after 1m0s
[07:36:52.567] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:119:0)
[07:36:52.567] Timer tick, asking Buildbucket for the build status
[07:36:53.062] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:53.062] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:120:0) after 1m0s
[07:37:53.150] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:120:0)
[07:37:53.150] Timer tick, asking Buildbucket for the build status
[07:37:54.227] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:54.228] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:121:0) after 1m0s
[07:38:54.287] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:121:0)
[07:38:54.287] Timer tick, asking Buildbucket for the build status
[07:38:54.387] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:54.387] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:122:0) after 1m0s
[07:39:54.409] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:122:0)
[07:39:54.409] Timer tick, asking Buildbucket for the build status
[07:39:55.347] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:55.347] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:123:0) after 1m0s
[07:40:55.376] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:123:0)
[07:40:55.376] Timer tick, asking Buildbucket for the build status
[07:40:55.525] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:55.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:124:0) after 1m0s
[07:41:55.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:124:0)
[07:41:55.592] Timer tick, asking Buildbucket for the build status
[07:41:56.565] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:56.565] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:125:0) after 1m0s
[07:42:56.717] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:125:0)
[07:42:56.717] Timer tick, asking Buildbucket for the build status
[07:42:57.273] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:57.273] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:126:0) after 1m0s
[07:43:57.353] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:126:0)
[07:43:57.353] Timer tick, asking Buildbucket for the build status
[07:43:57.641] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:57.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:127:0) after 1m0s
[07:44:57.687] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:127:0)
[07:44:57.687] Timer tick, asking Buildbucket for the build status
[07:44:58.108] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:58.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:128:0) after 1m0s
[07:45:58.139] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:128:0)
[07:45:58.139] Timer tick, asking Buildbucket for the build status
[07:45:59.514] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:59.514] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:129:0) after 1m0s
[07:46:59.576] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:129:0)
[07:46:59.576] Timer tick, asking Buildbucket for the build status
[07:46:59.851] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:59.851] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:130:0) after 1m0s
[07:47:59.889] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:130:0)
[07:47:59.889] Timer tick, asking Buildbucket for the build status
[07:48:00.468] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:00.468] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:131:0) after 1m0s
[07:49:00.504] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:131:0)
[07:49:00.504] Timer tick, asking Buildbucket for the build status
[07:49:01.129] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:01.129] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:132:0) after 1m0s
[07:50:01.216] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:132:0)
[07:50:01.216] Timer tick, asking Buildbucket for the build status
[07:50:05.651] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:05.651] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:133:0) after 1m0s
[07:51:06.095] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:133:0)
[07:51:06.095] Timer tick, asking Buildbucket for the build status
[07:51:07.351] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:07.351] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:134:0) after 1m0s
[07:52:07.444] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:134:0)
[07:52:07.444] Timer tick, asking Buildbucket for the build status
[07:52:07.577] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:07.577] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:135:0) after 1m0s
[07:53:07.569] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:135:0)
[07:53:07.569] Timer tick, asking Buildbucket for the build status
[07:53:08.569] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:08.569] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:136:0) after 1m0s
[07:54:08.620] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:136:0)
[07:54:08.620] Timer tick, asking Buildbucket for the build status
[07:54:09.524] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:09.524] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:137:0) after 1m0s
[07:55:09.724] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:137:0)
[07:55:09.724] Timer tick, asking Buildbucket for the build status
[07:55:09.927] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:09.927] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:138:0) after 1m0s
[07:56:09.974] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:138:0)
[07:56:09.974] Timer tick, asking Buildbucket for the build status
[07:56:10.765] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:10.766] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:139:0) after 1m0s
[07:57:10.777] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:139:0)
[07:57:10.777] Timer tick, asking Buildbucket for the build status
[07:57:12.306] Build 8909069876679904816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:12.306] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074499939058507472:140:0) after 1m0s
[07:57:43.252] Received PubSub notification, asking Buildbucket for the build status
[07:57:43.745] Build 8909069876679904816: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:57:43.745] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1562054260897654",
"created_by": "project:chromium",
"created_ts": "1562045897749143",
"id": "8909069876679904816",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"8d604f7179b4aca2ab4aa2f61d7df284f4ec32e5\"}}",
"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\": 13997, \"got_angle_revision\": \"5faff91af49995bb63a5d89ff4df7b81a03c128e\", \"got_dawn_revision\": \"521003fd4ea812776065c8f461bcb64751494d8b\", \"got_nacl_revision\": \"c4cf6f0621b94cca4053b19fa01ef329f5663880\", \"got_revision\": \"8d604f7179b4aca2ab4aa2f61d7df284f4ec32e5\", \"got_revision_cp\": \"refs/heads/master@{#673959}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"b6dda94d79dd9d7e5fe7b07b3a9b2b83c655896d\", \"got_v8_revision_cp\": \"refs/heads/7.7.171@{#1}\", \"got_webrtc_revision\": \"ebdf9f8163cf8ac85d626aa30596ca9e8aeacd7f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28438}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"8d604f7179b4aca2ab4aa2f61d7df284f4ec32e5\"}, \"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\": [\"4351-b85b41c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1562045902572918",
"status": "COMPLETED",
"status_changed_ts": "1562054261312524",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13997",
"builder:Windows deterministic",
"buildset:commit/git/8d604f7179b4aca2ab4aa2f61d7df284f4ec32e5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d604f7179b4aca2ab4aa2f61d7df284f4ec32e5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9074499939058507472",
"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/8909069876679904816/+/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:45ca0268d222ff10",
"user_agent:luci-scheduler"
],
"updated_ts": "1562054261318687",
"url": "https://ci.chromium.org/b/8909069876679904816",
"utcnow_ts": "1562054263705415"
}
}
[07:57:43.745] Invocation finished in 2h19m27.648146981s with status SUCCEEDED