[03:57:10.083] New invocation is queued and will start shortly
[03:57:11.143] Starting the invocation (attempt 1)
[03:57:11.195] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:57:11.195] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:57:11.195] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9083928384259837536",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"2deb08f78a64ccfc2c04a978230d25160f18bcb8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9083928384259837536",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/2deb08f78a64ccfc2c04a978230d25160f18bcb8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2deb08f78a64ccfc2c04a978230d25160f18bcb8",
"gitiles_ref:refs/heads/master"
]
}
[03:57:12.466] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1553054231451091",
"id": "8918498322159829760",
"lease_expiration_ts": "1553173032195215",
"lease_key": "2028251056",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"2deb08f78a64ccfc2c04a978230d25160f18bcb8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553054232337660",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12500",
"builder:Windows deterministic",
"buildset:commit/git/2deb08f78a64ccfc2c04a978230d25160f18bcb8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2deb08f78a64ccfc2c04a978230d25160f18bcb8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083928384259837536",
"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/8918498322159829760/+/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:43b2107502f6a510",
"user_agent:luci-scheduler"
],
"updated_ts": "1553054232337754",
"url": "https://ci.chromium.org/b/8918498322159829760",
"utcnow_ts": "1553054232456902"
}
}
[03:57:12.466] Task URL: https://ci.chromium.org/b/8918498322159829760
[03:57:12.466] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:2:0) after 1m0s
[03:57:13.210] Received PubSub notification, asking Buildbucket for the build status
[03:57:13.619] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:12.487] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:2:0)
[03:58:12.487] Timer tick, asking Buildbucket for the build status
[03:58:12.633] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:12.633] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:4:0) after 1m0s
[03:59:12.651] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:4:0)
[03:59:12.651] Timer tick, asking Buildbucket for the build status
[03:59:13.057] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:13.057] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:5:0) after 1m0s
[04:00:13.063] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:5:0)
[04:00:13.063] Timer tick, asking Buildbucket for the build status
[04:00:13.167] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:13.167] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:6:0) after 1m0s
[04:01:13.161] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:6:0)
[04:01:13.161] Timer tick, asking Buildbucket for the build status
[04:01:13.261] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:13.262] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:7:0) after 1m0s
[04:02:13.258] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:7:0)
[04:02:13.258] Timer tick, asking Buildbucket for the build status
[04:02:13.344] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:13.344] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:8:0) after 1m0s
[04:03:13.329] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:8:0)
[04:03:13.329] Timer tick, asking Buildbucket for the build status
[04:03:13.573] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:13.573] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:9:0) after 1m0s
[04:04:13.572] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:9:0)
[04:04:13.572] Timer tick, asking Buildbucket for the build status
[04:04:14.304] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:14.304] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:10:0) after 1m0s
[04:05:14.292] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:10:0)
[04:05:14.292] Timer tick, asking Buildbucket for the build status
[04:05:14.887] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:14.887] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:11:0) after 1m0s
[04:06:14.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:11:0)
[04:06:14.905] Timer tick, asking Buildbucket for the build status
[04:06:15.261] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:15.261] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:12:0) after 1m0s
[04:07:15.280] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:12:0)
[04:07:15.281] Timer tick, asking Buildbucket for the build status
[04:07:15.401] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:15.401] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:13:0) after 1m0s
[04:08:15.386] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:13:0)
[04:08:15.386] Timer tick, asking Buildbucket for the build status
[04:08:15.981] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:15.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:14:0) after 1m0s
[04:09:16.000] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:14:0)
[04:09:16.000] Timer tick, asking Buildbucket for the build status
[04:09:16.590] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:16.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:15:0) after 1m0s
[04:10:16.596] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:15:0)
[04:10:16.596] Timer tick, asking Buildbucket for the build status
[04:10:16.778] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:16.778] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:16:0) after 1m0s
[04:11:16.799] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:16:0)
[04:11:16.799] Timer tick, asking Buildbucket for the build status
[04:11:16.941] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:16.941] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:17:0) after 1m0s
[04:12:16.964] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:17:0)
[04:12:16.964] Timer tick, asking Buildbucket for the build status
[04:12:17.197] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:17.197] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:18:0) after 1m0s
[04:13:17.190] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:18:0)
[04:13:17.190] Timer tick, asking Buildbucket for the build status
[04:13:17.402] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:17.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:19:0) after 1m0s
[04:14:17.421] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:19:0)
[04:14:17.421] Timer tick, asking Buildbucket for the build status
[04:14:17.677] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:17.677] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:20:0) after 1m0s
[04:15:17.696] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:20:0)
[04:15:17.696] Timer tick, asking Buildbucket for the build status
[04:15:17.768] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:17.768] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:21:0) after 1m0s
[04:16:17.796] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:21:0)
[04:16:17.796] Timer tick, asking Buildbucket for the build status
[04:16:18.094] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:18.094] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:22:0) after 1m0s
[04:17:18.113] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:22:0)
[04:17:18.113] Timer tick, asking Buildbucket for the build status
[04:17:18.238] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:18.238] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:23:0) after 1m0s
[04:18:18.243] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:23:0)
[04:18:18.243] Timer tick, asking Buildbucket for the build status
[04:18:18.375] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:18.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:24:0) after 1m0s
[04:19:18.395] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:24:0)
[04:19:18.395] Timer tick, asking Buildbucket for the build status
[04:19:18.511] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:18.511] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:25:0) after 1m0s
[04:20:18.532] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:25:0)
[04:20:18.532] Timer tick, asking Buildbucket for the build status
[04:20:18.963] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:18.963] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:26:0) after 1m0s
[04:21:18.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:26:0)
[04:21:18.984] Timer tick, asking Buildbucket for the build status
[04:21:19.276] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:19.276] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:27:0) after 1m0s
[04:22:19.282] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:27:0)
[04:22:19.282] Timer tick, asking Buildbucket for the build status
[04:22:19.791] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:19.791] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:28:0) after 1m0s
[04:23:19.796] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:28:0)
[04:23:19.796] Timer tick, asking Buildbucket for the build status
[04:23:20.130] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:20.130] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:29:0) after 1m0s
[04:24:20.155] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:29:0)
[04:24:20.155] Timer tick, asking Buildbucket for the build status
[04:24:20.269] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:20.269] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:30:0) after 1m0s
[04:25:20.298] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:30:0)
[04:25:20.298] Timer tick, asking Buildbucket for the build status
[04:25:20.458] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:20.458] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:31:0) after 1m0s
[04:26:20.478] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:31:0)
[04:26:20.478] Timer tick, asking Buildbucket for the build status
[04:26:21.578] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:21.578] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:32:0) after 1m0s
[04:27:21.600] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:32:0)
[04:27:21.600] Timer tick, asking Buildbucket for the build status
[04:27:21.679] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:21.679] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:33:0) after 1m0s
[04:28:21.698] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:33:0)
[04:28:21.698] Timer tick, asking Buildbucket for the build status
[04:28:22.281] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:22.281] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:34:0) after 1m0s
[04:29:22.284] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:34:0)
[04:29:22.284] Timer tick, asking Buildbucket for the build status
[04:29:22.363] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:22.363] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:35:0) after 1m0s
[04:30:22.384] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:35:0)
[04:30:22.384] Timer tick, asking Buildbucket for the build status
[04:30:23.062] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:23.062] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:36:0) after 1m0s
[04:31:23.088] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:36:0)
[04:31:23.088] Timer tick, asking Buildbucket for the build status
[04:31:23.207] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:23.207] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:37:0) after 1m0s
[04:32:23.235] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:37:0)
[04:32:23.235] Timer tick, asking Buildbucket for the build status
[04:32:23.321] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:23.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:38:0) after 1m0s
[04:33:23.340] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:38:0)
[04:33:23.340] Timer tick, asking Buildbucket for the build status
[04:33:23.461] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:23.461] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:39:0) after 1m0s
[04:34:23.469] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:39:0)
[04:34:23.469] Timer tick, asking Buildbucket for the build status
[04:34:23.915] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:23.915] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:40:0) after 1m0s
[04:35:23.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:40:0)
[04:35:23.938] Timer tick, asking Buildbucket for the build status
[04:35:24.284] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:24.284] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:41:0) after 1m0s
[04:36:24.310] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:41:0)
[04:36:24.310] Timer tick, asking Buildbucket for the build status
[04:36:24.423] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:24.423] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:42:0) after 1m0s
[04:37:24.440] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:42:0)
[04:37:24.440] Timer tick, asking Buildbucket for the build status
[04:37:24.673] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:24.673] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:43:0) after 1m0s
[04:38:24.697] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:43:0)
[04:38:24.697] Timer tick, asking Buildbucket for the build status
[04:38:24.862] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:24.862] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:44:0) after 1m0s
[04:39:24.877] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:44:0)
[04:39:24.877] Timer tick, asking Buildbucket for the build status
[04:39:25.079] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:25.079] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:45:0) after 1m0s
[04:40:25.086] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:45:0)
[04:40:25.086] Timer tick, asking Buildbucket for the build status
[04:40:25.671] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:25.671] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:46:0) after 1m0s
[04:41:25.667] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:46:0)
[04:41:25.667] Timer tick, asking Buildbucket for the build status
[04:41:26.274] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:26.274] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:47:0) after 1m0s
[04:42:26.301] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:47:0)
[04:42:26.301] Timer tick, asking Buildbucket for the build status
[04:42:26.879] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:26.879] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:48:0) after 1m0s
[04:43:26.928] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:48:0)
[04:43:26.928] Timer tick, asking Buildbucket for the build status
[04:43:27.387] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:27.387] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:49:0) after 1m0s
[04:44:27.411] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:49:0)
[04:44:27.411] Timer tick, asking Buildbucket for the build status
[04:44:27.528] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:27.528] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:50:0) after 1m0s
[04:45:27.510] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:50:0)
[04:45:27.510] Timer tick, asking Buildbucket for the build status
[04:45:27.626] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:27.626] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:51:0) after 1m0s
[04:46:27.631] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:51:0)
[04:46:27.631] Timer tick, asking Buildbucket for the build status
[04:46:27.743] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:27.743] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:52:0) after 1m0s
[04:47:27.766] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:52:0)
[04:47:27.766] Timer tick, asking Buildbucket for the build status
[04:47:27.853] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:27.853] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:53:0) after 1m0s
[04:48:27.862] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:53:0)
[04:48:27.862] Timer tick, asking Buildbucket for the build status
[04:48:27.947] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:27.947] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:54:0) after 1m0s
[04:49:28.006] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:54:0)
[04:49:28.006] Timer tick, asking Buildbucket for the build status
[04:49:28.173] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:28.173] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:55:0) after 1m0s
[04:50:28.198] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:55:0)
[04:50:28.198] Timer tick, asking Buildbucket for the build status
[04:50:28.869] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:28.869] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:56:0) after 1m0s
[04:51:28.887] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:56:0)
[04:51:28.887] Timer tick, asking Buildbucket for the build status
[04:51:29.096] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:29.096] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:57:0) after 1m0s
[04:52:29.099] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:57:0)
[04:52:29.099] Timer tick, asking Buildbucket for the build status
[04:52:29.288] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:29.288] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:58:0) after 1m0s
[04:53:29.313] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:58:0)
[04:53:29.313] Timer tick, asking Buildbucket for the build status
[04:53:30.516] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:30.516] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:59:0) after 1m0s
[04:54:30.539] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:59:0)
[04:54:30.539] Timer tick, asking Buildbucket for the build status
[04:54:30.631] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:30.631] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:60:0) after 1m0s
[04:55:30.628] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:60:0)
[04:55:30.628] Timer tick, asking Buildbucket for the build status
[04:55:30.990] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:30.990] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:61:0) after 1m0s
[04:56:30.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:61:0)
[04:56:30.978] Timer tick, asking Buildbucket for the build status
[04:56:31.058] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:31.058] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:62:0) after 1m0s
[04:57:31.096] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:62:0)
[04:57:31.096] Timer tick, asking Buildbucket for the build status
[04:57:31.898] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:31.898] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:63:0) after 1m0s
[04:58:31.887] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:63:0)
[04:58:31.887] Timer tick, asking Buildbucket for the build status
[04:58:32.402] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:32.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:64:0) after 1m0s
[04:59:32.424] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:64:0)
[04:59:32.424] Timer tick, asking Buildbucket for the build status
[04:59:32.525] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:32.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:65:0) after 1m0s
[05:00:32.545] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:65:0)
[05:00:32.545] Timer tick, asking Buildbucket for the build status
[05:00:33.198] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:33.198] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:66:0) after 1m0s
[05:01:33.234] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:66:0)
[05:01:33.234] Timer tick, asking Buildbucket for the build status
[05:01:33.475] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:33.475] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:67:0) after 1m0s
[05:02:33.460] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:67:0)
[05:02:33.460] Timer tick, asking Buildbucket for the build status
[05:02:33.563] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:33.563] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:68:0) after 1m0s
[05:03:33.564] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:68:0)
[05:03:33.564] Timer tick, asking Buildbucket for the build status
[05:03:34.082] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:34.082] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:69:0) after 1m0s
[05:04:34.095] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:69:0)
[05:04:34.095] Timer tick, asking Buildbucket for the build status
[05:04:34.659] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:34.659] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:70:0) after 1m0s
[05:05:34.678] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:70:0)
[05:05:34.678] Timer tick, asking Buildbucket for the build status
[05:05:35.118] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:35.118] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:71:0) after 1m0s
[05:06:35.142] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:71:0)
[05:06:35.142] Timer tick, asking Buildbucket for the build status
[05:06:35.283] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:35.283] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:72:0) after 1m0s
[05:07:35.318] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:72:0)
[05:07:35.318] Timer tick, asking Buildbucket for the build status
[05:07:35.405] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:35.405] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:73:0) after 1m0s
[05:08:35.412] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:73:0)
[05:08:35.412] Timer tick, asking Buildbucket for the build status
[05:08:35.509] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:35.509] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:74:0) after 1m0s
[05:09:35.531] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:74:0)
[05:09:35.531] Timer tick, asking Buildbucket for the build status
[05:09:35.784] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:35.784] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:75:0) after 1m0s
[05:10:35.766] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:75:0)
[05:10:35.766] Timer tick, asking Buildbucket for the build status
[05:10:35.911] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:35.911] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:76:0) after 1m0s
[05:11:35.957] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:76:0)
[05:11:35.957] Timer tick, asking Buildbucket for the build status
[05:11:36.102] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:36.102] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:77:0) after 1m0s
[05:12:36.125] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:77:0)
[05:12:36.125] Timer tick, asking Buildbucket for the build status
[05:12:36.284] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:36.284] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:78:0) after 1m0s
[05:13:36.269] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:78:0)
[05:13:36.269] Timer tick, asking Buildbucket for the build status
[05:13:36.513] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:36.513] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:79:0) after 1m0s
[05:14:36.504] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:79:0)
[05:14:36.504] Timer tick, asking Buildbucket for the build status
[05:14:36.864] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:36.864] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:80:0) after 1m0s
[05:15:37.047] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:80:0)
[05:15:37.047] Timer tick, asking Buildbucket for the build status
[05:15:37.309] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:37.309] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:81:0) after 1m0s
[05:16:37.294] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:81:0)
[05:16:37.294] Timer tick, asking Buildbucket for the build status
[05:16:37.428] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:37.428] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:82:0) after 1m0s
[05:17:37.451] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:82:0)
[05:17:37.451] Timer tick, asking Buildbucket for the build status
[05:17:37.979] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:37.979] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:83:0) after 1m0s
[05:18:37.992] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:83:0)
[05:18:37.992] Timer tick, asking Buildbucket for the build status
[05:18:38.776] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:38.776] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:84:0) after 1m0s
[05:19:38.799] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:84:0)
[05:19:38.799] Timer tick, asking Buildbucket for the build status
[05:19:38.996] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:38.996] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:85:0) after 1m0s
[05:20:38.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:85:0)
[05:20:38.990] Timer tick, asking Buildbucket for the build status
[05:20:39.627] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:39.627] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:86:0) after 1m0s
[05:21:39.656] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:86:0)
[05:21:39.656] Timer tick, asking Buildbucket for the build status
[05:21:39.756] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:39.756] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:87:0) after 1m0s
[05:22:39.741] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:87:0)
[05:22:39.741] Timer tick, asking Buildbucket for the build status
[05:22:39.882] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:39.882] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:88:0) after 1m0s
[05:23:39.899] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:88:0)
[05:23:39.899] Timer tick, asking Buildbucket for the build status
[05:23:40.041] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:40.041] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:89:0) after 1m0s
[05:24:40.066] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:89:0)
[05:24:40.066] Timer tick, asking Buildbucket for the build status
[05:24:40.567] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:40.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:90:0) after 1m0s
[05:25:40.548] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:90:0)
[05:25:40.548] Timer tick, asking Buildbucket for the build status
[05:25:40.711] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:40.711] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:91:0) after 1m0s
[05:26:40.732] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:91:0)
[05:26:40.732] Timer tick, asking Buildbucket for the build status
[05:26:40.875] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:40.875] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:92:0) after 1m0s
[05:27:40.903] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:92:0)
[05:27:40.903] Timer tick, asking Buildbucket for the build status
[05:27:41.477] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:41.477] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:93:0) after 1m0s
[05:28:41.485] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:93:0)
[05:28:41.485] Timer tick, asking Buildbucket for the build status
[05:28:41.619] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:41.619] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:94:0) after 1m0s
[05:29:41.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:94:0)
[05:29:41.645] Timer tick, asking Buildbucket for the build status
[05:29:41.958] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:41.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:95:0) after 1m0s
[05:30:41.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:95:0)
[05:30:41.982] Timer tick, asking Buildbucket for the build status
[05:30:42.252] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:42.252] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:96:0) after 1m0s
[05:31:42.275] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:96:0)
[05:31:42.275] Timer tick, asking Buildbucket for the build status
[05:31:42.507] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:42.507] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:97:0) after 1m0s
[05:32:42.511] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:97:0)
[05:32:42.511] Timer tick, asking Buildbucket for the build status
[05:32:42.727] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:42.727] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:98:0) after 1m0s
[05:33:42.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:98:0)
[05:33:42.763] Timer tick, asking Buildbucket for the build status
[05:33:42.981] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:42.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:99:0) after 1m0s
[05:34:42.996] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:99:0)
[05:34:42.996] Timer tick, asking Buildbucket for the build status
[05:34:43.244] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:43.244] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:100:0) after 1m0s
[05:35:43.291] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:100:0)
[05:35:43.291] Timer tick, asking Buildbucket for the build status
[05:35:43.407] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:43.407] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:101:0) after 1m0s
[05:36:43.429] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:101:0)
[05:36:43.429] Timer tick, asking Buildbucket for the build status
[05:36:43.547] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:43.547] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:102:0) after 1m0s
[05:37:43.576] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:102:0)
[05:37:43.576] Timer tick, asking Buildbucket for the build status
[05:37:44.472] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:44.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:103:0) after 1m0s
[05:38:44.493] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:103:0)
[05:38:44.493] Timer tick, asking Buildbucket for the build status
[05:38:44.732] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:44.732] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:104:0) after 1m0s
[05:39:44.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:104:0)
[05:39:44.758] Timer tick, asking Buildbucket for the build status
[05:39:45.077] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:45.077] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:105:0) after 1m0s
[05:40:45.104] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:105:0)
[05:40:45.104] Timer tick, asking Buildbucket for the build status
[05:40:45.221] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:45.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:106:0) after 1m0s
[05:41:45.211] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:106:0)
[05:41:45.211] Timer tick, asking Buildbucket for the build status
[05:41:45.575] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:45.575] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:107:0) after 1m0s
[05:42:45.597] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:107:0)
[05:42:45.597] Timer tick, asking Buildbucket for the build status
[05:42:46.075] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:46.075] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:108:0) after 1m0s
[05:43:46.106] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:108:0)
[05:43:46.106] Timer tick, asking Buildbucket for the build status
[05:43:46.211] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:46.211] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:109:0) after 1m0s
[05:44:46.236] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:109:0)
[05:44:46.236] Timer tick, asking Buildbucket for the build status
[05:44:46.355] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:46.355] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:110:0) after 1m0s
[05:45:46.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:110:0)
[05:45:46.344] Timer tick, asking Buildbucket for the build status
[05:45:46.472] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:46.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:111:0) after 1m0s
[05:46:46.493] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:111:0)
[05:46:46.493] Timer tick, asking Buildbucket for the build status
[05:46:46.624] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:46.624] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:112:0) after 1m0s
[05:47:46.604] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:112:0)
[05:47:46.604] Timer tick, asking Buildbucket for the build status
[05:47:47.082] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:47.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:113:0) after 1m0s
[05:48:47.063] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:113:0)
[05:48:47.063] Timer tick, asking Buildbucket for the build status
[05:48:47.188] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:47.188] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:114:0) after 1m0s
[05:49:47.190] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:114:0)
[05:49:47.190] Timer tick, asking Buildbucket for the build status
[05:49:47.698] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:47.698] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:115:0) after 1m0s
[05:50:47.723] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:115:0)
[05:50:47.723] Timer tick, asking Buildbucket for the build status
[05:50:47.898] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:47.898] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:116:0) after 1m0s
[05:51:47.921] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:116:0)
[05:51:47.921] Timer tick, asking Buildbucket for the build status
[05:51:48.567] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:48.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:117:0) after 1m0s
[05:52:48.557] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:117:0)
[05:52:48.557] Timer tick, asking Buildbucket for the build status
[05:52:48.671] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:48.671] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:118:0) after 1m0s
[05:53:48.695] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:118:0)
[05:53:48.695] Timer tick, asking Buildbucket for the build status
[05:53:48.919] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:48.919] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:119:0) after 1m0s
[05:54:48.907] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:119:0)
[05:54:48.907] Timer tick, asking Buildbucket for the build status
[05:54:49.187] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:49.187] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:120:0) after 1m0s
[05:55:49.219] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:120:0)
[05:55:49.219] Timer tick, asking Buildbucket for the build status
[05:55:49.500] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:49.500] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:121:0) after 1m0s
[05:56:49.497] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:121:0)
[05:56:49.497] Timer tick, asking Buildbucket for the build status
[05:56:49.597] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:49.597] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:122:0) after 1m0s
[05:57:49.601] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:122:0)
[05:57:49.601] Timer tick, asking Buildbucket for the build status
[05:57:49.692] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:49.692] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:123:0) after 1m0s
[05:58:49.712] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:123:0)
[05:58:49.712] Timer tick, asking Buildbucket for the build status
[05:58:49.938] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:49.938] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:124:0) after 1m0s
[05:59:49.962] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:124:0)
[05:59:49.962] Timer tick, asking Buildbucket for the build status
[05:59:50.063] Build 8918498322159829760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:50.063] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083928384259837536:125:0) after 1m0s
[05:59:57.404] Received PubSub notification, asking Buildbucket for the build status
[05:59:57.493] Build 8918498322159829760: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:59:57.494] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1553061596091604",
"created_by": "project:chromium",
"created_ts": "1553054231451091",
"id": "8918498322159829760",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"2deb08f78a64ccfc2c04a978230d25160f18bcb8\"}}",
"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\": [\"4211-90dafba\"], \"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\": \"6ce1be5a5a53eb0213075dd7591a7dd4a3708614\", \"got_nacl_revision\": \"7d24fb15d81f95ed30dde0c04b68584749709657\", \"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\": \"2deb08f78a64ccfc2c04a978230d25160f18bcb8\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"recipe\": \"swarming/deterministic_build\", \"mastername\": \"chromium.win\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"got_v8_revision\": \"93306f1d7b2c10824e1e8876e5b8a3ab37c42b96\", \"got_revision_cp\": \"refs/heads/master@{#642369}\", \"got_dawn_revision\": \"54e4d47db4910ebd1ffce0247b60d4e6f984774f\", \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"buildnumber\": 12500, \"revision\": \"2deb08f78a64ccfc2c04a978230d25160f18bcb8\", \"got_v8_revision_cp\": \"refs/heads/7.5.72@{#1}\", \"bot_id\": \"windows-deterministic-0-v3qq\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1553054232503380",
"status": "COMPLETED",
"status_changed_ts": "1553061597011492",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12500",
"builder:Windows deterministic",
"buildset:commit/git/2deb08f78a64ccfc2c04a978230d25160f18bcb8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2deb08f78a64ccfc2c04a978230d25160f18bcb8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083928384259837536",
"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/8918498322159829760/+/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:43b2107502f6a510",
"user_agent:luci-scheduler"
],
"updated_ts": "1553061597014557",
"url": "https://ci.chromium.org/b/8918498322159829760",
"utcnow_ts": "1553061597485140"
}
}
[05:59:57.494] Invocation finished in 2h2m47.428897035s with status SUCCEEDED