[23:49:42.111] New invocation is queued and will start shortly
[23:49:43.222] Starting the invocation (attempt 1)
[23:49:43.262] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[23:49:43.262] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[23:49:43.262] Buildbucket request:
{
"bucket": "luci.v8.ci",
"client_operation_id": "9019167122511927600",
"parameters_json": "{\"builder_name\":\"V8 Win64 - dev image\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler-dev.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"d2948ce943dab04699988d8516eddcba8035e49e\"},\"id\":\"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@d2948ce943dab04699988d8516eddcba8035e49e\",\"title\":\"d2948ce943dab04699988d8516eddcba8035e49e\",\"url\":\"https://chromium.googlesource.com/v8/v8/+/d2948ce943dab04699988d8516eddcba8035e49e\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"d2948ce943dab04699988d8516eddcba8035e49e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
},
"tags": [
"builder:V8 Win64 - dev image",
"scheduler_invocation_id:9019167122511927600",
"scheduler_job_id:v8/V8 Win64 - dev image",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/d2948ce943dab04699988d8516eddcba8035e49e",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/d2948ce943dab04699988d8516eddcba8035e49e",
"gitiles_ref:refs/heads/master"
]
}
[23:49:43.866] Buildbucket response:
{
"build": {
"bucket": "luci.v8.ci",
"canary_preference": "PROD",
"created_by": "project:v8",
"created_ts": "1614815383357786",
"id": "8853737060539526560",
"parameters_json": "{\"builder_name\": \"V8 Win64 - dev image\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler-dev.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"d2948ce943dab04699988d8516eddcba8035e49e\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@d2948ce943dab04699988d8516eddcba8035e49e\", \"title\": \"d2948ce943dab04699988d8516eddcba8035e49e\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/d2948ce943dab04699988d8516eddcba8035e49e\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"d2948ce943dab04699988d8516eddcba8035e49e\"}}",
"project": "v8",
"result_details_json": "{\"properties\": {}}",
"service_account": "v8-ci-builder-dev@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614815383756760",
"tags": [
"build_address:luci.v8.ci/V8 Win64 - dev image/658",
"builder:V8 Win64 - dev image",
"buildset:commit/git/d2948ce943dab04699988d8516eddcba8035e49e",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/d2948ce943dab04699988d8516eddcba8035e49e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019167122511927600",
"scheduler_job_id:v8/V8 Win64 - dev image",
"swarming_hostname:chromium-swarm-dev.appspot.com",
"swarming_tag:log_location:logdog://luci-logdog-dev.appspot.com/v8/buildbucket/cr-buildbucket-dev.appspot.com/8853737060539526560/+/annotations",
"swarming_tag:luci_project:v8",
"swarming_tag:recipe_name:v8",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1614815383756878",
"url": "https://luci-milo-dev.appspot.com/b/8853737060539526560",
"utcnow_ts": "1614815383859311"
}
}
[23:49:43.866] Task URL: https://luci-milo-dev.appspot.com/b/8853737060539526560
[23:49:43.866] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:2:0) after 1m0s
[23:50:43.945] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:2:0)
[23:50:43.945] Timer tick, asking Buildbucket for the build status
[23:50:44.021] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:44.021] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:3:0) after 1m0s
[23:51:44.100] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:3:0)
[23:51:44.100] Timer tick, asking Buildbucket for the build status
[23:51:44.177] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:44.177] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:4:0) after 1m0s
[23:52:44.238] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:4:0)
[23:52:44.238] Timer tick, asking Buildbucket for the build status
[23:52:44.393] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:44.393] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:5:0) after 1m0s
[23:53:44.486] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:5:0)
[23:53:44.486] Timer tick, asking Buildbucket for the build status
[23:53:44.560] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:53:44.560] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:6:0) after 1m0s
[23:54:44.578] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:6:0)
[23:54:44.578] Timer tick, asking Buildbucket for the build status
[23:54:44.674] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:54:44.674] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:7:0) after 1m0s
[23:55:44.693] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:7:0)
[23:55:44.693] Timer tick, asking Buildbucket for the build status
[23:55:44.768] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:55:44.768] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:8:0) after 1m0s
[23:56:44.795] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:8:0)
[23:56:44.795] Timer tick, asking Buildbucket for the build status
[23:56:44.882] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:56:44.882] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:9:0) after 1m0s
[23:57:44.937] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:9:0)
[23:57:44.937] Timer tick, asking Buildbucket for the build status
[23:57:45.028] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:45.028] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:10:0) after 1m0s
[23:58:45.053] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:10:0)
[23:58:45.053] Timer tick, asking Buildbucket for the build status
[23:58:45.195] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:58:45.195] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:11:0) after 1m0s
[23:59:45.296] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:11:0)
[23:59:45.296] Timer tick, asking Buildbucket for the build status
[23:59:45.365] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:59:45.365] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:12:0) after 1m0s
[00:00:45.381] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:12:0)
[00:00:45.381] Timer tick, asking Buildbucket for the build status
[00:00:45.502] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:00:45.502] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:13:0) after 1m0s
[00:01:45.520] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:13:0)
[00:01:45.520] Timer tick, asking Buildbucket for the build status
[00:01:45.656] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:01:45.656] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:14:0) after 1m0s
[00:02:45.683] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:14:0)
[00:02:45.683] Timer tick, asking Buildbucket for the build status
[00:02:45.768] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:02:45.768] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:15:0) after 1m0s
[00:03:45.791] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:15:0)
[00:03:45.791] Timer tick, asking Buildbucket for the build status
[00:03:45.867] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:03:45.867] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:16:0) after 1m0s
[00:04:45.885] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:16:0)
[00:04:45.885] Timer tick, asking Buildbucket for the build status
[00:04:45.964] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:04:45.964] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:17:0) after 1m0s
[00:05:45.981] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:17:0)
[00:05:45.981] Timer tick, asking Buildbucket for the build status
[00:05:46.096] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:05:46.096] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:18:0) after 1m0s
[00:06:46.177] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:18:0)
[00:06:46.177] Timer tick, asking Buildbucket for the build status
[00:06:46.263] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:06:46.263] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:19:0) after 1m0s
[00:07:46.338] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:19:0)
[00:07:46.338] Timer tick, asking Buildbucket for the build status
[00:07:46.419] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:07:46.419] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:20:0) after 1m0s
[00:08:46.438] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:20:0)
[00:08:46.438] Timer tick, asking Buildbucket for the build status
[00:08:46.503] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:08:46.503] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:21:0) after 1m0s
[00:09:46.525] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:21:0)
[00:09:46.525] Timer tick, asking Buildbucket for the build status
[00:09:46.602] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:09:46.602] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:22:0) after 1m0s
[00:10:46.639] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:22:0)
[00:10:46.639] Timer tick, asking Buildbucket for the build status
[00:10:46.716] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:10:46.716] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:23:0) after 1m0s
[00:11:46.891] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:23:0)
[00:11:46.891] Timer tick, asking Buildbucket for the build status
[00:11:46.995] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:11:46.995] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:24:0) after 1m0s
[00:12:47.096] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:24:0)
[00:12:47.096] Timer tick, asking Buildbucket for the build status
[00:12:47.180] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:12:47.180] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:25:0) after 1m0s
[00:13:47.238] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:25:0)
[00:13:47.238] Timer tick, asking Buildbucket for the build status
[00:13:47.384] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:13:47.384] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:26:0) after 1m0s
[00:14:47.408] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:26:0)
[00:14:47.408] Timer tick, asking Buildbucket for the build status
[00:14:47.556] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:14:47.556] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:27:0) after 1m0s
[00:15:47.604] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:27:0)
[00:15:47.604] Timer tick, asking Buildbucket for the build status
[00:15:47.683] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:15:47.683] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:28:0) after 1m0s
[00:16:47.754] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:28:0)
[00:16:47.754] Timer tick, asking Buildbucket for the build status
[00:16:47.826] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:16:47.826] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:29:0) after 1m0s
[00:17:47.878] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:29:0)
[00:17:47.878] Timer tick, asking Buildbucket for the build status
[00:17:47.958] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:17:47.958] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:30:0) after 1m0s
[00:18:47.992] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:30:0)
[00:18:47.992] Timer tick, asking Buildbucket for the build status
[00:18:48.085] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:18:48.085] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:31:0) after 1m0s
[00:19:48.140] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:31:0)
[00:19:48.140] Timer tick, asking Buildbucket for the build status
[00:19:48.222] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:19:48.222] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:32:0) after 1m0s
[00:20:48.249] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:32:0)
[00:20:48.249] Timer tick, asking Buildbucket for the build status
[00:20:48.341] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:20:48.341] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:33:0) after 1m0s
[00:21:48.439] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:33:0)
[00:21:48.439] Timer tick, asking Buildbucket for the build status
[00:21:48.610] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:48.610] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:34:0) after 1m0s
[00:22:48.689] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:34:0)
[00:22:48.689] Timer tick, asking Buildbucket for the build status
[00:22:48.770] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:48.770] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:35:0) after 1m0s
[00:23:48.792] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:35:0)
[00:23:48.792] Timer tick, asking Buildbucket for the build status
[00:23:48.874] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:23:48.874] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:36:0) after 1m0s
[00:24:48.994] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:36:0)
[00:24:48.994] Timer tick, asking Buildbucket for the build status
[00:24:49.084] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:24:49.084] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:37:0) after 1m0s
[00:25:49.103] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:37:0)
[00:25:49.103] Timer tick, asking Buildbucket for the build status
[00:25:49.237] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:25:49.237] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:38:0) after 1m0s
[00:26:49.341] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:38:0)
[00:26:49.341] Timer tick, asking Buildbucket for the build status
[00:26:49.532] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:26:49.532] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:39:0) after 1m0s
[00:27:49.567] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:39:0)
[00:27:49.567] Timer tick, asking Buildbucket for the build status
[00:27:49.666] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:27:49.666] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:40:0) after 1m0s
[00:28:49.688] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:40:0)
[00:28:49.688] Timer tick, asking Buildbucket for the build status
[00:28:49.817] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:28:49.817] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:41:0) after 1m0s
[00:29:49.849] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:41:0)
[00:29:49.849] Timer tick, asking Buildbucket for the build status
[00:29:49.921] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:29:49.921] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:42:0) after 1m0s
[00:30:49.947] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:42:0)
[00:30:49.947] Timer tick, asking Buildbucket for the build status
[00:30:50.020] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:30:50.020] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:43:0) after 1m0s
[00:31:50.088] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:43:0)
[00:31:50.088] Timer tick, asking Buildbucket for the build status
[00:31:50.162] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:31:50.162] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:44:0) after 1m0s
[00:32:50.286] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:44:0)
[00:32:50.286] Timer tick, asking Buildbucket for the build status
[00:32:50.363] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:32:50.363] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:45:0) after 1m0s
[00:33:50.385] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:45:0)
[00:33:50.385] Timer tick, asking Buildbucket for the build status
[00:33:50.461] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:33:50.461] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:46:0) after 1m0s
[00:34:50.483] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:46:0)
[00:34:50.483] Timer tick, asking Buildbucket for the build status
[00:34:50.551] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:34:50.551] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:47:0) after 1m0s
[00:35:50.648] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:47:0)
[00:35:50.648] Timer tick, asking Buildbucket for the build status
[00:35:50.719] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:50.719] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:48:0) after 1m0s
[00:36:50.739] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:48:0)
[00:36:50.739] Timer tick, asking Buildbucket for the build status
[00:36:50.825] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:50.825] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:49:0) after 1m0s
[00:37:50.857] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:49:0)
[00:37:50.857] Timer tick, asking Buildbucket for the build status
[00:37:50.940] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:50.940] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:50:0) after 1m0s
[00:38:51.041] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:50:0)
[00:38:51.041] Timer tick, asking Buildbucket for the build status
[00:38:51.201] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:51.201] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:51:0) after 1m0s
[00:39:51.279] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:51:0)
[00:39:51.279] Timer tick, asking Buildbucket for the build status
[00:39:51.355] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:51.355] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:52:0) after 1m0s
[00:40:51.377] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:52:0)
[00:40:51.377] Timer tick, asking Buildbucket for the build status
[00:40:51.457] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:51.457] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:53:0) after 1m0s
[00:41:51.486] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:53:0)
[00:41:51.486] Timer tick, asking Buildbucket for the build status
[00:41:51.562] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:51.562] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:54:0) after 1m0s
[00:42:51.590] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:54:0)
[00:42:51.590] Timer tick, asking Buildbucket for the build status
[00:42:51.678] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:51.678] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:55:0) after 1m0s
[00:43:51.781] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:55:0)
[00:43:51.781] Timer tick, asking Buildbucket for the build status
[00:43:51.856] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:51.856] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:56:0) after 1m0s
[00:44:51.880] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:56:0)
[00:44:51.880] Timer tick, asking Buildbucket for the build status
[00:44:51.959] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:51.959] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:57:0) after 1m0s
[00:45:52.004] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:57:0)
[00:45:52.004] Timer tick, asking Buildbucket for the build status
[00:45:52.079] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:52.080] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:58:0) after 1m0s
[00:46:52.186] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:58:0)
[00:46:52.186] Timer tick, asking Buildbucket for the build status
[00:46:52.406] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:52.406] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:59:0) after 1m0s
[00:47:52.437] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:59:0)
[00:47:52.437] Timer tick, asking Buildbucket for the build status
[00:47:52.534] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:52.534] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:60:0) after 1m0s
[00:48:52.578] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:60:0)
[00:48:52.578] Timer tick, asking Buildbucket for the build status
[00:48:52.821] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:52.822] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:61:0) after 1m0s
[00:49:52.900] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:61:0)
[00:49:52.900] Timer tick, asking Buildbucket for the build status
[00:49:53.194] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:49:53.194] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:62:0) after 1m0s
[00:50:53.227] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:62:0)
[00:50:53.227] Timer tick, asking Buildbucket for the build status
[00:50:53.305] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:50:53.305] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:63:0) after 1m0s
[00:51:53.338] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:63:0)
[00:51:53.338] Timer tick, asking Buildbucket for the build status
[00:51:53.584] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:53.584] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:64:0) after 1m0s
[00:52:53.643] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:64:0)
[00:52:53.644] Timer tick, asking Buildbucket for the build status
[00:52:53.726] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:53.726] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:65:0) after 1m0s
[00:53:53.747] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:65:0)
[00:53:53.747] Timer tick, asking Buildbucket for the build status
[00:53:53.983] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:53.983] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:66:0) after 1m0s
[00:54:54.082] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:66:0)
[00:54:54.082] Timer tick, asking Buildbucket for the build status
[00:54:54.185] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:54:54.185] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:67:0) after 1m0s
[00:55:54.258] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:67:0)
[00:55:54.258] Timer tick, asking Buildbucket for the build status
[00:55:54.518] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:55:54.518] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:68:0) after 1m0s
[00:56:54.552] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:68:0)
[00:56:54.552] Timer tick, asking Buildbucket for the build status
[00:56:54.808] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:56:54.808] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:69:0) after 1m0s
[00:57:54.859] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:69:0)
[00:57:54.859] Timer tick, asking Buildbucket for the build status
[00:57:54.949] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:54.949] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:70:0) after 1m0s
[00:58:54.986] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:70:0)
[00:58:54.986] Timer tick, asking Buildbucket for the build status
[00:58:55.236] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:55.236] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:71:0) after 1m0s
[00:59:55.393] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:71:0)
[00:59:55.393] Timer tick, asking Buildbucket for the build status
[00:59:55.489] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:55.489] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:72:0) after 1m0s
[01:00:55.521] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:72:0)
[01:00:55.521] Timer tick, asking Buildbucket for the build status
[01:00:55.797] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:55.797] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:73:0) after 1m0s
[01:01:55.853] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:73:0)
[01:01:55.853] Timer tick, asking Buildbucket for the build status
[01:01:56.094] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:56.094] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:74:0) after 1m0s
[01:02:56.149] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:74:0)
[01:02:56.149] Timer tick, asking Buildbucket for the build status
[01:02:56.367] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:56.367] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:75:0) after 1m0s
[01:03:56.397] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:75:0)
[01:03:56.397] Timer tick, asking Buildbucket for the build status
[01:03:56.657] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:56.657] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:76:0) after 1m0s
[01:04:56.681] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:76:0)
[01:04:56.681] Timer tick, asking Buildbucket for the build status
[01:04:56.879] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:56.879] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:77:0) after 1m0s
[01:05:56.994] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:77:0)
[01:05:56.994] Timer tick, asking Buildbucket for the build status
[01:05:57.292] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:57.292] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:78:0) after 1m0s
[01:06:57.359] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:78:0)
[01:06:57.359] Timer tick, asking Buildbucket for the build status
[01:06:57.593] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:57.593] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:79:0) after 1m0s
[01:07:57.684] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:79:0)
[01:07:57.684] Timer tick, asking Buildbucket for the build status
[01:07:58.001] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:58.001] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:80:0) after 1m0s
[01:08:58.088] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:80:0)
[01:08:58.088] Timer tick, asking Buildbucket for the build status
[01:08:58.269] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:58.269] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:81:0) after 1m0s
[01:09:58.288] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:81:0)
[01:09:58.288] Timer tick, asking Buildbucket for the build status
[01:09:58.538] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:58.538] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:82:0) after 1m0s
[01:10:58.658] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:82:0)
[01:10:58.658] Timer tick, asking Buildbucket for the build status
[01:10:58.885] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:58.885] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:83:0) after 1m0s
[01:11:58.939] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:83:0)
[01:11:58.939] Timer tick, asking Buildbucket for the build status
[01:11:59.290] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:59.290] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:84:0) after 1m0s
[01:12:59.311] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:84:0)
[01:12:59.311] Timer tick, asking Buildbucket for the build status
[01:12:59.520] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:59.520] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:85:0) after 1m0s
[01:13:59.541] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:85:0)
[01:13:59.541] Timer tick, asking Buildbucket for the build status
[01:13:59.764] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:59.764] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:86:0) after 1m0s
[01:14:59.789] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:86:0)
[01:14:59.789] Timer tick, asking Buildbucket for the build status
[01:15:00.140] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:00.140] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:87:0) after 1m0s
[01:16:00.188] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:87:0)
[01:16:00.188] Timer tick, asking Buildbucket for the build status
[01:16:00.489] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:00.489] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:88:0) after 1m0s
[01:17:00.540] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:88:0)
[01:17:00.540] Timer tick, asking Buildbucket for the build status
[01:17:00.760] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:17:00.760] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:89:0) after 1m0s
[01:18:00.785] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:89:0)
[01:18:00.785] Timer tick, asking Buildbucket for the build status
[01:18:01.036] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:18:01.036] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:90:0) after 1m0s
[01:19:01.056] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:90:0)
[01:19:01.056] Timer tick, asking Buildbucket for the build status
[01:19:01.279] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:19:01.279] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:91:0) after 1m0s
[01:20:01.300] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:91:0)
[01:20:01.300] Timer tick, asking Buildbucket for the build status
[01:20:01.825] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:20:01.825] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:92:0) after 1m0s
[01:21:01.905] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:92:0)
[01:21:01.905] Timer tick, asking Buildbucket for the build status
[01:21:02.154] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:21:02.154] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:93:0) after 1m0s
[01:22:02.191] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:93:0)
[01:22:02.191] Timer tick, asking Buildbucket for the build status
[01:22:02.503] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:22:02.503] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:94:0) after 1m0s
[01:23:02.555] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:94:0)
[01:23:02.555] Timer tick, asking Buildbucket for the build status
[01:23:03.053] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:03.053] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:95:0) after 1m0s
[01:24:03.162] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:95:0)
[01:24:03.162] Timer tick, asking Buildbucket for the build status
[01:24:03.435] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:24:03.435] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:96:0) after 1m0s
[01:25:03.456] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:96:0)
[01:25:03.456] Timer tick, asking Buildbucket for the build status
[01:25:03.677] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:25:03.677] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:97:0) after 1m0s
[01:26:03.785] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:97:0)
[01:26:03.785] Timer tick, asking Buildbucket for the build status
[01:26:04.133] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:26:04.133] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:98:0) after 1m0s
[01:27:04.239] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:98:0)
[01:27:04.239] Timer tick, asking Buildbucket for the build status
[01:27:04.569] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:27:04.569] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:99:0) after 1m0s
[01:28:04.604] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:99:0)
[01:28:04.604] Timer tick, asking Buildbucket for the build status
[01:28:04.915] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:28:04.915] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:100:0) after 1m0s
[01:29:04.938] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:100:0)
[01:29:04.938] Timer tick, asking Buildbucket for the build status
[01:29:05.150] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:29:05.150] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:101:0) after 1m0s
[01:30:05.169] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:101:0)
[01:30:05.169] Timer tick, asking Buildbucket for the build status
[01:30:05.388] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:30:05.388] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:102:0) after 1m0s
[01:31:05.490] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:102:0)
[01:31:05.490] Timer tick, asking Buildbucket for the build status
[01:31:05.785] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:31:05.785] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:103:0) after 1m0s
[01:32:05.850] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:103:0)
[01:32:05.850] Timer tick, asking Buildbucket for the build status
[01:32:06.107] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:32:06.107] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:104:0) after 1m0s
[01:33:06.191] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:104:0)
[01:33:06.191] Timer tick, asking Buildbucket for the build status
[01:33:06.544] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:33:06.544] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:105:0) after 1m0s
[01:34:06.591] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:105:0)
[01:34:06.591] Timer tick, asking Buildbucket for the build status
[01:34:06.819] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:34:06.819] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:106:0) after 1m0s
[01:35:06.843] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:106:0)
[01:35:06.843] Timer tick, asking Buildbucket for the build status
[01:35:07.101] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:35:07.101] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:107:0) after 1m0s
[01:36:07.127] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:107:0)
[01:36:07.127] Timer tick, asking Buildbucket for the build status
[01:36:07.385] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:36:07.385] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:108:0) after 1m0s
[01:37:07.499] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:108:0)
[01:37:07.499] Timer tick, asking Buildbucket for the build status
[01:37:07.805] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:37:07.805] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:109:0) after 1m0s
[01:38:08.027] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:109:0)
[01:38:08.027] Timer tick, asking Buildbucket for the build status
[01:38:08.400] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:38:08.400] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:110:0) after 1m0s
[01:39:08.525] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:110:0)
[01:39:08.525] Timer tick, asking Buildbucket for the build status
[01:39:08.619] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:39:08.619] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:111:0) after 1m0s
[01:40:08.640] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:111:0)
[01:40:08.640] Timer tick, asking Buildbucket for the build status
[01:40:08.893] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:40:08.893] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:112:0) after 1m0s
[01:41:08.951] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:112:0)
[01:41:08.951] Timer tick, asking Buildbucket for the build status
[01:41:09.025] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:41:09.025] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:113:0) after 1m0s
[01:42:09.057] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:113:0)
[01:42:09.057] Timer tick, asking Buildbucket for the build status
[01:42:09.288] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:42:09.288] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:114:0) after 1m0s
[01:43:09.410] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:114:0)
[01:43:09.410] Timer tick, asking Buildbucket for the build status
[01:43:09.578] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:43:09.578] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:115:0) after 1m0s
[01:44:09.612] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:115:0)
[01:44:09.612] Timer tick, asking Buildbucket for the build status
[01:44:09.689] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:44:09.689] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:116:0) after 1m0s
[01:45:09.778] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:116:0)
[01:45:09.778] Timer tick, asking Buildbucket for the build status
[01:45:09.846] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:45:09.846] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:117:0) after 1m0s
[01:46:09.891] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:117:0)
[01:46:09.891] Timer tick, asking Buildbucket for the build status
[01:46:09.971] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:46:09.971] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:118:0) after 1m0s
[01:47:10.047] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:118:0)
[01:47:10.047] Timer tick, asking Buildbucket for the build status
[01:47:10.197] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:47:10.197] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:119:0) after 1m0s
[01:48:10.220] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:119:0)
[01:48:10.220] Timer tick, asking Buildbucket for the build status
[01:48:10.292] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:48:10.292] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:120:0) after 1m0s
[01:49:10.313] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:120:0)
[01:49:10.313] Timer tick, asking Buildbucket for the build status
[01:49:10.421] Build 8853737060539526560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:49:10.421] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:121:0) after 1m0s
[01:49:55.699] Received PubSub notification, asking Buildbucket for the build status
[01:49:55.779] Build 8853737060539526560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:10.451] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:121:0)
[01:50:10.451] Timer tick, asking Buildbucket for the build status
[01:50:10.520] Build 8853737060539526560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:10.520] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:123:0) after 1m0s
[01:51:10.580] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:123:0)
[01:51:10.580] Timer tick, asking Buildbucket for the build status
[01:51:10.660] Build 8853737060539526560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:10.660] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:124:0) after 1m0s
[01:52:10.753] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:124:0)
[01:52:10.753] Timer tick, asking Buildbucket for the build status
[01:52:10.902] Build 8853737060539526560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:10.902] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:125:0) after 1m0s
[01:53:10.981] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:125:0)
[01:53:10.981] Timer tick, asking Buildbucket for the build status
[01:53:11.075] Build 8853737060539526560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:11.075] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:126:0) after 1m0s
[01:54:11.155] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:126:0)
[01:54:11.155] Timer tick, asking Buildbucket for the build status
[01:54:11.241] Build 8853737060539526560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:11.241] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019167122511927600:127:0) after 1m0s
[01:54:13.501] Received PubSub notification, asking Buildbucket for the build status
[01:54:13.586] Build 8853737060539526560: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:54:13.586] Buildbucket build:
{
"build": {
"bucket": "luci.v8.ci",
"canary_preference": "PROD",
"completed_ts": "1614822851978868",
"created_by": "project:v8",
"created_ts": "1614815383357786",
"id": "8853737060539526560",
"parameters_json": "{\"builder_name\": \"V8 Win64 - dev image\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler-dev.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"d2948ce943dab04699988d8516eddcba8035e49e\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@d2948ce943dab04699988d8516eddcba8035e49e\", \"title\": \"d2948ce943dab04699988d8516eddcba8035e49e\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/d2948ce943dab04699988d8516eddcba8035e49e\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"d2948ce943dab04699988d8516eddcba8035e49e\"}}",
"project": "v8",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver-dev.appspot.com/\"}, \"$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}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler-dev.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"d2948ce943dab04699988d8516eddcba8035e49e\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@d2948ce943dab04699988d8516eddcba8035e49e\", \"title\": \"d2948ce943dab04699988d8516eddcba8035e49e\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/d2948ce943dab04699988d8516eddcba8035e49e\"}]}, \"bot_id\": \"dev-luci-chromium-ci-win10-0-ayif\", \"branch\": \"refs/heads/master\", \"builder_group\": \"client.v8\", \"buildername\": \"V8 Win64 - dev image\", \"buildnumber\": 658, \"got_revision\": \"d2948ce943dab04699988d8516eddcba8035e49e\", \"got_revision_cp\": \"refs/heads/master@{#73177}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"path_config\": \"generic\", \"recipe\": \"v8\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"d2948ce943dab04699988d8516eddcba8035e49e\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_044445a3bc7a4bfe795493d7956aa02cd526172de1fa7300f69fe152e82ea631_v2\", \"builder_5de1032f0e9ef497433aad28840ee258c037976023b410f653133cee0ad04e2c_v2\", \"builder_82ed111fed7ea8169e482f00b5f3e5338a65daa41208585de67cb0a736b1d538_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_v2\", \"builder_d38bdc976f893fc6930479dce8a6d7dc793b2a74fe1392d4af00713a5c0bb46e_v2\", \"git\", \"goma\", \"infra_gclient_with_go\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"dev-luci-chromium-ci-win10-0-ayif\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5734-f765927\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "v8-ci-builder-dev@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614822594036336",
"status": "COMPLETED",
"status_changed_ts": "1614822852238149",
"tags": [
"build_address:luci.v8.ci/V8 Win64 - dev image/658",
"builder:V8 Win64 - dev image",
"buildset:commit/git/d2948ce943dab04699988d8516eddcba8035e49e",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/d2948ce943dab04699988d8516eddcba8035e49e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019167122511927600",
"scheduler_job_id:v8/V8 Win64 - dev image",
"swarming_hostname:chromium-swarm-dev.appspot.com",
"swarming_tag:log_location:logdog://luci-logdog-dev.appspot.com/v8/buildbucket/cr-buildbucket-dev.appspot.com/8853737060539526560/+/annotations",
"swarming_tag:luci_project:v8",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:v8",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:521350da88d25c10",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1614822852249485",
"url": "https://luci-milo-dev.appspot.com/b/8853737060539526560",
"utcnow_ts": "1614822853579569"
}
}
[01:54:13.586] Invocation finished in 2h4m31.495212393s with status SUCCEEDED