[01:48:26.080] New invocation is queued and will start shortly
[01:48:27.222] Starting the invocation (attempt 1)
[01:48:27.264] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:48:27.264] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:48:27.265] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9084027080437814928",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"e38dfa276d9b31d8f4ebc314a457e9ba1653eb25\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9084027080437814928",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/e38dfa276d9b31d8f4ebc314a457e9ba1653eb25",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e38dfa276d9b31d8f4ebc314a457e9ba1653eb25",
"gitiles_ref:refs/heads/master"
]
}
[01:48:28.356] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1552960107421844",
"id": "8918597018359562576",
"lease_expiration_ts": "1553078908069116",
"lease_key": "2041183604",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e38dfa276d9b31d8f4ebc314a457e9ba1653eb25\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552960108193909",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12486",
"builder:Windows deterministic",
"buildset:commit/git/e38dfa276d9b31d8f4ebc314a457e9ba1653eb25",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e38dfa276d9b31d8f4ebc314a457e9ba1653eb25",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084027080437814928",
"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/8918597018359562576/+/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:43ac743d61179510",
"user_agent:luci-scheduler"
],
"updated_ts": "1552960108194017",
"url": "https://ci.chromium.org/b/8918597018359562576",
"utcnow_ts": "1552960108345048"
}
}
[01:48:28.356] Task URL: https://ci.chromium.org/b/8918597018359562576
[01:48:28.356] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:2:0) after 1m0s
[01:48:30.809] Received PubSub notification, asking Buildbucket for the build status
[01:48:30.952] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:28.371] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:2:0)
[01:49:28.371] Timer tick, asking Buildbucket for the build status
[01:49:28.642] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:28.643] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:4:0) after 1m0s
[01:50:28.623] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:4:0)
[01:50:28.623] Timer tick, asking Buildbucket for the build status
[01:50:28.780] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:28.780] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:5:0) after 1m0s
[01:51:28.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:5:0)
[01:51:28.773] Timer tick, asking Buildbucket for the build status
[01:51:28.923] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:28.923] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:6:0) after 1m0s
[01:52:28.918] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:6:0)
[01:52:28.918] Timer tick, asking Buildbucket for the build status
[01:52:29.016] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:29.016] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:7:0) after 1m0s
[01:53:29.008] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:7:0)
[01:53:29.008] Timer tick, asking Buildbucket for the build status
[01:53:29.591] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:29.591] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:8:0) after 1m0s
[01:54:29.605] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:8:0)
[01:54:29.605] Timer tick, asking Buildbucket for the build status
[01:54:29.705] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:29.705] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:9:0) after 1m0s
[01:55:29.722] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:9:0)
[01:55:29.722] Timer tick, asking Buildbucket for the build status
[01:55:29.982] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:29.982] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:10:0) after 1m0s
[01:56:29.960] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:10:0)
[01:56:29.960] Timer tick, asking Buildbucket for the build status
[01:56:30.141] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:30.141] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:11:0) after 1m0s
[01:57:30.164] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:11:0)
[01:57:30.164] Timer tick, asking Buildbucket for the build status
[01:57:30.859] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:30.859] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:12:0) after 1m0s
[01:58:30.853] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:12:0)
[01:58:30.853] Timer tick, asking Buildbucket for the build status
[01:58:31.008] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:31.008] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:13:0) after 1m0s
[01:59:31.059] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:13:0)
[01:59:31.059] Timer tick, asking Buildbucket for the build status
[01:59:31.149] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:31.149] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:14:0) after 1m0s
[02:00:31.160] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:14:0)
[02:00:31.160] Timer tick, asking Buildbucket for the build status
[02:00:31.328] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:31.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:15:0) after 1m0s
[02:01:31.306] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:15:0)
[02:01:31.306] Timer tick, asking Buildbucket for the build status
[02:01:31.438] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:31.438] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:16:0) after 1m0s
[02:02:31.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:16:0)
[02:02:31.417] Timer tick, asking Buildbucket for the build status
[02:02:32.056] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:32.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:17:0) after 1m0s
[02:03:32.035] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:17:0)
[02:03:32.035] Timer tick, asking Buildbucket for the build status
[02:03:32.618] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:32.619] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:18:0) after 1m0s
[02:04:32.647] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:18:0)
[02:04:32.647] Timer tick, asking Buildbucket for the build status
[02:04:32.779] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:32.779] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:19:0) after 1m0s
[02:05:32.797] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:19:0)
[02:05:32.797] Timer tick, asking Buildbucket for the build status
[02:05:32.918] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:32.918] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:20:0) after 1m0s
[02:06:32.904] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:20:0)
[02:06:32.904] Timer tick, asking Buildbucket for the build status
[02:06:33.052] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:33.052] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:21:0) after 1m0s
[02:07:33.062] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:21:0)
[02:07:33.062] Timer tick, asking Buildbucket for the build status
[02:07:33.463] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:33.463] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:22:0) after 1m0s
[02:08:33.438] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:22:0)
[02:08:33.439] Timer tick, asking Buildbucket for the build status
[02:08:33.670] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:33.670] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:23:0) after 1m0s
[02:09:33.689] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:23:0)
[02:09:33.689] Timer tick, asking Buildbucket for the build status
[02:09:34.552] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:34.552] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:24:0) after 1m0s
[02:10:34.571] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:24:0)
[02:10:34.571] Timer tick, asking Buildbucket for the build status
[02:10:34.992] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:34.992] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:25:0) after 1m0s
[02:11:34.981] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:25:0)
[02:11:34.981] Timer tick, asking Buildbucket for the build status
[02:11:35.071] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:35.072] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:26:0) after 1m0s
[02:12:35.067] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:26:0)
[02:12:35.067] Timer tick, asking Buildbucket for the build status
[02:12:35.191] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:35.191] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:27:0) after 1m0s
[02:13:35.164] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:27:0)
[02:13:35.164] Timer tick, asking Buildbucket for the build status
[02:13:35.264] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:35.264] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:28:0) after 1m0s
[02:14:35.284] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:28:0)
[02:14:35.284] Timer tick, asking Buildbucket for the build status
[02:14:35.678] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:35.678] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:29:0) after 1m0s
[02:15:35.711] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:29:0)
[02:15:35.711] Timer tick, asking Buildbucket for the build status
[02:15:35.805] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:35.805] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:30:0) after 1m0s
[02:16:35.811] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:30:0)
[02:16:35.811] Timer tick, asking Buildbucket for the build status
[02:16:36.192] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:36.192] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:31:0) after 1m0s
[02:17:36.210] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:31:0)
[02:17:36.210] Timer tick, asking Buildbucket for the build status
[02:17:36.883] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:36.883] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:32:0) after 1m0s
[02:18:36.901] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:32:0)
[02:18:36.901] Timer tick, asking Buildbucket for the build status
[02:18:37.029] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:37.029] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:33:0) after 1m0s
[02:19:37.061] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:33:0)
[02:19:37.061] Timer tick, asking Buildbucket for the build status
[02:19:37.307] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:37.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:34:0) after 1m0s
[02:20:37.280] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:34:0)
[02:20:37.280] Timer tick, asking Buildbucket for the build status
[02:20:37.414] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:37.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:35:0) after 1m0s
[02:21:37.431] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:35:0)
[02:21:37.431] Timer tick, asking Buildbucket for the build status
[02:21:37.661] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:37.661] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:36:0) after 1m0s
[02:22:37.634] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:36:0)
[02:22:37.634] Timer tick, asking Buildbucket for the build status
[02:22:37.794] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:37.794] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:37:0) after 1m0s
[02:23:37.785] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:37:0)
[02:23:37.785] Timer tick, asking Buildbucket for the build status
[02:23:37.918] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:37.918] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:38:0) after 1m0s
[02:24:37.933] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:38:0)
[02:24:37.933] Timer tick, asking Buildbucket for the build status
[02:24:38.642] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:38.642] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:39:0) after 1m0s
[02:25:38.659] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:39:0)
[02:25:38.659] Timer tick, asking Buildbucket for the build status
[02:25:38.842] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:38.842] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:40:0) after 1m0s
[02:26:38.844] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:40:0)
[02:26:38.844] Timer tick, asking Buildbucket for the build status
[02:26:39.100] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:39.100] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:41:0) after 1m0s
[02:27:39.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:41:0)
[02:27:39.117] Timer tick, asking Buildbucket for the build status
[02:27:39.219] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:39.219] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:42:0) after 1m0s
[02:28:39.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:42:0)
[02:28:39.194] Timer tick, asking Buildbucket for the build status
[02:28:39.512] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:39.512] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:43:0) after 1m0s
[02:29:39.498] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:43:0)
[02:29:39.498] Timer tick, asking Buildbucket for the build status
[02:29:39.689] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:39.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:44:0) after 1m0s
[02:30:39.711] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:44:0)
[02:30:39.711] Timer tick, asking Buildbucket for the build status
[02:30:40.410] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:40.410] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:45:0) after 1m0s
[02:31:40.388] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:45:0)
[02:31:40.388] Timer tick, asking Buildbucket for the build status
[02:31:40.683] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:40.683] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:46:0) after 1m0s
[02:32:40.686] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:46:0)
[02:32:40.686] Timer tick, asking Buildbucket for the build status
[02:32:41.323] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:41.323] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:47:0) after 1m0s
[02:33:41.332] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:47:0)
[02:33:41.332] Timer tick, asking Buildbucket for the build status
[02:33:41.438] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:41.438] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:48:0) after 1m0s
[02:34:41.409] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:48:0)
[02:34:41.409] Timer tick, asking Buildbucket for the build status
[02:34:41.545] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:41.545] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:49:0) after 1m0s
[02:35:41.539] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:49:0)
[02:35:41.539] Timer tick, asking Buildbucket for the build status
[02:35:42.600] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:42.600] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:50:0) after 1m0s
[02:36:42.618] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:50:0)
[02:36:42.618] Timer tick, asking Buildbucket for the build status
[02:36:42.763] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:42.763] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:51:0) after 1m0s
[02:37:42.752] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:51:0)
[02:37:42.752] Timer tick, asking Buildbucket for the build status
[02:37:42.855] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:42.855] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:52:0) after 1m0s
[02:38:42.873] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:52:0)
[02:38:42.873] Timer tick, asking Buildbucket for the build status
[02:38:43.070] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:43.070] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:53:0) after 1m0s
[02:39:43.120] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:53:0)
[02:39:43.120] Timer tick, asking Buildbucket for the build status
[02:39:43.505] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:43.505] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:54:0) after 1m0s
[02:40:43.503] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:54:0)
[02:40:43.503] Timer tick, asking Buildbucket for the build status
[02:40:43.810] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:43.810] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:55:0) after 1m0s
[02:41:43.810] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:55:0)
[02:41:43.810] Timer tick, asking Buildbucket for the build status
[02:41:43.982] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:43.982] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:56:0) after 1m0s
[02:42:43.971] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:56:0)
[02:42:43.971] Timer tick, asking Buildbucket for the build status
[02:42:44.185] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:44.185] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:57:0) after 1m0s
[02:43:44.190] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:57:0)
[02:43:44.190] Timer tick, asking Buildbucket for the build status
[02:43:44.615] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:44.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:58:0) after 1m0s
[02:44:44.660] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:58:0)
[02:44:44.660] Timer tick, asking Buildbucket for the build status
[02:44:45.058] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:45.058] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:59:0) after 1m0s
[02:45:45.099] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:59:0)
[02:45:45.099] Timer tick, asking Buildbucket for the build status
[02:45:45.583] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:45.583] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:60:0) after 1m0s
[02:46:45.593] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:60:0)
[02:46:45.593] Timer tick, asking Buildbucket for the build status
[02:46:45.765] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:45.765] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:61:0) after 1m0s
[02:47:45.770] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:61:0)
[02:47:45.770] Timer tick, asking Buildbucket for the build status
[02:47:46.100] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:46.100] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:62:0) after 1m0s
[02:48:46.068] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:62:0)
[02:48:46.068] Timer tick, asking Buildbucket for the build status
[02:48:47.076] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:47.076] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:63:0) after 1m0s
[02:49:47.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:63:0)
[02:49:47.183] Timer tick, asking Buildbucket for the build status
[02:49:47.297] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:47.297] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:64:0) after 1m0s
[02:50:47.317] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:64:0)
[02:50:47.317] Timer tick, asking Buildbucket for the build status
[02:50:47.442] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:47.442] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:65:0) after 1m0s
[02:51:47.455] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:65:0)
[02:51:47.456] Timer tick, asking Buildbucket for the build status
[02:51:47.584] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:47.584] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:66:0) after 1m0s
[02:52:47.594] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:66:0)
[02:52:47.594] Timer tick, asking Buildbucket for the build status
[02:52:47.691] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:47.691] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:67:0) after 1m0s
[02:53:47.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:67:0)
[02:53:47.705] Timer tick, asking Buildbucket for the build status
[02:53:47.811] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:47.811] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:68:0) after 1m0s
[02:54:47.832] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:68:0)
[02:54:47.832] Timer tick, asking Buildbucket for the build status
[02:54:47.931] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:47.931] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:69:0) after 1m0s
[02:55:47.943] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:69:0)
[02:55:47.943] Timer tick, asking Buildbucket for the build status
[02:55:48.131] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:48.131] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:70:0) after 1m0s
[02:56:48.143] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:70:0)
[02:56:48.143] Timer tick, asking Buildbucket for the build status
[02:56:48.495] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:48.495] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:71:0) after 1m0s
[02:57:48.510] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:71:0)
[02:57:48.511] Timer tick, asking Buildbucket for the build status
[02:57:48.901] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:48.902] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:72:0) after 1m0s
[02:58:48.923] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:72:0)
[02:58:48.923] Timer tick, asking Buildbucket for the build status
[02:58:49.033] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:49.033] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:73:0) after 1m0s
[02:59:49.109] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:73:0)
[02:59:49.109] Timer tick, asking Buildbucket for the build status
[02:59:49.289] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:49.289] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:74:0) after 1m0s
[03:00:49.272] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:74:0)
[03:00:49.272] Timer tick, asking Buildbucket for the build status
[03:00:49.394] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:49.394] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:75:0) after 1m0s
[03:01:49.432] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:75:0)
[03:01:49.432] Timer tick, asking Buildbucket for the build status
[03:01:49.564] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:49.564] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:76:0) after 1m0s
[03:02:49.549] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:76:0)
[03:02:49.549] Timer tick, asking Buildbucket for the build status
[03:02:50.096] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:50.096] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:77:0) after 1m0s
[03:03:50.127] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:77:0)
[03:03:50.127] Timer tick, asking Buildbucket for the build status
[03:03:50.560] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:50.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:78:0) after 1m0s
[03:04:50.584] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:78:0)
[03:04:50.584] Timer tick, asking Buildbucket for the build status
[03:04:51.194] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:51.194] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:79:0) after 1m0s
[03:05:51.212] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:79:0)
[03:05:51.212] Timer tick, asking Buildbucket for the build status
[03:05:51.296] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:51.296] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:80:0) after 1m0s
[03:06:51.316] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:80:0)
[03:06:51.316] Timer tick, asking Buildbucket for the build status
[03:06:51.474] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:51.474] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:81:0) after 1m0s
[03:07:51.476] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:81:0)
[03:07:51.476] Timer tick, asking Buildbucket for the build status
[03:07:51.585] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:51.585] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:82:0) after 1m0s
[03:08:51.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:82:0)
[03:08:51.602] Timer tick, asking Buildbucket for the build status
[03:08:51.708] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:51.708] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:83:0) after 1m0s
[03:09:51.743] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:83:0)
[03:09:51.743] Timer tick, asking Buildbucket for the build status
[03:09:51.876] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:51.876] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:84:0) after 1m0s
[03:10:51.897] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:84:0)
[03:10:51.897] Timer tick, asking Buildbucket for the build status
[03:10:52.205] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:52.205] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:85:0) after 1m0s
[03:11:52.186] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:85:0)
[03:11:52.187] Timer tick, asking Buildbucket for the build status
[03:11:53.044] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:53.044] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:86:0) after 1m0s
[03:12:53.023] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:86:0)
[03:12:53.023] Timer tick, asking Buildbucket for the build status
[03:12:53.288] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:53.288] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:87:0) after 1m0s
[03:13:53.294] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:87:0)
[03:13:53.294] Timer tick, asking Buildbucket for the build status
[03:13:53.430] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:53.430] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:88:0) after 1m0s
[03:14:53.437] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:88:0)
[03:14:53.437] Timer tick, asking Buildbucket for the build status
[03:14:53.886] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:53.886] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:89:0) after 1m0s
[03:15:53.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:89:0)
[03:15:53.910] Timer tick, asking Buildbucket for the build status
[03:15:54.000] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:54.000] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:90:0) after 1m0s
[03:16:54.002] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:90:0)
[03:16:54.002] Timer tick, asking Buildbucket for the build status
[03:16:54.577] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:54.577] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:91:0) after 1m0s
[03:17:54.617] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:91:0)
[03:17:54.617] Timer tick, asking Buildbucket for the build status
[03:17:55.391] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:55.391] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:92:0) after 1m0s
[03:18:55.440] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:92:0)
[03:18:55.440] Timer tick, asking Buildbucket for the build status
[03:18:55.865] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:55.865] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:93:0) after 1m0s
[03:19:55.883] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:93:0)
[03:19:55.883] Timer tick, asking Buildbucket for the build status
[03:19:56.136] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:56.136] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:94:0) after 1m0s
[03:20:56.214] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:94:0)
[03:20:56.214] Timer tick, asking Buildbucket for the build status
[03:20:56.702] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:56.702] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:95:0) after 1m0s
[03:21:56.701] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:95:0)
[03:21:56.701] Timer tick, asking Buildbucket for the build status
[03:21:57.603] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:57.603] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:96:0) after 1m0s
[03:22:57.635] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:96:0)
[03:22:57.635] Timer tick, asking Buildbucket for the build status
[03:22:58.164] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:58.164] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:97:0) after 1m0s
[03:23:58.204] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:97:0)
[03:23:58.204] Timer tick, asking Buildbucket for the build status
[03:23:58.492] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:58.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:98:0) after 1m0s
[03:24:58.483] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:98:0)
[03:24:58.483] Timer tick, asking Buildbucket for the build status
[03:24:58.905] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:58.905] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:99:0) after 1m0s
[03:25:58.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:99:0)
[03:25:58.911] Timer tick, asking Buildbucket for the build status
[03:25:59.279] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:59.279] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:100:0) after 1m0s
[03:26:59.267] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:100:0)
[03:26:59.267] Timer tick, asking Buildbucket for the build status
[03:26:59.355] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:59.355] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:101:0) after 1m0s
[03:27:59.341] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:101:0)
[03:27:59.341] Timer tick, asking Buildbucket for the build status
[03:27:59.668] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:59.668] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:102:0) after 1m0s
[03:28:59.662] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:102:0)
[03:28:59.662] Timer tick, asking Buildbucket for the build status
[03:29:00.001] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:00.001] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:103:0) after 1m0s
[03:30:00.039] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:103:0)
[03:30:00.039] Timer tick, asking Buildbucket for the build status
[03:30:00.377] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:00.377] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:104:0) after 1m0s
[03:31:00.399] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:104:0)
[03:31:00.399] Timer tick, asking Buildbucket for the build status
[03:31:01.326] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:01.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:105:0) after 1m0s
[03:32:01.354] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:105:0)
[03:32:01.354] Timer tick, asking Buildbucket for the build status
[03:32:01.505] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:01.505] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:106:0) after 1m0s
[03:33:01.530] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:106:0)
[03:33:01.530] Timer tick, asking Buildbucket for the build status
[03:33:01.667] Build 8918597018359562576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:01.667] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084027080437814928:107:0) after 1m0s
[03:33:25.815] Received PubSub notification, asking Buildbucket for the build status
[03:33:26.769] Build 8918597018359562576: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:33:26.769] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1552966405225901",
"created_by": "project:chromium",
"created_ts": "1552960107421844",
"id": "8918597018359562576",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e38dfa276d9b31d8f4ebc314a457e9ba1653eb25\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"windows-deterministic-0-v3qq\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4201-b04f7f8\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"got_angle_revision\": \"38e282570676f3e434482f8a02ea539b286fd1fa\", \"got_nacl_revision\": \"1ed9878b8eb68c1e0d0268754dafcf83e747fa8f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27160}\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"7dbc0eb2efa4708da5e3e44d2a15bfdee4ceb474\", \"got_revision\": \"e38dfa276d9b31d8f4ebc314a457e9ba1653eb25\", \"got_swarming_client_revision\": \"7a61cf37d6a0163f0ec02d495289a1d038e62457\", \"recipe\": \"swarming/deterministic_build\", \"mastername\": \"chromium.win\", \"buildnumber\": 12486, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"got_v8_revision\": \"36585c1b6b1fd69d093706a6c4ad9775506a7885\", \"got_revision_cp\": \"refs/heads/master@{#641798}\", \"got_dawn_revision\": \"fef613365afe5234c1bae9fe33c3d30698d27078\", \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"e38dfa276d9b31d8f4ebc314a457e9ba1653eb25\", \"got_v8_revision_cp\": \"refs/heads/7.5.62@{#1}\", \"bot_id\": \"windows-deterministic-0-v3qq\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1552960110368054",
"status": "COMPLETED",
"status_changed_ts": "1552966405623417",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12486",
"builder:Windows deterministic",
"buildset:commit/git/e38dfa276d9b31d8f4ebc314a457e9ba1653eb25",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e38dfa276d9b31d8f4ebc314a457e9ba1653eb25",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084027080437814928",
"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/8918597018359562576/+/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:43ac743d61179510",
"user_agent:luci-scheduler"
],
"updated_ts": "1552966405627016",
"url": "https://ci.chromium.org/b/8918597018359562576",
"utcnow_ts": "1552966406759867"
}
}
[03:33:26.769] Invocation finished in 1h45m0.714361471s with status SUCCEEDED