[07:55:16.510] New invocation is queued and will start shortly
[07:55:17.679] Starting the invocation (attempt 1)
[07:55:17.733] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:55:17.733] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:55:17.733] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9073404155786902224",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"0ed788e3a7dc8b7c18c7462c68bc2c0574b495cb\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9073404155786902224",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/0ed788e3a7dc8b7c18c7462c68bc2c0574b495cb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0ed788e3a7dc8b7c18c7462c68bc2c0574b495cb",
"gitiles_ref:refs/heads/master"
]
}
[07:55:20.491] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1563090918661448",
"id": "8907974092832693568",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0ed788e3a7dc8b7c18c7462c68bc2c0574b495cb\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1563090920191876",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14127",
"builder:Windows deterministic",
"buildset:commit/git/0ed788e3a7dc8b7c18c7462c68bc2c0574b495cb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0ed788e3a7dc8b7c18c7462c68bc2c0574b495cb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9073404155786902224",
"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/8907974092832693568/+/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": "1563090920191978",
"url": "https://ci.chromium.org/b/8907974092832693568",
"utcnow_ts": "1563090920463253"
}
}
[07:55:20.491] Task URL: https://ci.chromium.org/b/8907974092832693568
[07:55:20.491] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:2:0) after 1m0s
[07:56:20.513] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:2:0)
[07:56:20.513] Timer tick, asking Buildbucket for the build status
[07:56:21.034] Build 8907974092832693568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:56:21.034] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:3:0) after 1m0s
[07:56:24.168] Received PubSub notification, asking Buildbucket for the build status
[07:56:24.760] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:21.057] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:3:0)
[07:57:21.057] Timer tick, asking Buildbucket for the build status
[07:57:21.956] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:21.956] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:5:0) after 1m0s
[07:58:21.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:5:0)
[07:58:21.988] Timer tick, asking Buildbucket for the build status
[07:58:24.431] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:24.431] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:6:0) after 1m0s
[07:59:24.464] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:6:0)
[07:59:24.464] Timer tick, asking Buildbucket for the build status
[07:59:24.924] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:24.924] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:7:0) after 1m0s
[08:00:24.976] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:7:0)
[08:00:24.976] Timer tick, asking Buildbucket for the build status
[08:00:25.186] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:25.186] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:8:0) after 1m0s
[08:01:25.339] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:8:0)
[08:01:25.339] Timer tick, asking Buildbucket for the build status
[08:01:25.499] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:25.499] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:9:0) after 1m0s
[08:02:25.521] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:9:0)
[08:02:25.521] Timer tick, asking Buildbucket for the build status
[08:02:28.459] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:28.459] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:10:0) after 1m0s
[08:03:28.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:10:0)
[08:03:28.495] Timer tick, asking Buildbucket for the build status
[08:03:29.023] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:29.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:11:0) after 1m0s
[08:04:29.053] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:11:0)
[08:04:29.053] Timer tick, asking Buildbucket for the build status
[08:04:29.251] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:29.251] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:12:0) after 1m0s
[08:05:29.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:12:0)
[08:05:29.277] Timer tick, asking Buildbucket for the build status
[08:05:31.526] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:31.526] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:13:0) after 1m0s
[08:06:31.660] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:13:0)
[08:06:31.660] Timer tick, asking Buildbucket for the build status
[08:06:31.866] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:31.866] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:14:0) after 1m0s
[08:07:31.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:14:0)
[08:07:31.989] Timer tick, asking Buildbucket for the build status
[08:07:35.041] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:35.041] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:15:0) after 1m0s
[08:08:35.068] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:15:0)
[08:08:35.068] Timer tick, asking Buildbucket for the build status
[08:08:35.540] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:35.540] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:16:0) after 1m0s
[08:09:35.666] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:16:0)
[08:09:35.666] Timer tick, asking Buildbucket for the build status
[08:09:37.090] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:37.090] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:17:0) after 1m0s
[08:10:37.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:17:0)
[08:10:37.154] Timer tick, asking Buildbucket for the build status
[08:10:38.754] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:38.754] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:18:0) after 1m0s
[08:11:38.873] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:18:0)
[08:11:38.873] Timer tick, asking Buildbucket for the build status
[08:11:39.965] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:39.965] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:19:0) after 1m0s
[08:12:40.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:19:0)
[08:12:40.083] Timer tick, asking Buildbucket for the build status
[08:12:40.247] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:40.247] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:20:0) after 1m0s
[08:13:40.242] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:20:0)
[08:13:40.243] Timer tick, asking Buildbucket for the build status
[08:13:44.386] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:44.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:21:0) after 1m0s
[08:14:44.457] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:21:0)
[08:14:44.457] Timer tick, asking Buildbucket for the build status
[08:14:47.075] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:47.075] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:22:0) after 1m0s
[08:15:47.109] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:22:0)
[08:15:47.109] Timer tick, asking Buildbucket for the build status
[08:15:47.326] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:47.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:23:0) after 1m0s
[08:16:47.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:23:0)
[08:16:47.350] Timer tick, asking Buildbucket for the build status
[08:16:48.235] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:48.235] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:24:0) after 1m0s
[08:17:48.355] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:24:0)
[08:17:48.355] Timer tick, asking Buildbucket for the build status
[08:17:50.235] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:50.235] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:25:0) after 1m0s
[08:18:50.353] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:25:0)
[08:18:50.353] Timer tick, asking Buildbucket for the build status
[08:18:50.658] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:50.658] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:26:0) after 1m0s
[08:19:50.682] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:26:0)
[08:19:50.682] Timer tick, asking Buildbucket for the build status
[08:19:52.244] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:52.244] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:27:0) after 1m0s
[08:20:52.270] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:27:0)
[08:20:52.270] Timer tick, asking Buildbucket for the build status
[08:20:52.492] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:52.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:28:0) after 1m0s
[08:21:52.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:28:0)
[08:21:52.514] Timer tick, asking Buildbucket for the build status
[08:21:54.523] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:54.523] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:29:0) after 1m0s
[08:22:54.522] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:29:0)
[08:22:54.522] Timer tick, asking Buildbucket for the build status
[08:22:54.715] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:54.715] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:30:0) after 1m0s
[08:23:54.779] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:30:0)
[08:23:54.779] Timer tick, asking Buildbucket for the build status
[08:23:55.018] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:55.018] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:31:0) after 1m0s
[08:24:55.043] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:31:0)
[08:24:55.043] Timer tick, asking Buildbucket for the build status
[08:24:57.006] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:57.006] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:32:0) after 1m0s
[08:25:57.219] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:32:0)
[08:25:57.219] Timer tick, asking Buildbucket for the build status
[08:25:57.400] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:57.400] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:33:0) after 1m0s
[08:26:57.425] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:33:0)
[08:26:57.425] Timer tick, asking Buildbucket for the build status
[08:26:58.912] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:58.912] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:34:0) after 1m0s
[08:27:58.998] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:34:0)
[08:27:58.998] Timer tick, asking Buildbucket for the build status
[08:27:59.264] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:59.264] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:35:0) after 1m0s
[08:28:59.368] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:35:0)
[08:28:59.368] Timer tick, asking Buildbucket for the build status
[08:29:00.164] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:00.164] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:36:0) after 1m0s
[08:30:00.216] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:36:0)
[08:30:00.216] Timer tick, asking Buildbucket for the build status
[08:30:00.501] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:00.501] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:37:0) after 1m0s
[08:31:00.685] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:37:0)
[08:31:00.685] Timer tick, asking Buildbucket for the build status
[08:31:02.725] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:02.725] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:38:0) after 1m0s
[08:32:02.759] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:38:0)
[08:32:02.759] Timer tick, asking Buildbucket for the build status
[08:32:04.314] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:04.314] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:39:0) after 1m0s
[08:33:04.348] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:39:0)
[08:33:04.348] Timer tick, asking Buildbucket for the build status
[08:33:05.175] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:05.175] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:40:0) after 1m0s
[08:34:05.199] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:40:0)
[08:34:05.199] Timer tick, asking Buildbucket for the build status
[08:34:06.588] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:06.588] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:41:0) after 1m0s
[08:35:06.624] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:41:0)
[08:35:06.624] Timer tick, asking Buildbucket for the build status
[08:35:08.026] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:08.027] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:42:0) after 1m0s
[08:36:08.051] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:42:0)
[08:36:08.051] Timer tick, asking Buildbucket for the build status
[08:36:10.849] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:10.849] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:43:0) after 1m0s
[08:37:10.917] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:43:0)
[08:37:10.917] Timer tick, asking Buildbucket for the build status
[08:37:11.963] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:11.963] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:44:0) after 1m0s
[08:38:11.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:44:0)
[08:38:11.990] Timer tick, asking Buildbucket for the build status
[08:38:14.697] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:14.697] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:45:0) after 1m0s
[08:39:14.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:45:0)
[08:39:14.705] Timer tick, asking Buildbucket for the build status
[08:39:15.639] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:15.639] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:46:0) after 1m0s
[08:40:15.671] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:46:0)
[08:40:15.671] Timer tick, asking Buildbucket for the build status
[08:40:16.037] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:16.037] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:47:0) after 1m0s
[08:41:16.188] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:47:0)
[08:41:16.188] Timer tick, asking Buildbucket for the build status
[08:41:18.002] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:18.002] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:48:0) after 1m0s
[08:42:18.024] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:48:0)
[08:42:18.024] Timer tick, asking Buildbucket for the build status
[08:42:20.230] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:20.230] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:49:0) after 1m0s
[08:43:20.351] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:49:0)
[08:43:20.351] Timer tick, asking Buildbucket for the build status
[08:43:20.521] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:20.521] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:50:0) after 1m0s
[08:44:20.767] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:50:0)
[08:44:20.767] Timer tick, asking Buildbucket for the build status
[08:44:20.945] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:20.945] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:51:0) after 1m0s
[08:45:20.975] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:51:0)
[08:45:20.975] Timer tick, asking Buildbucket for the build status
[08:45:22.411] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:22.411] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:52:0) after 1m0s
[08:46:22.464] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:52:0)
[08:46:22.464] Timer tick, asking Buildbucket for the build status
[08:46:22.949] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:22.949] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:53:0) after 1m0s
[08:47:22.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:53:0)
[08:47:22.972] Timer tick, asking Buildbucket for the build status
[08:47:25.747] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:25.747] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:54:0) after 1m0s
[08:48:25.771] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:54:0)
[08:48:25.771] Timer tick, asking Buildbucket for the build status
[08:48:26.515] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:26.515] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:55:0) after 1m0s
[08:49:26.580] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:55:0)
[08:49:26.580] Timer tick, asking Buildbucket for the build status
[08:49:27.009] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:27.009] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:56:0) after 1m0s
[08:50:26.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:56:0)
[08:50:26.990] Timer tick, asking Buildbucket for the build status
[08:50:29.335] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:29.335] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:57:0) after 1m0s
[08:51:29.357] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:57:0)
[08:51:29.357] Timer tick, asking Buildbucket for the build status
[08:51:29.941] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:29.941] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:58:0) after 1m0s
[08:52:29.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:58:0)
[08:52:29.978] Timer tick, asking Buildbucket for the build status
[08:52:31.476] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:31.476] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:59:0) after 1m0s
[08:53:31.504] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:59:0)
[08:53:31.504] Timer tick, asking Buildbucket for the build status
[08:53:35.632] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:35.632] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:60:0) after 1m0s
[08:54:35.676] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:60:0)
[08:54:35.676] Timer tick, asking Buildbucket for the build status
[08:54:36.007] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:36.007] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:61:0) after 1m0s
[08:55:36.028] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:61:0)
[08:55:36.028] Timer tick, asking Buildbucket for the build status
[08:55:37.036] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:37.036] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:62:0) after 1m0s
[08:56:37.059] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:62:0)
[08:56:37.059] Timer tick, asking Buildbucket for the build status
[08:56:39.903] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:39.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:63:0) after 1m0s
[08:57:39.959] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:63:0)
[08:57:39.959] Timer tick, asking Buildbucket for the build status
[08:57:40.550] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:40.551] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:64:0) after 1m0s
[08:58:40.571] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:64:0)
[08:58:40.571] Timer tick, asking Buildbucket for the build status
[08:58:41.567] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:41.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:65:0) after 1m0s
[08:59:41.781] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:65:0)
[08:59:41.781] Timer tick, asking Buildbucket for the build status
[08:59:42.102] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:42.102] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:66:0) after 1m0s
[09:00:42.217] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:66:0)
[09:00:42.217] Timer tick, asking Buildbucket for the build status
[09:00:44.128] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:44.128] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:67:0) after 1m0s
[09:01:44.242] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:67:0)
[09:01:44.242] Timer tick, asking Buildbucket for the build status
[09:01:46.486] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:46.486] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:68:0) after 1m0s
[09:02:46.497] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:68:0)
[09:02:46.497] Timer tick, asking Buildbucket for the build status
[09:02:48.861] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:48.861] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:69:0) after 1m0s
[09:03:49.374] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:69:0)
[09:03:49.374] Timer tick, asking Buildbucket for the build status
[09:03:52.495] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:52.495] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:70:0) after 1m0s
[09:04:52.529] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:70:0)
[09:04:52.529] Timer tick, asking Buildbucket for the build status
[09:04:52.826] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:52.826] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:71:0) after 1m0s
[09:05:52.976] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:71:0)
[09:05:52.976] Timer tick, asking Buildbucket for the build status
[09:05:54.638] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:54.638] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:72:0) after 1m0s
[09:06:54.666] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:72:0)
[09:06:54.666] Timer tick, asking Buildbucket for the build status
[09:06:55.227] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:55.227] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:73:0) after 1m0s
[09:07:55.659] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:73:0)
[09:07:55.659] Timer tick, asking Buildbucket for the build status
[09:07:55.828] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:55.828] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:74:0) after 1m0s
[09:08:56.664] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:74:0)
[09:08:56.664] Timer tick, asking Buildbucket for the build status
[09:08:57.185] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:57.185] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:75:0) after 1m0s
[09:09:57.208] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:75:0)
[09:09:57.208] Timer tick, asking Buildbucket for the build status
[09:09:57.866] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:57.866] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:76:0) after 1m0s
[09:10:57.894] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:76:0)
[09:10:57.894] Timer tick, asking Buildbucket for the build status
[09:11:00.259] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:00.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:77:0) after 1m0s
[09:12:01.148] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:77:0)
[09:12:01.148] Timer tick, asking Buildbucket for the build status
[09:12:01.591] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:01.591] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:78:0) after 1m0s
[09:13:01.840] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:78:0)
[09:13:01.840] Timer tick, asking Buildbucket for the build status
[09:13:02.517] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:02.517] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:79:0) after 1m0s
[09:14:02.494] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:79:0)
[09:14:02.494] Timer tick, asking Buildbucket for the build status
[09:14:05.504] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:05.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:80:0) after 1m0s
[09:15:05.548] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:80:0)
[09:15:05.548] Timer tick, asking Buildbucket for the build status
[09:15:06.166] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:06.166] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:81:0) after 1m0s
[09:16:06.195] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:81:0)
[09:16:06.195] Timer tick, asking Buildbucket for the build status
[09:16:07.013] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:07.013] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:82:0) after 1m0s
[09:17:07.370] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:82:0)
[09:17:07.370] Timer tick, asking Buildbucket for the build status
[09:17:13.322] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:13.322] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:83:0) after 1m0s
[09:18:14.057] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:83:0)
[09:18:14.057] Timer tick, asking Buildbucket for the build status
[09:18:14.465] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:14.465] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:84:0) after 1m0s
[09:19:14.750] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:84:0)
[09:19:14.750] Timer tick, asking Buildbucket for the build status
[09:19:16.360] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:16.360] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:85:0) after 1m0s
[09:20:16.545] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:85:0)
[09:20:16.545] Timer tick, asking Buildbucket for the build status
[09:20:19.818] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:19.818] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:86:0) after 1m0s
[09:21:19.843] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:86:0)
[09:21:19.843] Timer tick, asking Buildbucket for the build status
[09:21:21.854] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:21.854] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:87:0) after 1m0s
[09:22:22.006] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:87:0)
[09:22:22.006] Timer tick, asking Buildbucket for the build status
[09:22:22.264] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:22.265] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:88:0) after 1m0s
[09:23:22.674] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:88:0)
[09:23:22.674] Timer tick, asking Buildbucket for the build status
[09:23:22.900] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:22.900] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:89:0) after 1m0s
[09:24:22.934] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:89:0)
[09:24:22.934] Timer tick, asking Buildbucket for the build status
[09:24:25.333] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:25.333] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:90:0) after 1m0s
[09:25:25.848] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:90:0)
[09:25:25.848] Timer tick, asking Buildbucket for the build status
[09:25:26.464] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:26.464] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:91:0) after 1m0s
[09:26:26.486] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:91:0)
[09:26:26.486] Timer tick, asking Buildbucket for the build status
[09:26:26.768] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:26.768] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:92:0) after 1m0s
[09:27:27.044] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:92:0)
[09:27:27.044] Timer tick, asking Buildbucket for the build status
[09:27:29.596] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:29.596] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:93:0) after 1m0s
[09:28:29.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:93:0)
[09:28:29.929] Timer tick, asking Buildbucket for the build status
[09:28:31.922] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:31.922] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:94:0) after 1m0s
[09:29:32.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:94:0)
[09:29:32.197] Timer tick, asking Buildbucket for the build status
[09:29:36.062] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:36.062] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:95:0) after 1m0s
[09:30:36.663] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:95:0)
[09:30:36.663] Timer tick, asking Buildbucket for the build status
[09:30:40.115] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:40.115] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:96:0) after 1m0s
[09:31:40.142] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:96:0)
[09:31:40.142] Timer tick, asking Buildbucket for the build status
[09:31:40.321] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:40.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:97:0) after 1m0s
[09:32:40.346] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:97:0)
[09:32:40.346] Timer tick, asking Buildbucket for the build status
[09:32:42.422] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:42.422] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:98:0) after 1m0s
[09:33:42.915] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:98:0)
[09:33:42.915] Timer tick, asking Buildbucket for the build status
[09:33:43.714] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:43.714] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:99:0) after 1m0s
[09:34:43.981] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:99:0)
[09:34:43.981] Timer tick, asking Buildbucket for the build status
[09:34:44.709] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:44.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:100:0) after 1m0s
[09:35:45.508] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:100:0)
[09:35:45.508] Timer tick, asking Buildbucket for the build status
[09:35:45.820] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:45.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:101:0) after 1m0s
[09:36:46.291] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:101:0)
[09:36:46.291] Timer tick, asking Buildbucket for the build status
[09:36:47.837] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:47.837] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:102:0) after 1m0s
[09:37:48.042] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:102:0)
[09:37:48.042] Timer tick, asking Buildbucket for the build status
[09:37:51.052] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:51.052] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:103:0) after 1m0s
[09:38:51.096] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:103:0)
[09:38:51.096] Timer tick, asking Buildbucket for the build status
[09:38:51.412] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:51.412] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:104:0) after 1m0s
[09:39:51.727] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:104:0)
[09:39:51.727] Timer tick, asking Buildbucket for the build status
[09:39:51.923] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:51.923] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:105:0) after 1m0s
[09:40:51.956] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:105:0)
[09:40:51.956] Timer tick, asking Buildbucket for the build status
[09:40:52.172] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:52.172] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:106:0) after 1m0s
[09:41:52.298] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:106:0)
[09:41:52.298] Timer tick, asking Buildbucket for the build status
[09:41:54.203] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:54.203] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:107:0) after 1m0s
[09:42:54.265] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:107:0)
[09:42:54.265] Timer tick, asking Buildbucket for the build status
[09:42:57.079] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:57.079] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:108:0) after 1m0s
[09:43:57.103] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:108:0)
[09:43:57.103] Timer tick, asking Buildbucket for the build status
[09:43:57.992] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:57.993] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:109:0) after 1m0s
[09:44:58.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:109:0)
[09:44:58.083] Timer tick, asking Buildbucket for the build status
[09:44:58.280] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:58.280] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:110:0) after 1m0s
[09:45:58.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:110:0)
[09:45:58.856] Timer tick, asking Buildbucket for the build status
[09:45:59.369] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:59.369] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:111:0) after 1m0s
[09:46:59.687] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:111:0)
[09:46:59.687] Timer tick, asking Buildbucket for the build status
[09:47:00.037] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:00.037] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:112:0) after 1m0s
[09:48:00.372] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:112:0)
[09:48:00.372] Timer tick, asking Buildbucket for the build status
[09:48:02.414] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:02.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:113:0) after 1m0s
[09:49:02.654] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:113:0)
[09:49:02.654] Timer tick, asking Buildbucket for the build status
[09:49:02.793] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:02.793] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:114:0) after 1m0s
[09:50:02.936] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:114:0)
[09:50:02.936] Timer tick, asking Buildbucket for the build status
[09:50:03.228] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:03.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:115:0) after 1m0s
[09:51:03.385] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:115:0)
[09:51:03.385] Timer tick, asking Buildbucket for the build status
[09:51:03.536] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:03.536] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:116:0) after 1m0s
[09:52:03.812] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:116:0)
[09:52:03.812] Timer tick, asking Buildbucket for the build status
[09:52:04.309] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:04.309] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:117:0) after 1m0s
[09:53:04.352] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:117:0)
[09:53:04.352] Timer tick, asking Buildbucket for the build status
[09:53:04.517] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:04.517] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:118:0) after 1m0s
[09:54:04.555] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:118:0)
[09:54:04.555] Timer tick, asking Buildbucket for the build status
[09:54:07.390] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:07.390] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:119:0) after 1m0s
[09:55:07.760] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:119:0)
[09:55:07.760] Timer tick, asking Buildbucket for the build status
[09:55:08.083] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:08.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:120:0) after 1m0s
[09:56:08.421] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:120:0)
[09:56:08.422] Timer tick, asking Buildbucket for the build status
[09:56:09.467] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:09.467] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:121:0) after 1m0s
[09:57:09.546] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:121:0)
[09:57:09.546] Timer tick, asking Buildbucket for the build status
[09:57:10.061] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:10.061] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:122:0) after 1m0s
[09:58:10.647] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:122:0)
[09:58:10.647] Timer tick, asking Buildbucket for the build status
[09:58:10.802] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:10.802] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:123:0) after 1m0s
[09:59:10.842] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:123:0)
[09:59:10.842] Timer tick, asking Buildbucket for the build status
[09:59:11.677] Build 8907974092832693568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:11.677] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073404155786902224:124:0) after 1m0s
[09:59:45.970] Received PubSub notification, asking Buildbucket for the build status
[09:59:47.682] Build 8907974092832693568: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:59:47.682] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1563098359554259",
"created_by": "project:chromium",
"created_ts": "1563090918661448",
"id": "8907974092832693568",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0ed788e3a7dc8b7c18c7462c68bc2c0574b495cb\"}}",
"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-so9v\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 14127, \"got_angle_revision\": \"c3085ead7055e45b2d24189d5eee86753ea5c2f3\", \"got_dawn_revision\": \"2a1d8c2b1d32f4fe27f4bdc35287e1ebdeb8a86a\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"0ed788e3a7dc8b7c18c7462c68bc2c0574b495cb\", \"got_revision_cp\": \"refs/heads/master@{#677219}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"c2aa402868de1f205107166656c085bfcbacfa00\", \"got_v8_revision_cp\": \"refs/heads/7.7.228@{#1}\", \"got_webrtc_revision\": \"5a29d526be7589f5ba7fb824a749f9088b305070\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28568}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0ed788e3a7dc8b7c18c7462c68bc2c0574b495cb\"}, \"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-so9v\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4370-0e704a3\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1563090925419255",
"status": "COMPLETED",
"status_changed_ts": "1563098383284351",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14127",
"builder:Windows deterministic",
"buildset:commit/git/0ed788e3a7dc8b7c18c7462c68bc2c0574b495cb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0ed788e3a7dc8b7c18c7462c68bc2c0574b495cb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9073404155786902224",
"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/8907974092832693568/+/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:46084c307ef00a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1563098383493926",
"url": "https://ci.chromium.org/b/8907974092832693568",
"utcnow_ts": "1563098387651888"
}
}
[09:59:47.682] Invocation finished in 2h4m31.222736311s with status SUCCEEDED