[09:05:16.337] New invocation is queued and will start shortly
[09:05:17.480] Starting the invocation (attempt 1)
[09:05:17.534] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[09:05:17.534] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[09:05:17.534] Buildbucket request:
{
"bucket": "luci.v8.ci",
"client_operation_id": "9022846644701773744",
"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\":\"3f1177c5a05f30ae2a6aa630468a274dcd4fce98\"},\"id\":\"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@3f1177c5a05f30ae2a6aa630468a274dcd4fce98\",\"title\":\"3f1177c5a05f30ae2a6aa630468a274dcd4fce98\",\"url\":\"https://chromium.googlesource.com/v8/v8/+/3f1177c5a05f30ae2a6aa630468a274dcd4fce98\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"3f1177c5a05f30ae2a6aa630468a274dcd4fce98\"}}",
"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:9022846644701773744",
"scheduler_job_id:v8/V8 Win64 - dev image",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/3f1177c5a05f30ae2a6aa630468a274dcd4fce98",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/3f1177c5a05f30ae2a6aa630468a274dcd4fce98",
"gitiles_ref:refs/heads/master"
]
}
[09:05:18.315] Buildbucket response:
{
"build": {
"bucket": "luci.v8.ci",
"canary_preference": "PROD",
"created_by": "project:v8",
"created_ts": "1611306317635785",
"id": "8857416582637460080",
"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\": \"3f1177c5a05f30ae2a6aa630468a274dcd4fce98\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@3f1177c5a05f30ae2a6aa630468a274dcd4fce98\", \"title\": \"3f1177c5a05f30ae2a6aa630468a274dcd4fce98\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/3f1177c5a05f30ae2a6aa630468a274dcd4fce98\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"3f1177c5a05f30ae2a6aa630468a274dcd4fce98\"}}",
"project": "v8",
"result_details_json": "{\"properties\": {}}",
"service_account": "v8-ci-builder-dev@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611306318133610",
"tags": [
"build_address:luci.v8.ci/V8 Win64 - dev image/295",
"builder:V8 Win64 - dev image",
"buildset:commit/git/3f1177c5a05f30ae2a6aa630468a274dcd4fce98",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/3f1177c5a05f30ae2a6aa630468a274dcd4fce98",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022846644701773744",
"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/8857416582637460080/+/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": "1611306318133729",
"url": "https://luci-milo-dev.appspot.com/b/8857416582637460080",
"utcnow_ts": "1611306318306297"
}
}
[09:05:18.315] Task URL: https://luci-milo-dev.appspot.com/b/8857416582637460080
[09:05:18.315] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:2:0) after 1m0s
[09:06:18.365] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:2:0)
[09:06:18.365] Timer tick, asking Buildbucket for the build status
[09:06:18.533] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:06:18.533] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:3:0) after 1m0s
[09:07:18.559] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:3:0)
[09:07:18.559] Timer tick, asking Buildbucket for the build status
[09:07:18.702] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:07:18.702] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:4:0) after 1m0s
[09:08:18.758] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:4:0)
[09:08:18.758] Timer tick, asking Buildbucket for the build status
[09:08:18.857] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:08:18.857] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:5:0) after 1m0s
[09:09:18.971] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:5:0)
[09:09:18.971] Timer tick, asking Buildbucket for the build status
[09:09:19.084] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:09:19.084] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:6:0) after 1m0s
[09:10:19.158] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:6:0)
[09:10:19.158] Timer tick, asking Buildbucket for the build status
[09:10:19.284] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:10:19.284] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:7:0) after 1m0s
[09:11:19.359] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:7:0)
[09:11:19.359] Timer tick, asking Buildbucket for the build status
[09:11:19.484] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:11:19.484] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:8:0) after 1m0s
[09:12:19.558] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:8:0)
[09:12:19.558] Timer tick, asking Buildbucket for the build status
[09:12:19.657] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:12:19.657] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:9:0) after 1m0s
[09:13:19.779] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:9:0)
[09:13:19.779] Timer tick, asking Buildbucket for the build status
[09:13:19.861] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:13:19.861] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:10:0) after 1m0s
[09:14:19.885] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:10:0)
[09:14:19.885] Timer tick, asking Buildbucket for the build status
[09:14:20.037] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:14:20.037] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:11:0) after 1m0s
[09:15:20.077] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:11:0)
[09:15:20.077] Timer tick, asking Buildbucket for the build status
[09:15:20.173] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:15:20.173] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:12:0) after 1m0s
[09:16:20.277] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:12:0)
[09:16:20.277] Timer tick, asking Buildbucket for the build status
[09:16:20.419] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:16:20.419] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:13:0) after 1m0s
[09:17:20.466] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:13:0)
[09:17:20.466] Timer tick, asking Buildbucket for the build status
[09:17:20.626] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:17:20.626] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:14:0) after 1m0s
[09:18:20.652] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:14:0)
[09:18:20.652] Timer tick, asking Buildbucket for the build status
[09:18:20.754] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:18:20.754] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:15:0) after 1m0s
[09:19:20.791] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:15:0)
[09:19:20.791] Timer tick, asking Buildbucket for the build status
[09:19:20.895] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:19:20.895] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:16:0) after 1m0s
[09:20:20.954] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:16:0)
[09:20:20.954] Timer tick, asking Buildbucket for the build status
[09:20:21.078] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:20:21.078] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:17:0) after 1m0s
[09:21:21.104] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:17:0)
[09:21:21.104] Timer tick, asking Buildbucket for the build status
[09:21:21.209] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:21:21.209] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:18:0) after 1m0s
[09:22:21.266] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:18:0)
[09:22:21.266] Timer tick, asking Buildbucket for the build status
[09:22:21.364] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:22:21.364] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:19:0) after 1m0s
[09:23:21.470] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:19:0)
[09:23:21.470] Timer tick, asking Buildbucket for the build status
[09:23:21.624] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:23:21.624] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:20:0) after 1m0s
[09:24:21.658] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:20:0)
[09:24:21.658] Timer tick, asking Buildbucket for the build status
[09:24:21.800] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:24:21.801] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:21:0) after 1m0s
[09:25:21.885] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:21:0)
[09:25:21.885] Timer tick, asking Buildbucket for the build status
[09:25:22.022] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:25:22.022] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:22:0) after 1m0s
[09:26:22.069] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:22:0)
[09:26:22.069] Timer tick, asking Buildbucket for the build status
[09:26:22.162] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:26:22.162] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:23:0) after 1m0s
[09:27:22.271] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:23:0)
[09:27:22.271] Timer tick, asking Buildbucket for the build status
[09:27:22.379] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:27:22.379] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:24:0) after 1m0s
[09:28:22.457] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:24:0)
[09:28:22.457] Timer tick, asking Buildbucket for the build status
[09:28:22.573] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:28:22.573] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:25:0) after 1m0s
[09:29:22.660] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:25:0)
[09:29:22.660] Timer tick, asking Buildbucket for the build status
[09:29:22.765] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:29:22.765] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:26:0) after 1m0s
[09:30:22.795] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:26:0)
[09:30:22.795] Timer tick, asking Buildbucket for the build status
[09:30:22.887] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:30:22.888] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:27:0) after 1m0s
[09:31:22.992] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:27:0)
[09:31:22.992] Timer tick, asking Buildbucket for the build status
[09:31:23.173] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:31:23.173] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:28:0) after 1m0s
[09:32:23.259] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:28:0)
[09:32:23.259] Timer tick, asking Buildbucket for the build status
[09:32:23.421] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:32:23.421] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:29:0) after 1m0s
[09:33:23.468] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:29:0)
[09:33:23.468] Timer tick, asking Buildbucket for the build status
[09:33:23.557] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:33:23.557] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:30:0) after 1m0s
[09:34:23.670] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:30:0)
[09:34:23.670] Timer tick, asking Buildbucket for the build status
[09:34:23.758] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:34:23.758] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:31:0) after 1m0s
[09:35:23.798] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:31:0)
[09:35:23.798] Timer tick, asking Buildbucket for the build status
[09:35:23.896] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:35:23.896] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:32:0) after 1m0s
[09:36:23.982] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:32:0)
[09:36:23.982] Timer tick, asking Buildbucket for the build status
[09:36:24.118] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:36:24.118] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:33:0) after 1m0s
[09:37:24.159] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:33:0)
[09:37:24.159] Timer tick, asking Buildbucket for the build status
[09:37:24.263] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:37:24.263] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:34:0) after 1m0s
[09:38:24.284] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:34:0)
[09:38:24.284] Timer tick, asking Buildbucket for the build status
[09:38:24.379] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:38:24.379] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:35:0) after 1m0s
[09:39:24.457] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:35:0)
[09:39:24.457] Timer tick, asking Buildbucket for the build status
[09:39:24.557] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:39:24.557] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:36:0) after 1m0s
[09:40:24.671] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:36:0)
[09:40:24.671] Timer tick, asking Buildbucket for the build status
[09:40:24.815] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:40:24.815] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:37:0) after 1m0s
[09:41:24.869] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:37:0)
[09:41:24.870] Timer tick, asking Buildbucket for the build status
[09:41:24.966] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:41:24.966] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:38:0) after 1m0s
[09:42:25.061] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:38:0)
[09:42:25.061] Timer tick, asking Buildbucket for the build status
[09:42:25.269] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:42:25.269] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:39:0) after 1m0s
[09:43:25.379] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:39:0)
[09:43:25.379] Timer tick, asking Buildbucket for the build status
[09:43:25.508] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:43:25.508] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:40:0) after 1m0s
[09:44:25.571] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:40:0)
[09:44:25.572] Timer tick, asking Buildbucket for the build status
[09:44:25.677] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:44:25.677] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:41:0) after 1m0s
[09:45:25.700] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:41:0)
[09:45:25.700] Timer tick, asking Buildbucket for the build status
[09:45:25.818] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:45:25.818] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:42:0) after 1m0s
[09:46:25.869] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:42:0)
[09:46:25.869] Timer tick, asking Buildbucket for the build status
[09:46:25.988] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:46:25.988] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:43:0) after 1m0s
[09:47:26.074] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:43:0)
[09:47:26.074] Timer tick, asking Buildbucket for the build status
[09:47:26.250] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:47:26.250] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:44:0) after 1m0s
[09:48:26.276] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:44:0)
[09:48:26.276] Timer tick, asking Buildbucket for the build status
[09:48:26.405] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:48:26.405] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:45:0) after 1m0s
[09:49:26.429] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:45:0)
[09:49:26.429] Timer tick, asking Buildbucket for the build status
[09:49:26.539] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:49:26.539] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:46:0) after 1m0s
[09:50:26.589] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:46:0)
[09:50:26.589] Timer tick, asking Buildbucket for the build status
[09:50:26.749] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:50:26.749] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:47:0) after 1m0s
[09:51:26.860] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:47:0)
[09:51:26.860] Timer tick, asking Buildbucket for the build status
[09:51:27.034] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:51:27.034] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:48:0) after 1m0s
[09:52:27.075] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:48:0)
[09:52:27.075] Timer tick, asking Buildbucket for the build status
[09:52:27.182] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:52:27.182] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:49:0) after 1m0s
[09:53:27.211] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:49:0)
[09:53:27.211] Timer tick, asking Buildbucket for the build status
[09:53:27.330] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:53:27.330] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:50:0) after 1m0s
[09:54:27.369] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:50:0)
[09:54:27.369] Timer tick, asking Buildbucket for the build status
[09:54:27.466] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:54:27.466] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:51:0) after 1m0s
[09:55:27.590] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:51:0)
[09:55:27.590] Timer tick, asking Buildbucket for the build status
[09:55:27.699] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:55:27.699] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:52:0) after 1m0s
[09:56:27.771] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:52:0)
[09:56:27.771] Timer tick, asking Buildbucket for the build status
[09:56:27.991] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:56:27.991] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:53:0) after 1m0s
[09:57:28.023] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:53:0)
[09:57:28.023] Timer tick, asking Buildbucket for the build status
[09:57:28.260] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:57:28.260] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:54:0) after 1m0s
[09:58:28.378] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:54:0)
[09:58:28.378] Timer tick, asking Buildbucket for the build status
[09:58:28.678] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:58:28.678] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:55:0) after 1m0s
[09:59:28.702] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:55:0)
[09:59:28.702] Timer tick, asking Buildbucket for the build status
[09:59:29.002] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:59:29.002] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:56:0) after 1m0s
[10:00:29.060] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:56:0)
[10:00:29.060] Timer tick, asking Buildbucket for the build status
[10:00:29.711] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:00:29.711] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:57:0) after 1m0s
[10:01:29.793] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:57:0)
[10:01:29.793] Timer tick, asking Buildbucket for the build status
[10:01:30.196] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:01:30.196] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:58:0) after 1m0s
[10:02:30.276] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:58:0)
[10:02:30.276] Timer tick, asking Buildbucket for the build status
[10:02:30.576] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:02:30.576] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:59:0) after 1m0s
[10:03:30.671] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:59:0)
[10:03:30.671] Timer tick, asking Buildbucket for the build status
[10:03:30.919] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:03:30.919] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:60:0) after 1m0s
[10:04:30.960] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:60:0)
[10:04:30.960] Timer tick, asking Buildbucket for the build status
[10:04:31.166] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:04:31.166] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:61:0) after 1m0s
[10:05:31.216] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:61:0)
[10:05:31.216] Timer tick, asking Buildbucket for the build status
[10:05:31.454] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:05:31.454] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:62:0) after 1m0s
[10:06:31.572] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:62:0)
[10:06:31.573] Timer tick, asking Buildbucket for the build status
[10:06:31.820] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:06:31.820] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:63:0) after 1m0s
[10:07:31.861] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:63:0)
[10:07:31.861] Timer tick, asking Buildbucket for the build status
[10:07:32.104] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:07:32.104] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:64:0) after 1m0s
[10:08:32.171] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:64:0)
[10:08:32.171] Timer tick, asking Buildbucket for the build status
[10:08:32.402] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:08:32.402] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:65:0) after 1m0s
[10:09:32.477] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:65:0)
[10:09:32.477] Timer tick, asking Buildbucket for the build status
[10:09:32.744] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:09:32.744] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:66:0) after 1m0s
[10:10:32.772] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:66:0)
[10:10:32.772] Timer tick, asking Buildbucket for the build status
[10:10:33.068] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:10:33.068] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:67:0) after 1m0s
[10:11:33.178] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:67:0)
[10:11:33.178] Timer tick, asking Buildbucket for the build status
[10:11:33.633] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:11:33.633] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:68:0) after 1m0s
[10:12:33.769] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:68:0)
[10:12:33.769] Timer tick, asking Buildbucket for the build status
[10:12:33.929] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:12:33.929] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:69:0) after 1m0s
[10:13:33.960] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:69:0)
[10:13:33.960] Timer tick, asking Buildbucket for the build status
[10:13:34.215] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:13:34.215] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:70:0) after 1m0s
[10:14:34.299] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:70:0)
[10:14:34.299] Timer tick, asking Buildbucket for the build status
[10:14:34.491] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:14:34.491] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:71:0) after 1m0s
[10:15:34.533] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:71:0)
[10:15:34.533] Timer tick, asking Buildbucket for the build status
[10:15:35.489] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:15:35.489] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:72:0) after 1m0s
[10:16:35.544] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:72:0)
[10:16:35.544] Timer tick, asking Buildbucket for the build status
[10:16:35.653] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:16:35.653] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:73:0) after 1m0s
[10:17:35.775] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:73:0)
[10:17:35.775] Timer tick, asking Buildbucket for the build status
[10:17:36.145] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:17:36.145] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:74:0) after 1m0s
[10:18:36.176] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:74:0)
[10:18:36.176] Timer tick, asking Buildbucket for the build status
[10:18:36.317] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:18:36.317] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:75:0) after 1m0s
[10:19:36.357] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:75:0)
[10:19:36.357] Timer tick, asking Buildbucket for the build status
[10:19:36.571] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:19:36.571] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:76:0) after 1m0s
[10:20:36.680] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:76:0)
[10:20:36.680] Timer tick, asking Buildbucket for the build status
[10:20:36.924] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:20:36.924] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:77:0) after 1m0s
[10:21:37.002] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:77:0)
[10:21:37.002] Timer tick, asking Buildbucket for the build status
[10:21:37.239] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:21:37.239] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:78:0) after 1m0s
[10:22:37.286] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:78:0)
[10:22:37.286] Timer tick, asking Buildbucket for the build status
[10:22:37.425] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:22:37.425] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:79:0) after 1m0s
[10:23:37.461] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:79:0)
[10:23:37.461] Timer tick, asking Buildbucket for the build status
[10:23:37.595] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:23:37.595] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:80:0) after 1m0s
[10:24:37.661] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:80:0)
[10:24:37.661] Timer tick, asking Buildbucket for the build status
[10:24:37.798] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:24:37.798] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:81:0) after 1m0s
[10:25:37.863] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:81:0)
[10:25:37.863] Timer tick, asking Buildbucket for the build status
[10:25:38.010] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:25:38.010] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:82:0) after 1m0s
[10:26:38.091] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:82:0)
[10:26:38.091] Timer tick, asking Buildbucket for the build status
[10:26:38.208] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:26:38.208] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:83:0) after 1m0s
[10:27:38.233] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:83:0)
[10:27:38.233] Timer tick, asking Buildbucket for the build status
[10:27:38.563] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:27:38.563] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:84:0) after 1m0s
[10:28:38.673] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:84:0)
[10:28:38.673] Timer tick, asking Buildbucket for the build status
[10:28:38.814] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:28:38.814] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:85:0) after 1m0s
[10:29:38.872] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:85:0)
[10:29:38.872] Timer tick, asking Buildbucket for the build status
[10:29:39.027] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:29:39.028] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:86:0) after 1m0s
[10:30:39.072] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:86:0)
[10:30:39.072] Timer tick, asking Buildbucket for the build status
[10:30:39.211] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:30:39.211] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:87:0) after 1m0s
[10:31:39.269] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:87:0)
[10:31:39.269] Timer tick, asking Buildbucket for the build status
[10:31:39.464] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:31:39.464] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:88:0) after 1m0s
[10:32:39.566] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:88:0)
[10:32:39.566] Timer tick, asking Buildbucket for the build status
[10:32:39.711] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:32:39.711] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:89:0) after 1m0s
[10:33:39.778] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:89:0)
[10:33:39.778] Timer tick, asking Buildbucket for the build status
[10:33:39.923] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:33:39.923] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:90:0) after 1m0s
[10:34:39.962] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:90:0)
[10:34:39.962] Timer tick, asking Buildbucket for the build status
[10:34:40.102] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:34:40.102] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:91:0) after 1m0s
[10:35:40.176] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:91:0)
[10:35:40.176] Timer tick, asking Buildbucket for the build status
[10:35:40.312] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:35:40.312] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:92:0) after 1m0s
[10:36:40.370] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:92:0)
[10:36:40.370] Timer tick, asking Buildbucket for the build status
[10:36:40.499] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:36:40.499] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:93:0) after 1m0s
[10:37:40.580] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:93:0)
[10:37:40.580] Timer tick, asking Buildbucket for the build status
[10:37:40.799] Build 8857416582637460080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:37:40.799] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:94:0) after 1m0s
[10:38:38.487] Received PubSub notification, asking Buildbucket for the build status
[10:38:38.632] Build 8857416582637460080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:40.881] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:94:0)
[10:38:40.881] Timer tick, asking Buildbucket for the build status
[10:38:41.002] Build 8857416582637460080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:41.002] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:96:0) after 1m0s
[10:39:41.086] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:96:0)
[10:39:41.086] Timer tick, asking Buildbucket for the build status
[10:39:41.228] Build 8857416582637460080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:41.228] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:97:0) after 1m0s
[10:40:41.277] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:97:0)
[10:40:41.277] Timer tick, asking Buildbucket for the build status
[10:40:41.462] Build 8857416582637460080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:41.462] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:98:0) after 1m0s
[10:41:41.563] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:98:0)
[10:41:41.563] Timer tick, asking Buildbucket for the build status
[10:41:42.774] Build 8857416582637460080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:42.774] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9022846644701773744:99:0) after 1m0s
[10:41:53.475] Received PubSub notification, asking Buildbucket for the build status
[10:41:53.590] Build 8857416582637460080: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:41:53.590] Buildbucket build:
{
"build": {
"bucket": "luci.v8.ci",
"canary_preference": "PROD",
"completed_ts": "1611312109808579",
"created_by": "project:v8",
"created_ts": "1611306317635785",
"id": "8857416582637460080",
"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\": \"3f1177c5a05f30ae2a6aa630468a274dcd4fce98\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@3f1177c5a05f30ae2a6aa630468a274dcd4fce98\", \"title\": \"3f1177c5a05f30ae2a6aa630468a274dcd4fce98\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/3f1177c5a05f30ae2a6aa630468a274dcd4fce98\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"3f1177c5a05f30ae2a6aa630468a274dcd4fce98\"}}",
"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\": \"3f1177c5a05f30ae2a6aa630468a274dcd4fce98\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@3f1177c5a05f30ae2a6aa630468a274dcd4fce98\", \"title\": \"3f1177c5a05f30ae2a6aa630468a274dcd4fce98\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/3f1177c5a05f30ae2a6aa630468a274dcd4fce98\"}]}, \"bot_id\": \"dev-luci-chromium-ci-win10-0-sa9m\", \"branch\": \"refs/heads/master\", \"builder_group\": \"client.v8\", \"buildername\": \"V8 Win64 - dev image\", \"buildnumber\": 295, \"got_revision\": \"3f1177c5a05f30ae2a6aa630468a274dcd4fce98\", \"got_revision_cp\": \"refs/heads/master@{#72245}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"path_config\": \"generic\", \"recipe\": \"v8\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"3f1177c5a05f30ae2a6aa630468a274dcd4fce98\"}, \"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-sa9m\"], \"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\": [\"5671-1d9dea8\"], \"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": "1611311910113767",
"status": "COMPLETED",
"status_changed_ts": "1611312110198459",
"tags": [
"build_address:luci.v8.ci/V8 Win64 - dev image/295",
"builder:V8 Win64 - dev image",
"buildset:commit/git/3f1177c5a05f30ae2a6aa630468a274dcd4fce98",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/3f1177c5a05f30ae2a6aa630468a274dcd4fce98",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022846644701773744",
"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/8857416582637460080/+/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:514228c43b449f10",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1611312110209731",
"url": "https://luci-milo-dev.appspot.com/b/8857416582637460080",
"utcnow_ts": "1611312113580373"
}
}
[10:41:53.590] Invocation finished in 1h36m37.306692538s with status SUCCEEDED