[20:30:54.644] New invocation is queued and will start shortly
[20:30:55.780] Starting the invocation (attempt 1)
[20:30:55.817] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:30:55.817] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:30:55.817] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9069007960886076464",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"7acee7ba237db6175a22c18183556c1858b5fcb8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9069007960886076464",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/7acee7ba237db6175a22c18183556c1858b5fcb8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7acee7ba237db6175a22c18183556c1858b5fcb8",
"gitiles_ref:refs/heads/master"
]
}
[20:30:57.055] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1567283455960210",
"id": "8903577898841437648",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"7acee7ba237db6175a22c18183556c1858b5fcb8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1567283456591589",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14589",
"builder:Windows deterministic",
"buildset:commit/git/7acee7ba237db6175a22c18183556c1858b5fcb8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7acee7ba237db6175a22c18183556c1858b5fcb8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9069007960886076464",
"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/8903577898841437648/+/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": "1567283456591727",
"url": "https://ci.chromium.org/b/8903577898841437648",
"utcnow_ts": "1567283457048328"
}
}
[20:30:57.055] Task URL: https://ci.chromium.org/b/8903577898841437648
[20:30:57.055] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:2:0) after 1m0s
[20:31:00.137] Received PubSub notification, asking Buildbucket for the build status
[20:31:02.729] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:57.248] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:2:0)
[20:31:57.248] Timer tick, asking Buildbucket for the build status
[20:31:57.435] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:57.435] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:4:0) after 1m0s
[20:32:57.805] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:4:0)
[20:32:57.805] Timer tick, asking Buildbucket for the build status
[20:32:57.954] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:57.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:5:0) after 1m0s
[20:33:57.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:5:0)
[20:33:57.984] Timer tick, asking Buildbucket for the build status
[20:33:58.150] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:58.150] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:6:0) after 1m0s
[20:34:58.178] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:6:0)
[20:34:58.178] Timer tick, asking Buildbucket for the build status
[20:34:58.429] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:58.429] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:7:0) after 1m0s
[20:35:58.439] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:7:0)
[20:35:58.439] Timer tick, asking Buildbucket for the build status
[20:35:58.576] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:58.576] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:8:0) after 1m0s
[20:36:58.688] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:8:0)
[20:36:58.688] Timer tick, asking Buildbucket for the build status
[20:36:58.900] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:58.900] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:9:0) after 1m0s
[20:37:59.053] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:9:0)
[20:37:59.053] Timer tick, asking Buildbucket for the build status
[20:37:59.200] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:59.200] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:10:0) after 1m0s
[20:38:59.224] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:10:0)
[20:38:59.224] Timer tick, asking Buildbucket for the build status
[20:38:59.688] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:59.688] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:11:0) after 1m0s
[20:39:59.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:11:0)
[20:39:59.708] Timer tick, asking Buildbucket for the build status
[20:39:59.866] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:59.866] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:12:0) after 1m0s
[20:40:59.962] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:12:0)
[20:40:59.962] Timer tick, asking Buildbucket for the build status
[20:41:00.179] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:00.179] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:13:0) after 1m0s
[20:42:00.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:13:0)
[20:42:00.202] Timer tick, asking Buildbucket for the build status
[20:42:00.544] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:00.544] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:14:0) after 1m0s
[20:43:00.564] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:14:0)
[20:43:00.564] Timer tick, asking Buildbucket for the build status
[20:43:01.183] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:01.183] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:15:0) after 1m0s
[20:44:01.208] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:15:0)
[20:44:01.208] Timer tick, asking Buildbucket for the build status
[20:44:01.501] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:01.501] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:16:0) after 1m0s
[20:45:01.625] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:16:0)
[20:45:01.625] Timer tick, asking Buildbucket for the build status
[20:45:01.813] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:01.813] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:17:0) after 1m0s
[20:46:01.836] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:17:0)
[20:46:01.836] Timer tick, asking Buildbucket for the build status
[20:46:02.330] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:02.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:18:0) after 1m0s
[20:47:02.525] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:18:0)
[20:47:02.525] Timer tick, asking Buildbucket for the build status
[20:47:02.722] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:02.722] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:19:0) after 1m0s
[20:48:07.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:19:0)
[20:48:07.137] Timer tick, asking Buildbucket for the build status
[20:48:07.397] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:07.397] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:20:0) after 1m0s
[20:49:07.461] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:20:0)
[20:49:07.461] Timer tick, asking Buildbucket for the build status
[20:49:08.330] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:08.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:21:0) after 1m0s
[20:50:08.379] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:21:0)
[20:50:08.379] Timer tick, asking Buildbucket for the build status
[20:50:08.545] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:08.545] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:22:0) after 1m0s
[20:51:08.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:22:0)
[20:51:08.591] Timer tick, asking Buildbucket for the build status
[20:51:08.858] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:08.858] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:23:0) after 1m0s
[20:52:08.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:23:0)
[20:52:08.927] Timer tick, asking Buildbucket for the build status
[20:52:09.118] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:09.118] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:24:0) after 1m0s
[20:53:09.281] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:24:0)
[20:53:09.281] Timer tick, asking Buildbucket for the build status
[20:53:09.428] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:09.428] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:25:0) after 1m0s
[20:54:09.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:25:0)
[20:54:09.592] Timer tick, asking Buildbucket for the build status
[20:54:09.919] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:09.919] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:26:0) after 1m0s
[20:55:09.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:26:0)
[20:55:09.990] Timer tick, asking Buildbucket for the build status
[20:55:10.622] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:10.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:27:0) after 1m0s
[20:56:10.698] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:27:0)
[20:56:10.698] Timer tick, asking Buildbucket for the build status
[20:56:10.850] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:10.850] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:28:0) after 1m0s
[20:57:10.862] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:28:0)
[20:57:10.862] Timer tick, asking Buildbucket for the build status
[20:57:11.030] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:11.030] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:29:0) after 1m0s
[20:58:11.054] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:29:0)
[20:58:11.054] Timer tick, asking Buildbucket for the build status
[20:58:12.747] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:12.747] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:30:0) after 1m0s
[20:59:12.935] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:30:0)
[20:59:12.935] Timer tick, asking Buildbucket for the build status
[20:59:13.408] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:13.408] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:31:0) after 1m0s
[21:00:13.477] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:31:0)
[21:00:13.477] Timer tick, asking Buildbucket for the build status
[21:00:13.708] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:13.708] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:32:0) after 1m0s
[21:01:13.845] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:32:0)
[21:01:13.845] Timer tick, asking Buildbucket for the build status
[21:01:14.334] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:14.334] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:33:0) after 1m0s
[21:02:14.460] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:33:0)
[21:02:14.460] Timer tick, asking Buildbucket for the build status
[21:02:14.681] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:14.681] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:34:0) after 1m0s
[21:03:14.718] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:34:0)
[21:03:14.718] Timer tick, asking Buildbucket for the build status
[21:03:15.733] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:15.733] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:35:0) after 1m0s
[21:04:15.838] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:35:0)
[21:04:15.838] Timer tick, asking Buildbucket for the build status
[21:04:16.049] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:16.049] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:36:0) after 1m0s
[21:05:16.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:36:0)
[21:05:16.117] Timer tick, asking Buildbucket for the build status
[21:05:16.272] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:16.272] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:37:0) after 1m0s
[21:06:16.347] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:37:0)
[21:06:16.347] Timer tick, asking Buildbucket for the build status
[21:06:16.557] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:16.557] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:38:0) after 1m0s
[21:07:16.641] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:38:0)
[21:07:16.641] Timer tick, asking Buildbucket for the build status
[21:07:17.360] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:17.360] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:39:0) after 1m0s
[21:08:17.408] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:39:0)
[21:08:17.408] Timer tick, asking Buildbucket for the build status
[21:08:18.058] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:18.058] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:40:0) after 1m0s
[21:09:18.182] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:40:0)
[21:09:18.182] Timer tick, asking Buildbucket for the build status
[21:09:18.636] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:18.636] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:41:0) after 1m0s
[21:10:18.752] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:41:0)
[21:10:18.752] Timer tick, asking Buildbucket for the build status
[21:10:19.260] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:19.260] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:42:0) after 1m0s
[21:11:19.451] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:42:0)
[21:11:19.451] Timer tick, asking Buildbucket for the build status
[21:11:20.195] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:20.195] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:43:0) after 1m0s
[21:12:20.221] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:43:0)
[21:12:20.221] Timer tick, asking Buildbucket for the build status
[21:12:20.860] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:20.860] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:44:0) after 1m0s
[21:13:20.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:44:0)
[21:13:20.972] Timer tick, asking Buildbucket for the build status
[21:13:21.193] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:21.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:45:0) after 1m0s
[21:14:21.265] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:45:0)
[21:14:21.265] Timer tick, asking Buildbucket for the build status
[21:14:22.302] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:22.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:46:0) after 1m0s
[21:15:22.623] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:46:0)
[21:15:22.623] Timer tick, asking Buildbucket for the build status
[21:15:22.953] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:22.953] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:47:0) after 1m0s
[21:16:23.104] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:47:0)
[21:16:23.104] Timer tick, asking Buildbucket for the build status
[21:16:23.324] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:23.324] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:48:0) after 1m0s
[21:17:23.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:48:0)
[21:17:23.350] Timer tick, asking Buildbucket for the build status
[21:17:24.044] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:24.044] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:49:0) after 1m0s
[21:18:24.193] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:49:0)
[21:18:24.193] Timer tick, asking Buildbucket for the build status
[21:18:24.755] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:24.755] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:50:0) after 1m0s
[21:19:24.776] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:50:0)
[21:19:24.776] Timer tick, asking Buildbucket for the build status
[21:19:25.296] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:25.296] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:51:0) after 1m0s
[21:20:25.404] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:51:0)
[21:20:25.404] Timer tick, asking Buildbucket for the build status
[21:20:25.566] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:25.566] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:52:0) after 1m0s
[21:21:25.695] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:52:0)
[21:21:25.695] Timer tick, asking Buildbucket for the build status
[21:21:28.570] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:28.570] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:53:0) after 1m0s
[21:22:28.857] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:53:0)
[21:22:28.857] Timer tick, asking Buildbucket for the build status
[21:22:29.045] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:29.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:54:0) after 1m0s
[21:23:29.333] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:54:0)
[21:23:29.333] Timer tick, asking Buildbucket for the build status
[21:23:30.049] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:30.049] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:55:0) after 1m0s
[21:24:30.261] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:55:0)
[21:24:30.261] Timer tick, asking Buildbucket for the build status
[21:24:31.015] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:31.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:56:0) after 1m0s
[21:25:31.040] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:56:0)
[21:25:31.040] Timer tick, asking Buildbucket for the build status
[21:25:31.931] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:31.931] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:57:0) after 1m0s
[21:26:31.983] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:57:0)
[21:26:31.983] Timer tick, asking Buildbucket for the build status
[21:26:32.170] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:32.170] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:58:0) after 1m0s
[21:27:32.363] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:58:0)
[21:27:32.363] Timer tick, asking Buildbucket for the build status
[21:27:32.607] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:32.607] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:59:0) after 1m0s
[21:28:32.755] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:59:0)
[21:28:32.755] Timer tick, asking Buildbucket for the build status
[21:28:32.959] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:32.959] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:60:0) after 1m0s
[21:29:33.125] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:60:0)
[21:29:33.125] Timer tick, asking Buildbucket for the build status
[21:29:33.345] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:33.345] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:61:0) after 1m0s
[21:30:33.491] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:61:0)
[21:30:33.491] Timer tick, asking Buildbucket for the build status
[21:30:34.201] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:34.201] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:62:0) after 1m0s
[21:31:34.427] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:62:0)
[21:31:34.427] Timer tick, asking Buildbucket for the build status
[21:31:34.732] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:34.732] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:63:0) after 1m0s
[21:32:34.849] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:63:0)
[21:32:34.849] Timer tick, asking Buildbucket for the build status
[21:32:35.637] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:35.637] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:64:0) after 1m0s
[21:33:41.355] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:64:0)
[21:33:41.355] Timer tick, asking Buildbucket for the build status
[21:33:41.846] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:41.846] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:65:0) after 1m0s
[21:34:41.936] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:65:0)
[21:34:41.936] Timer tick, asking Buildbucket for the build status
[21:34:42.975] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:42.976] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:66:0) after 1m0s
[21:35:43.166] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:66:0)
[21:35:43.166] Timer tick, asking Buildbucket for the build status
[21:35:43.330] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:43.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:67:0) after 1m0s
[21:36:43.419] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:67:0)
[21:36:43.419] Timer tick, asking Buildbucket for the build status
[21:36:47.630] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:47.630] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:68:0) after 1m0s
[21:37:47.648] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:68:0)
[21:37:47.648] Timer tick, asking Buildbucket for the build status
[21:37:48.215] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:48.215] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:69:0) after 1m0s
[21:38:48.309] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:69:0)
[21:38:48.309] Timer tick, asking Buildbucket for the build status
[21:38:48.465] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:48.465] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:70:0) after 1m0s
[21:39:48.538] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:70:0)
[21:39:48.538] Timer tick, asking Buildbucket for the build status
[21:39:49.183] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:49.183] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:71:0) after 1m0s
[21:40:49.347] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:71:0)
[21:40:49.347] Timer tick, asking Buildbucket for the build status
[21:40:49.572] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:49.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:72:0) after 1m0s
[21:41:49.678] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:72:0)
[21:41:49.678] Timer tick, asking Buildbucket for the build status
[21:41:49.868] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:49.868] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:73:0) after 1m0s
[21:42:49.970] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:73:0)
[21:42:49.970] Timer tick, asking Buildbucket for the build status
[21:42:50.557] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:50.557] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:74:0) after 1m0s
[21:43:50.543] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:74:0)
[21:43:50.543] Timer tick, asking Buildbucket for the build status
[21:43:50.707] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:50.707] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:75:0) after 1m0s
[21:44:50.754] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:75:0)
[21:44:50.754] Timer tick, asking Buildbucket for the build status
[21:44:50.918] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:50.918] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:76:0) after 1m0s
[21:45:50.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:76:0)
[21:45:50.972] Timer tick, asking Buildbucket for the build status
[21:45:51.155] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:51.155] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:77:0) after 1m0s
[21:46:51.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:77:0)
[21:46:51.183] Timer tick, asking Buildbucket for the build status
[21:46:52.158] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:52.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:78:0) after 1m0s
[21:47:52.214] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:78:0)
[21:47:52.214] Timer tick, asking Buildbucket for the build status
[21:47:52.404] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:52.404] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:79:0) after 1m0s
[21:48:52.544] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:79:0)
[21:48:52.544] Timer tick, asking Buildbucket for the build status
[21:48:52.743] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:52.744] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:80:0) after 1m0s
[21:49:52.868] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:80:0)
[21:49:52.868] Timer tick, asking Buildbucket for the build status
[21:49:53.917] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:53.917] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:81:0) after 1m0s
[21:50:53.942] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:81:0)
[21:50:53.942] Timer tick, asking Buildbucket for the build status
[21:50:54.154] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:54.154] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:82:0) after 1m0s
[21:51:54.178] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:82:0)
[21:51:54.178] Timer tick, asking Buildbucket for the build status
[21:51:54.301] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:54.301] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:83:0) after 1m0s
[21:52:54.336] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:83:0)
[21:52:54.336] Timer tick, asking Buildbucket for the build status
[21:52:54.618] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:54.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:84:0) after 1m0s
[21:53:54.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:84:0)
[21:53:54.705] Timer tick, asking Buildbucket for the build status
[21:53:54.886] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:54.886] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:85:0) after 1m0s
[21:54:54.958] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:85:0)
[21:54:54.958] Timer tick, asking Buildbucket for the build status
[21:54:55.847] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:55.848] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:86:0) after 1m0s
[21:55:55.896] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:86:0)
[21:55:55.896] Timer tick, asking Buildbucket for the build status
[21:55:56.062] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:56.062] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:87:0) after 1m0s
[21:56:56.092] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:87:0)
[21:56:56.092] Timer tick, asking Buildbucket for the build status
[21:56:56.334] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:56.334] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:88:0) after 1m0s
[21:57:56.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:88:0)
[21:57:56.417] Timer tick, asking Buildbucket for the build status
[21:57:57.134] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:57.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:89:0) after 1m0s
[21:58:57.178] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:89:0)
[21:58:57.178] Timer tick, asking Buildbucket for the build status
[21:58:57.394] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:57.394] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:90:0) after 1m0s
[21:59:57.494] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:90:0)
[21:59:57.494] Timer tick, asking Buildbucket for the build status
[21:59:57.653] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:57.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:91:0) after 1m0s
[22:00:57.678] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:91:0)
[22:00:57.678] Timer tick, asking Buildbucket for the build status
[22:00:57.844] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:57.844] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:92:0) after 1m0s
[22:01:57.877] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:92:0)
[22:01:57.877] Timer tick, asking Buildbucket for the build status
[22:01:58.021] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:58.021] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:93:0) after 1m0s
[22:02:58.076] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:93:0)
[22:02:58.076] Timer tick, asking Buildbucket for the build status
[22:02:58.553] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:58.553] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:94:0) after 1m0s
[22:03:58.639] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:94:0)
[22:03:58.639] Timer tick, asking Buildbucket for the build status
[22:03:58.986] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:58.986] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:95:0) after 1m0s
[22:04:59.041] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:95:0)
[22:04:59.041] Timer tick, asking Buildbucket for the build status
[22:04:59.204] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:59.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:96:0) after 1m0s
[22:05:59.227] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:96:0)
[22:05:59.227] Timer tick, asking Buildbucket for the build status
[22:05:59.489] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:59.489] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:97:0) after 1m0s
[22:06:59.524] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:97:0)
[22:06:59.524] Timer tick, asking Buildbucket for the build status
[22:07:00.790] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:00.790] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:98:0) after 1m0s
[22:08:00.843] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:98:0)
[22:08:00.843] Timer tick, asking Buildbucket for the build status
[22:08:01.458] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:01.458] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:99:0) after 1m0s
[22:09:01.533] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:99:0)
[22:09:01.533] Timer tick, asking Buildbucket for the build status
[22:09:02.209] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:02.209] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:100:0) after 1m0s
[22:10:02.298] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:100:0)
[22:10:02.298] Timer tick, asking Buildbucket for the build status
[22:10:02.498] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:02.498] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:101:0) after 1m0s
[22:11:02.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:101:0)
[22:11:02.592] Timer tick, asking Buildbucket for the build status
[22:11:04.037] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:04.037] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:102:0) after 1m0s
[22:12:04.088] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:102:0)
[22:12:04.088] Timer tick, asking Buildbucket for the build status
[22:12:06.294] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:06.294] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:103:0) after 1m0s
[22:13:06.422] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:103:0)
[22:13:06.422] Timer tick, asking Buildbucket for the build status
[22:13:07.026] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:07.026] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:104:0) after 1m0s
[22:14:07.777] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:104:0)
[22:14:07.777] Timer tick, asking Buildbucket for the build status
[22:14:08.030] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:08.030] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:105:0) after 1m0s
[22:15:08.471] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:105:0)
[22:15:08.471] Timer tick, asking Buildbucket for the build status
[22:15:08.764] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:08.764] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:106:0) after 1m0s
[22:16:08.831] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:106:0)
[22:16:08.831] Timer tick, asking Buildbucket for the build status
[22:16:09.749] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:09.749] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:107:0) after 1m0s
[22:17:09.837] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:107:0)
[22:17:09.837] Timer tick, asking Buildbucket for the build status
[22:17:09.959] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:09.959] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:108:0) after 1m0s
[22:18:10.164] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:108:0)
[22:18:10.164] Timer tick, asking Buildbucket for the build status
[22:18:10.373] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:10.373] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:109:0) after 1m0s
[22:19:10.499] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:109:0)
[22:19:10.499] Timer tick, asking Buildbucket for the build status
[22:19:13.271] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:13.271] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:110:0) after 1m0s
[22:20:13.448] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:110:0)
[22:20:13.448] Timer tick, asking Buildbucket for the build status
[22:20:14.512] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:14.512] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:111:0) after 1m0s
[22:21:14.625] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:111:0)
[22:21:14.625] Timer tick, asking Buildbucket for the build status
[22:21:14.767] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:14.767] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:112:0) after 1m0s
[22:22:20.813] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:112:0)
[22:22:20.813] Timer tick, asking Buildbucket for the build status
[22:22:21.635] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:21.635] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:113:0) after 1m0s
[22:23:21.631] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:113:0)
[22:23:21.631] Timer tick, asking Buildbucket for the build status
[22:23:23.035] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:23.035] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:114:0) after 1m0s
[22:24:23.181] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:114:0)
[22:24:23.181] Timer tick, asking Buildbucket for the build status
[22:24:24.129] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:24.129] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:115:0) after 1m0s
[22:25:24.161] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:115:0)
[22:25:24.161] Timer tick, asking Buildbucket for the build status
[22:25:24.304] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:24.304] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:116:0) after 1m0s
[22:26:24.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:116:0)
[22:26:24.344] Timer tick, asking Buildbucket for the build status
[22:26:24.495] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:24.495] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:117:0) after 1m0s
[22:27:24.525] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:117:0)
[22:27:24.525] Timer tick, asking Buildbucket for the build status
[22:27:24.904] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:24.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:118:0) after 1m0s
[22:28:24.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:118:0)
[22:28:24.929] Timer tick, asking Buildbucket for the build status
[22:28:26.361] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:26.361] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:119:0) after 1m0s
[22:29:26.450] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:119:0)
[22:29:26.450] Timer tick, asking Buildbucket for the build status
[22:29:27.432] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:27.432] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:120:0) after 1m0s
[22:30:27.469] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:120:0)
[22:30:27.469] Timer tick, asking Buildbucket for the build status
[22:30:27.995] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:27.995] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:121:0) after 1m0s
[22:31:28.132] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:121:0)
[22:31:28.135] Timer tick, asking Buildbucket for the build status
[22:31:28.355] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:28.355] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:122:0) after 1m0s
[22:32:28.453] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:122:0)
[22:32:28.453] Timer tick, asking Buildbucket for the build status
[22:32:29.792] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:29.792] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:123:0) after 1m0s
[22:33:29.819] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:123:0)
[22:33:29.819] Timer tick, asking Buildbucket for the build status
[22:33:29.952] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:29.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:124:0) after 1m0s
[22:34:29.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:124:0)
[22:34:29.984] Timer tick, asking Buildbucket for the build status
[22:34:30.187] Build 8903577898841437648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:30.187] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069007960886076464:125:0) after 1m0s
[22:35:11.932] Received PubSub notification, asking Buildbucket for the build status
[22:35:12.318] Build 8903577898841437648: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:35:12.319] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1567290911025862",
"created_by": "project:chromium",
"created_ts": "1567283455960210",
"id": "8903577898841437648",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"7acee7ba237db6175a22c18183556c1858b5fcb8\"}}",
"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-bzrg\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 14589, \"got_angle_revision\": \"c3f57231124b74dd069550dabf5196f988fc8666\", \"got_dawn_revision\": \"824424fa351be2b2eccd78b2f31de1d5d1ad4183\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"7acee7ba237db6175a22c18183556c1858b5fcb8\", \"got_revision_cp\": \"refs/heads/master@{#692363}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"aa3fccf48bfd34411bd3c232a5b952a5b859df82\", \"got_v8_revision_cp\": \"refs/heads/7.8.242@{#1}\", \"got_webrtc_revision\": \"ce6a0c8fb3f3a19caa797d5913b7e7c8ecf3ca9b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29029}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"7acee7ba237db6175a22c18183556c1858b5fcb8\"}, \"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-bzrg\"], \"image\": [\"chrome-win10-1703-19072400-fedda89f1f1\"], \"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\": [\"4397-8786906\"], \"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": "1567283459276253",
"status": "COMPLETED",
"status_changed_ts": "1567290911324348",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14589",
"builder:Windows deterministic",
"buildset:commit/git/7acee7ba237db6175a22c18183556c1858b5fcb8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7acee7ba237db6175a22c18183556c1858b5fcb8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9069007960886076464",
"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/8903577898841437648/+/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:47023134bb35ce10",
"user_agent:luci-scheduler"
],
"updated_ts": "1567290911329389",
"url": "https://ci.chromium.org/b/8903577898841437648",
"utcnow_ts": "1567290912305973"
}
}
[22:35:12.319] Invocation finished in 2h4m17.691378556s with status SUCCEEDED