[13:41:11.306] New invocation is queued and will start shortly
[13:41:12.926] Starting the invocation (attempt 1)
[13:41:13.092] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:41:13.092] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:41:13.092] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9061061205412591952",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6c138bfbfcd1fbae7935ff9bac9c87f90ff25139\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9061061205412591952",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/6c138bfbfcd1fbae7935ff9bac9c87f90ff25139",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6c138bfbfcd1fbae7935ff9bac9c87f90ff25139",
"gitiles_ref:refs/heads/master"
]
}
[13:41:15.310] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1574862073510616",
"id": "8895631142365236400",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6c138bfbfcd1fbae7935ff9bac9c87f90ff25139\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1574862073948643",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/15313",
"builder:Windows deterministic",
"buildset:commit/git/6c138bfbfcd1fbae7935ff9bac9c87f90ff25139",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6c138bfbfcd1fbae7935ff9bac9c87f90ff25139",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061061205412591952",
"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/8895631142365236400/+/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": "1574862073948877",
"url": "https://ci.chromium.org/b/8895631142365236400",
"utcnow_ts": "1574862075292083"
}
}
[13:41:15.310] Task URL: https://ci.chromium.org/b/8895631142365236400
[13:41:15.310] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:2:0) after 1m0s
[13:41:26.287] Received PubSub notification, asking Buildbucket for the build status
[13:41:26.420] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:15.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:2:0)
[13:42:15.344] Timer tick, asking Buildbucket for the build status
[13:42:16.275] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:16.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:4:0) after 1m0s
[13:43:16.338] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:4:0)
[13:43:16.338] Timer tick, asking Buildbucket for the build status
[13:43:17.898] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:17.898] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:5:0) after 1m0s
[13:44:17.919] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:5:0)
[13:44:17.919] Timer tick, asking Buildbucket for the build status
[13:44:18.377] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:18.377] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:6:0) after 1m0s
[13:45:18.517] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:6:0)
[13:45:18.517] Timer tick, asking Buildbucket for the build status
[13:45:18.684] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:18.684] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:7:0) after 1m0s
[13:46:18.738] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:7:0)
[13:46:18.738] Timer tick, asking Buildbucket for the build status
[13:46:20.954] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:20.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:8:0) after 1m0s
[13:47:20.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:8:0)
[13:47:20.988] Timer tick, asking Buildbucket for the build status
[13:47:21.212] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:21.212] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:9:0) after 1m0s
[13:48:21.260] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:9:0)
[13:48:21.260] Timer tick, asking Buildbucket for the build status
[13:48:21.463] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:21.463] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:10:0) after 1m0s
[13:49:21.506] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:10:0)
[13:49:21.506] Timer tick, asking Buildbucket for the build status
[13:49:21.963] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:21.963] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:11:0) after 1m0s
[13:50:21.996] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:11:0)
[13:50:21.996] Timer tick, asking Buildbucket for the build status
[13:50:22.194] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:22.194] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:12:0) after 1m0s
[13:51:22.176] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:12:0)
[13:51:22.176] Timer tick, asking Buildbucket for the build status
[13:51:22.375] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:22.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:13:0) after 1m0s
[13:52:22.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:13:0)
[13:52:22.417] Timer tick, asking Buildbucket for the build status
[13:52:22.603] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:22.603] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:14:0) after 1m0s
[13:53:22.634] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:14:0)
[13:53:22.634] Timer tick, asking Buildbucket for the build status
[13:53:23.495] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:23.495] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:15:0) after 1m0s
[13:54:23.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:15:0)
[13:54:23.514] Timer tick, asking Buildbucket for the build status
[13:54:23.967] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:23.967] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:16:0) after 1m0s
[13:55:24.159] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:16:0)
[13:55:24.159] Timer tick, asking Buildbucket for the build status
[13:55:25.110] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:25.110] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:17:0) after 1m0s
[13:56:25.147] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:17:0)
[13:56:25.147] Timer tick, asking Buildbucket for the build status
[13:56:25.351] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:25.351] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:18:0) after 1m0s
[13:57:25.379] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:18:0)
[13:57:25.379] Timer tick, asking Buildbucket for the build status
[13:57:25.648] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:25.648] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:19:0) after 1m0s
[13:58:25.667] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:19:0)
[13:58:25.667] Timer tick, asking Buildbucket for the build status
[13:58:25.816] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:25.816] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:20:0) after 1m0s
[13:59:25.885] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:20:0)
[13:59:25.885] Timer tick, asking Buildbucket for the build status
[13:59:26.152] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:26.152] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:21:0) after 1m0s
[14:00:26.250] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:21:0)
[14:00:26.250] Timer tick, asking Buildbucket for the build status
[14:00:26.421] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:26.421] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:22:0) after 1m0s
[14:01:26.582] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:22:0)
[14:01:26.582] Timer tick, asking Buildbucket for the build status
[14:01:26.724] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:26.724] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:23:0) after 1m0s
[14:02:26.766] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:23:0)
[14:02:26.767] Timer tick, asking Buildbucket for the build status
[14:02:27.234] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:27.234] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:24:0) after 1m0s
[14:03:50.914] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:24:0)
[14:03:50.914] Timer tick, asking Buildbucket for the build status
[14:03:51.262] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:51.262] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:25:0) after 1m0s
[14:04:51.460] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:25:0)
[14:04:51.460] Timer tick, asking Buildbucket for the build status
[14:04:52.081] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:52.081] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:26:0) after 1m0s
[14:05:52.160] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:26:0)
[14:05:52.160] Timer tick, asking Buildbucket for the build status
[14:05:52.684] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:52.684] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:27:0) after 1m0s
[14:06:52.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:27:0)
[14:06:52.773] Timer tick, asking Buildbucket for the build status
[14:06:52.935] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:52.935] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:28:0) after 1m0s
[14:07:52.992] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:28:0)
[14:07:52.992] Timer tick, asking Buildbucket for the build status
[14:07:53.820] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:53.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:29:0) after 1m0s
[14:08:53.888] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:29:0)
[14:08:53.888] Timer tick, asking Buildbucket for the build status
[14:08:54.272] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:54.272] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:30:0) after 1m0s
[14:09:54.366] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:30:0)
[14:09:54.366] Timer tick, asking Buildbucket for the build status
[14:09:54.896] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:54.896] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:31:0) after 1m0s
[14:10:55.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:31:0)
[14:10:55.084] Timer tick, asking Buildbucket for the build status
[14:10:56.052] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:56.052] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:32:0) after 1m0s
[14:11:56.134] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:32:0)
[14:11:56.134] Timer tick, asking Buildbucket for the build status
[14:11:57.144] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:57.144] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:33:0) after 1m0s
[14:12:57.174] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:33:0)
[14:12:57.174] Timer tick, asking Buildbucket for the build status
[14:12:58.309] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:58.309] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:34:0) after 1m0s
[14:13:58.363] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:34:0)
[14:13:58.363] Timer tick, asking Buildbucket for the build status
[14:13:58.524] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:58.524] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:35:0) after 1m0s
[14:14:58.949] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:35:0)
[14:14:58.949] Timer tick, asking Buildbucket for the build status
[14:14:59.109] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:59.109] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:36:0) after 1m0s
[14:15:59.318] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:36:0)
[14:15:59.318] Timer tick, asking Buildbucket for the build status
[14:15:59.688] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:59.688] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:37:0) after 1m0s
[14:16:59.858] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:37:0)
[14:16:59.858] Timer tick, asking Buildbucket for the build status
[14:17:00.096] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:00.096] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:38:0) after 1m0s
[14:18:00.125] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:38:0)
[14:18:00.125] Timer tick, asking Buildbucket for the build status
[14:18:00.383] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:00.383] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:39:0) after 1m0s
[14:19:00.486] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:39:0)
[14:19:00.486] Timer tick, asking Buildbucket for the build status
[14:19:00.953] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:00.953] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:40:0) after 1m0s
[14:20:01.085] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:40:0)
[14:20:01.085] Timer tick, asking Buildbucket for the build status
[14:20:01.257] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:01.257] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:41:0) after 1m0s
[14:21:01.677] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:41:0)
[14:21:01.677] Timer tick, asking Buildbucket for the build status
[14:21:02.378] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:02.378] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:42:0) after 1m0s
[14:22:02.534] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:42:0)
[14:22:02.534] Timer tick, asking Buildbucket for the build status
[14:22:02.713] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:02.713] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:43:0) after 1m0s
[14:23:02.761] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:43:0)
[14:23:02.761] Timer tick, asking Buildbucket for the build status
[14:23:03.361] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:03.361] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:44:0) after 1m0s
[14:24:03.451] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:44:0)
[14:24:03.451] Timer tick, asking Buildbucket for the build status
[14:24:03.789] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:03.790] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:45:0) after 1m0s
[14:25:03.818] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:45:0)
[14:25:03.818] Timer tick, asking Buildbucket for the build status
[14:25:03.955] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:03.955] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:46:0) after 1m0s
[14:26:03.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:46:0)
[14:26:03.978] Timer tick, asking Buildbucket for the build status
[14:26:04.577] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:04.577] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:47:0) after 1m0s
[14:27:04.690] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:47:0)
[14:27:04.690] Timer tick, asking Buildbucket for the build status
[14:27:05.328] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:05.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:48:0) after 1m0s
[14:28:05.387] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:48:0)
[14:28:05.387] Timer tick, asking Buildbucket for the build status
[14:28:06.354] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:06.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:49:0) after 1m0s
[14:29:06.395] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:49:0)
[14:29:06.395] Timer tick, asking Buildbucket for the build status
[14:29:06.598] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:06.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:50:0) after 1m0s
[14:30:06.674] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:50:0)
[14:30:06.674] Timer tick, asking Buildbucket for the build status
[14:30:06.910] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:06.910] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:51:0) after 1m0s
[14:31:07.043] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:51:0)
[14:31:07.043] Timer tick, asking Buildbucket for the build status
[14:31:07.357] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:07.357] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:52:0) after 1m0s
[14:32:07.681] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:52:0)
[14:32:07.681] Timer tick, asking Buildbucket for the build status
[14:32:08.413] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:08.413] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:53:0) after 1m0s
[14:33:08.458] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:53:0)
[14:33:08.458] Timer tick, asking Buildbucket for the build status
[14:33:08.996] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:08.996] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:54:0) after 1m0s
[14:34:09.078] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:54:0)
[14:34:09.078] Timer tick, asking Buildbucket for the build status
[14:34:09.525] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:09.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:55:0) after 1m0s
[14:35:09.583] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:55:0)
[14:35:09.583] Timer tick, asking Buildbucket for the build status
[14:35:09.710] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:09.710] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:56:0) after 1m0s
[14:36:09.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:56:0)
[14:36:09.963] Timer tick, asking Buildbucket for the build status
[14:36:10.224] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:10.224] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:57:0) after 1m0s
[14:37:10.463] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:57:0)
[14:37:10.463] Timer tick, asking Buildbucket for the build status
[14:37:11.330] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:11.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:58:0) after 1m0s
[14:38:11.405] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:58:0)
[14:38:11.405] Timer tick, asking Buildbucket for the build status
[14:38:11.590] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:11.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:59:0) after 1m0s
[14:39:12.119] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:59:0)
[14:39:12.119] Timer tick, asking Buildbucket for the build status
[14:39:12.288] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:12.288] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:60:0) after 1m0s
[14:40:12.594] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:60:0)
[14:40:12.594] Timer tick, asking Buildbucket for the build status
[14:40:13.023] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:13.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:61:0) after 1m0s
[14:41:13.255] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:61:0)
[14:41:13.255] Timer tick, asking Buildbucket for the build status
[14:41:13.417] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:13.417] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:62:0) after 1m0s
[14:42:13.523] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:62:0)
[14:42:13.523] Timer tick, asking Buildbucket for the build status
[14:42:14.362] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:14.362] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:63:0) after 1m0s
[14:43:14.489] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:63:0)
[14:43:14.489] Timer tick, asking Buildbucket for the build status
[14:43:15.150] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:15.150] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:64:0) after 1m0s
[14:44:15.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:64:0)
[14:44:15.194] Timer tick, asking Buildbucket for the build status
[14:44:15.475] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:15.475] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:65:0) after 1m0s
[14:45:15.550] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:65:0)
[14:45:15.550] Timer tick, asking Buildbucket for the build status
[14:45:16.245] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:16.245] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:66:0) after 1m0s
[14:46:16.376] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:66:0)
[14:46:16.376] Timer tick, asking Buildbucket for the build status
[14:46:16.639] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:16.639] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:67:0) after 1m0s
[14:47:16.682] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:67:0)
[14:47:16.682] Timer tick, asking Buildbucket for the build status
[14:47:16.975] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:16.975] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:68:0) after 1m0s
[14:48:17.073] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:68:0)
[14:48:17.073] Timer tick, asking Buildbucket for the build status
[14:48:17.286] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:17.286] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:69:0) after 1m0s
[14:49:17.433] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:69:0)
[14:49:17.433] Timer tick, asking Buildbucket for the build status
[14:49:17.687] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:17.687] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:70:0) after 1m0s
[14:50:17.761] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:70:0)
[14:50:17.761] Timer tick, asking Buildbucket for the build status
[14:50:18.319] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:18.319] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:71:0) after 1m0s
[14:51:18.392] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:71:0)
[14:51:18.392] Timer tick, asking Buildbucket for the build status
[14:51:20.083] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:20.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:72:0) after 1m0s
[14:52:20.122] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:72:0)
[14:52:20.122] Timer tick, asking Buildbucket for the build status
[14:52:20.700] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:20.700] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:73:0) after 1m0s
[14:53:20.830] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:73:0)
[14:53:20.830] Timer tick, asking Buildbucket for the build status
[14:53:20.988] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:20.988] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:74:0) after 1m0s
[14:54:21.213] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:74:0)
[14:54:21.213] Timer tick, asking Buildbucket for the build status
[14:54:21.366] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:21.366] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:75:0) after 1m0s
[14:55:21.381] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:75:0)
[14:55:21.381] Timer tick, asking Buildbucket for the build status
[14:55:22.048] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:22.048] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:76:0) after 1m0s
[14:56:22.121] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:76:0)
[14:56:22.121] Timer tick, asking Buildbucket for the build status
[14:56:22.277] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:22.277] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:77:0) after 1m0s
[14:57:22.443] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:77:0)
[14:57:22.443] Timer tick, asking Buildbucket for the build status
[14:57:22.573] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:22.573] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:78:0) after 1m0s
[14:58:22.767] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:78:0)
[14:58:22.767] Timer tick, asking Buildbucket for the build status
[14:58:23.928] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:23.928] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:79:0) after 1m0s
[14:59:24.100] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:79:0)
[14:59:24.100] Timer tick, asking Buildbucket for the build status
[14:59:24.313] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:24.313] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:80:0) after 1m0s
[15:00:24.500] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:80:0)
[15:00:24.500] Timer tick, asking Buildbucket for the build status
[15:00:25.023] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:25.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:81:0) after 1m0s
[15:01:25.112] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:81:0)
[15:01:25.112] Timer tick, asking Buildbucket for the build status
[15:01:25.229] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:25.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:82:0) after 1m0s
[15:02:25.487] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:82:0)
[15:02:25.487] Timer tick, asking Buildbucket for the build status
[15:02:25.995] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:25.995] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:83:0) after 1m0s
[15:03:26.113] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:83:0)
[15:03:26.113] Timer tick, asking Buildbucket for the build status
[15:03:26.295] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:26.295] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:84:0) after 1m0s
[15:04:26.326] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:84:0)
[15:04:26.326] Timer tick, asking Buildbucket for the build status
[15:04:26.503] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:26.503] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:85:0) after 1m0s
[15:05:26.693] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:85:0)
[15:05:26.693] Timer tick, asking Buildbucket for the build status
[15:05:27.093] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:27.093] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:86:0) after 1m0s
[15:06:27.191] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:86:0)
[15:06:27.191] Timer tick, asking Buildbucket for the build status
[15:06:27.423] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:27.423] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:87:0) after 1m0s
[15:07:27.472] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:87:0)
[15:07:27.472] Timer tick, asking Buildbucket for the build status
[15:07:27.848] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:27.848] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:88:0) after 1m0s
[15:08:27.866] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:88:0)
[15:08:27.866] Timer tick, asking Buildbucket for the build status
[15:08:28.329] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:28.329] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:89:0) after 1m0s
[15:09:28.402] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:89:0)
[15:09:28.402] Timer tick, asking Buildbucket for the build status
[15:09:28.572] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:28.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:90:0) after 1m0s
[15:10:28.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:90:0)
[15:10:28.773] Timer tick, asking Buildbucket for the build status
[15:10:28.954] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:28.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:91:0) after 1m0s
[15:11:29.028] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:91:0)
[15:11:29.028] Timer tick, asking Buildbucket for the build status
[15:11:30.402] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:30.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:92:0) after 1m0s
[15:12:30.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:92:0)
[15:12:30.519] Timer tick, asking Buildbucket for the build status
[15:12:30.681] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:30.681] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:93:0) after 1m0s
[15:13:30.762] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:93:0)
[15:13:30.762] Timer tick, asking Buildbucket for the build status
[15:13:31.137] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:31.137] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:94:0) after 1m0s
[15:14:31.386] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:94:0)
[15:14:31.386] Timer tick, asking Buildbucket for the build status
[15:14:31.743] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:31.743] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:95:0) after 1m0s
[15:15:31.877] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:95:0)
[15:15:31.877] Timer tick, asking Buildbucket for the build status
[15:15:32.167] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:32.167] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:96:0) after 1m0s
[15:16:32.373] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:96:0)
[15:16:32.373] Timer tick, asking Buildbucket for the build status
[15:16:32.535] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:32.535] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:97:0) after 1m0s
[15:17:32.621] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:97:0)
[15:17:32.621] Timer tick, asking Buildbucket for the build status
[15:17:33.403] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:33.403] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:98:0) after 1m0s
[15:18:34.253] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:98:0)
[15:18:34.253] Timer tick, asking Buildbucket for the build status
[15:18:34.405] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:34.405] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:99:0) after 1m0s
[15:19:34.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:99:0)
[15:19:34.763] Timer tick, asking Buildbucket for the build status
[15:19:39.149] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:39.149] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:100:0) after 1m0s
[15:20:39.213] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:100:0)
[15:20:39.213] Timer tick, asking Buildbucket for the build status
[15:20:39.651] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:39.651] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:101:0) after 1m0s
[15:21:39.736] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:101:0)
[15:21:39.736] Timer tick, asking Buildbucket for the build status
[15:21:39.928] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:39.928] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:102:0) after 1m0s
[15:22:40.028] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:102:0)
[15:22:40.028] Timer tick, asking Buildbucket for the build status
[15:22:40.496] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:40.496] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:103:0) after 1m0s
[15:23:40.722] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:103:0)
[15:23:40.722] Timer tick, asking Buildbucket for the build status
[15:23:41.277] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:41.277] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:104:0) after 1m0s
[15:24:41.489] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:104:0)
[15:24:41.489] Timer tick, asking Buildbucket for the build status
[15:24:41.584] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:41.584] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:105:0) after 1m0s
[15:25:41.679] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:105:0)
[15:25:41.679] Timer tick, asking Buildbucket for the build status
[15:25:41.876] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:41.876] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:106:0) after 1m0s
[15:26:42.027] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:106:0)
[15:26:42.027] Timer tick, asking Buildbucket for the build status
[15:26:42.640] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:42.640] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:107:0) after 1m0s
[15:27:42.950] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:107:0)
[15:27:42.950] Timer tick, asking Buildbucket for the build status
[15:27:43.101] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:43.101] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:108:0) after 1m0s
[15:28:43.093] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:108:0)
[15:28:43.093] Timer tick, asking Buildbucket for the build status
[15:28:43.850] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:43.850] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:109:0) after 1m0s
[15:29:43.999] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:109:0)
[15:29:43.999] Timer tick, asking Buildbucket for the build status
[15:29:44.444] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:44.444] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:110:0) after 1m0s
[15:30:44.502] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:110:0)
[15:30:44.502] Timer tick, asking Buildbucket for the build status
[15:30:44.678] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:44.678] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:111:0) after 1m0s
[15:31:44.951] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:111:0)
[15:31:44.951] Timer tick, asking Buildbucket for the build status
[15:31:45.174] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:45.174] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:112:0) after 1m0s
[15:32:45.389] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:112:0)
[15:32:45.393] Timer tick, asking Buildbucket for the build status
[15:32:45.820] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:45.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:113:0) after 1m0s
[15:33:45.976] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:113:0)
[15:33:45.976] Timer tick, asking Buildbucket for the build status
[15:33:46.116] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:46.116] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:114:0) after 1m0s
[15:34:46.223] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:114:0)
[15:34:46.223] Timer tick, asking Buildbucket for the build status
[15:34:46.634] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:46.634] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:115:0) after 1m0s
[15:35:47.251] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:115:0)
[15:35:47.251] Timer tick, asking Buildbucket for the build status
[15:35:47.746] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:47.746] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:116:0) after 1m0s
[15:36:48.136] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:116:0)
[15:36:48.137] Timer tick, asking Buildbucket for the build status
[15:36:48.690] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:48.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:117:0) after 1m0s
[15:37:48.734] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:117:0)
[15:37:48.734] Timer tick, asking Buildbucket for the build status
[15:37:50.631] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:50.631] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:118:0) after 1m0s
[15:38:50.747] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:118:0)
[15:38:50.747] Timer tick, asking Buildbucket for the build status
[15:38:50.926] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:50.926] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:119:0) after 1m0s
[15:39:51.086] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:119:0)
[15:39:51.086] Timer tick, asking Buildbucket for the build status
[15:39:51.258] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:51.258] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:120:0) after 1m0s
[15:40:51.358] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:120:0)
[15:40:51.358] Timer tick, asking Buildbucket for the build status
[15:40:51.954] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:51.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:121:0) after 1m0s
[15:41:52.191] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:121:0)
[15:41:52.191] Timer tick, asking Buildbucket for the build status
[15:41:52.727] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:52.727] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:122:0) after 1m0s
[15:42:52.789] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:122:0)
[15:42:52.789] Timer tick, asking Buildbucket for the build status
[15:42:52.997] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:52.997] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:123:0) after 1m0s
[15:43:53.138] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:123:0)
[15:43:53.138] Timer tick, asking Buildbucket for the build status
[15:43:53.443] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:53.443] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:124:0) after 1m0s
[15:44:53.585] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:124:0)
[15:44:53.585] Timer tick, asking Buildbucket for the build status
[15:44:53.764] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:53.764] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:125:0) after 1m0s
[15:45:53.891] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:125:0)
[15:45:53.891] Timer tick, asking Buildbucket for the build status
[15:45:54.316] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:54.316] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:126:0) after 1m0s
[15:46:54.355] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:126:0)
[15:46:54.355] Timer tick, asking Buildbucket for the build status
[15:46:57.193] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:57.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:127:0) after 1m0s
[15:47:57.221] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:127:0)
[15:47:57.221] Timer tick, asking Buildbucket for the build status
[15:47:57.695] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:57.695] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:128:0) after 1m0s
[15:48:57.733] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:128:0)
[15:48:57.733] Timer tick, asking Buildbucket for the build status
[15:48:58.381] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:58.381] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:129:0) after 1m0s
[15:49:58.457] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:129:0)
[15:49:58.457] Timer tick, asking Buildbucket for the build status
[15:49:58.895] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:58.895] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:130:0) after 1m0s
[15:50:58.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:130:0)
[15:50:58.972] Timer tick, asking Buildbucket for the build status
[15:50:59.157] Build 8895631142365236400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:59.157] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9061061205412591952:131:0) after 1m0s
[15:51:18.063] Received PubSub notification, asking Buildbucket for the build status
[15:51:18.315] Build 8895631142365236400: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:51:18.315] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1574869876333525",
"created_by": "project:chromium",
"created_ts": "1574862073510616",
"id": "8895631142365236400",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6c138bfbfcd1fbae7935ff9bac9c87f90ff25139\"}}",
"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-n2xb\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 15313, \"got_angle_revision\": \"781bb85e96986a927f17a537e3973d3acf02dba8\", \"got_dawn_revision\": \"74cebd68792d5788144f50511b3eb35134f9803e\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"6c138bfbfcd1fbae7935ff9bac9c87f90ff25139\", \"got_revision_cp\": \"refs/heads/master@{#719568}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"663dcefa22ea5eec1b108feebaf40a683fb104ff\", \"got_v8_revision\": \"d21964d6bbf89204807b21ab3b5bc2fc53c71127\", \"got_v8_revision_cp\": \"refs/heads/8.0.384@{#1}\", \"got_webrtc_revision\": \"e14cb994086dd9be2625ce59bd75c1ec00d9b72b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29926}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6c138bfbfcd1fbae7935ff9bac9c87f90ff25139\"}, \"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-n2xb\"], \"image\": [\"chrome-win10-1703-19112100-3085293b610\"], \"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\": [\"4662-e230705\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1574862085021876",
"status": "COMPLETED",
"status_changed_ts": "1574869877072548",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/15313",
"builder:Windows deterministic",
"buildset:commit/git/6c138bfbfcd1fbae7935ff9bac9c87f90ff25139",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6c138bfbfcd1fbae7935ff9bac9c87f90ff25139",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061061205412591952",
"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/8895631142365236400/+/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:48c5e9d447ed8810",
"user_agent:luci-scheduler"
],
"updated_ts": "1574869877089190",
"url": "https://ci.chromium.org/b/8895631142365236400",
"utcnow_ts": "1574869878292531"
}
}
[15:51:18.315] Invocation finished in 2h10m7.094358882s with status SUCCEEDED