[14:28:20.097] New invocation is queued and will start shortly
[14:28:21.308] Starting the invocation (attempt 1)
[14:28:21.341] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[14:28:21.342] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[14:28:21.342] Buildbucket request:
{
"bucket": "luci.v8.ci",
"client_operation_id": "9019293037627534192",
"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\":\"eb976c3ef96484facecce291ebf5fd97ce34ccfb\"},\"id\":\"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@eb976c3ef96484facecce291ebf5fd97ce34ccfb\",\"title\":\"eb976c3ef96484facecce291ebf5fd97ce34ccfb\",\"url\":\"https://chromium.googlesource.com/v8/v8/+/eb976c3ef96484facecce291ebf5fd97ce34ccfb\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"eb976c3ef96484facecce291ebf5fd97ce34ccfb\"}}",
"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:9019293037627534192",
"scheduler_job_id:v8/V8 Win64 - dev image",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/eb976c3ef96484facecce291ebf5fd97ce34ccfb",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/eb976c3ef96484facecce291ebf5fd97ce34ccfb",
"gitiles_ref:refs/heads/master"
]
}
[14:28:21.988] Buildbucket response:
{
"build": {
"bucket": "luci.v8.ci",
"canary_preference": "PROD",
"created_by": "project:v8",
"created_ts": "1614695301472961",
"id": "8853862975521656960",
"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\": \"eb976c3ef96484facecce291ebf5fd97ce34ccfb\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@eb976c3ef96484facecce291ebf5fd97ce34ccfb\", \"title\": \"eb976c3ef96484facecce291ebf5fd97ce34ccfb\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/eb976c3ef96484facecce291ebf5fd97ce34ccfb\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"eb976c3ef96484facecce291ebf5fd97ce34ccfb\"}}",
"project": "v8",
"result_details_json": "{\"properties\": {}}",
"service_account": "v8-ci-builder-dev@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614695301874656",
"tags": [
"build_address:luci.v8.ci/V8 Win64 - dev image/640",
"builder:V8 Win64 - dev image",
"buildset:commit/git/eb976c3ef96484facecce291ebf5fd97ce34ccfb",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/eb976c3ef96484facecce291ebf5fd97ce34ccfb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019293037627534192",
"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/8853862975521656960/+/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": "1614695301874771",
"url": "https://luci-milo-dev.appspot.com/b/8853862975521656960",
"utcnow_ts": "1614695301981951"
}
}
[14:28:21.988] Task URL: https://luci-milo-dev.appspot.com/b/8853862975521656960
[14:28:21.988] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:2:0) after 1m0s
[14:29:22.046] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:2:0)
[14:29:22.046] Timer tick, asking Buildbucket for the build status
[14:29:22.142] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:29:22.142] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:3:0) after 1m0s
[14:30:22.160] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:3:0)
[14:30:22.160] Timer tick, asking Buildbucket for the build status
[14:30:22.328] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:30:22.328] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:4:0) after 1m0s
[14:31:22.353] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:4:0)
[14:31:22.353] Timer tick, asking Buildbucket for the build status
[14:31:22.434] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:31:22.434] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:5:0) after 1m0s
[14:32:22.454] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:5:0)
[14:32:22.455] Timer tick, asking Buildbucket for the build status
[14:32:22.522] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:32:22.522] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:6:0) after 1m0s
[14:33:22.546] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:6:0)
[14:33:22.546] Timer tick, asking Buildbucket for the build status
[14:33:22.629] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:33:22.629] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:7:0) after 1m0s
[14:34:22.708] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:7:0)
[14:34:22.708] Timer tick, asking Buildbucket for the build status
[14:34:22.795] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:34:22.795] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:8:0) after 1m0s
[14:35:22.857] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:8:0)
[14:35:22.857] Timer tick, asking Buildbucket for the build status
[14:35:22.943] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:35:22.943] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:9:0) after 1m0s
[14:36:22.962] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:9:0)
[14:36:22.962] Timer tick, asking Buildbucket for the build status
[14:36:23.045] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:36:23.045] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:10:0) after 1m0s
[14:37:23.153] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:10:0)
[14:37:23.153] Timer tick, asking Buildbucket for the build status
[14:37:23.237] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:37:23.237] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:11:0) after 1m0s
[14:38:23.310] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:11:0)
[14:38:23.310] Timer tick, asking Buildbucket for the build status
[14:38:23.392] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:38:23.392] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:12:0) after 1m0s
[14:39:23.408] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:12:0)
[14:39:23.408] Timer tick, asking Buildbucket for the build status
[14:39:23.493] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:39:23.493] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:13:0) after 1m0s
[14:40:23.557] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:13:0)
[14:40:23.557] Timer tick, asking Buildbucket for the build status
[14:40:23.632] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:40:23.632] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:14:0) after 1m0s
[14:41:23.701] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:14:0)
[14:41:23.701] Timer tick, asking Buildbucket for the build status
[14:41:23.780] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:41:23.780] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:15:0) after 1m0s
[14:42:23.861] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:15:0)
[14:42:23.861] Timer tick, asking Buildbucket for the build status
[14:42:23.938] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:42:23.938] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:16:0) after 1m0s
[14:43:24.002] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:16:0)
[14:43:24.002] Timer tick, asking Buildbucket for the build status
[14:43:24.106] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:43:24.106] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:17:0) after 1m0s
[14:44:24.202] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:17:0)
[14:44:24.202] Timer tick, asking Buildbucket for the build status
[14:44:24.286] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:44:24.286] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:18:0) after 1m0s
[14:45:24.304] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:18:0)
[14:45:24.304] Timer tick, asking Buildbucket for the build status
[14:45:24.407] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:45:24.407] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:19:0) after 1m0s
[14:46:24.458] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:19:0)
[14:46:24.458] Timer tick, asking Buildbucket for the build status
[14:46:24.550] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:46:24.550] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:20:0) after 1m0s
[14:47:24.567] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:20:0)
[14:47:24.567] Timer tick, asking Buildbucket for the build status
[14:47:24.638] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:47:24.638] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:21:0) after 1m0s
[14:48:24.713] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:21:0)
[14:48:24.713] Timer tick, asking Buildbucket for the build status
[14:48:24.798] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:48:24.798] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:22:0) after 1m0s
[14:49:24.854] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:22:0)
[14:49:24.854] Timer tick, asking Buildbucket for the build status
[14:49:24.924] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:49:24.924] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:23:0) after 1m0s
[14:50:24.957] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:23:0)
[14:50:24.957] Timer tick, asking Buildbucket for the build status
[14:50:25.064] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:50:25.064] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:24:0) after 1m0s
[14:51:25.117] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:24:0)
[14:51:25.117] Timer tick, asking Buildbucket for the build status
[14:51:25.193] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:51:25.193] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:25:0) after 1m0s
[14:52:25.212] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:25:0)
[14:52:25.212] Timer tick, asking Buildbucket for the build status
[14:52:25.307] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:52:25.307] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:26:0) after 1m0s
[14:53:25.328] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:26:0)
[14:53:25.328] Timer tick, asking Buildbucket for the build status
[14:53:25.404] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:53:25.405] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:27:0) after 1m0s
[14:54:25.453] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:27:0)
[14:54:25.453] Timer tick, asking Buildbucket for the build status
[14:54:25.537] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:54:25.537] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:28:0) after 1m0s
[14:55:25.603] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:28:0)
[14:55:25.603] Timer tick, asking Buildbucket for the build status
[14:55:25.686] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:55:25.686] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:29:0) after 1m0s
[14:56:25.713] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:29:0)
[14:56:25.713] Timer tick, asking Buildbucket for the build status
[14:56:25.786] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:56:25.786] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:30:0) after 1m0s
[14:57:25.813] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:30:0)
[14:57:25.813] Timer tick, asking Buildbucket for the build status
[14:57:25.889] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:57:25.889] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:31:0) after 1m0s
[14:58:25.912] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:31:0)
[14:58:25.912] Timer tick, asking Buildbucket for the build status
[14:58:25.996] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:58:25.996] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:32:0) after 1m0s
[14:59:26.116] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:32:0)
[14:59:26.116] Timer tick, asking Buildbucket for the build status
[14:59:26.183] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:59:26.183] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:33:0) after 1m0s
[15:00:26.207] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:33:0)
[15:00:26.207] Timer tick, asking Buildbucket for the build status
[15:00:26.298] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:00:26.298] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:34:0) after 1m0s
[15:01:26.356] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:34:0)
[15:01:26.356] Timer tick, asking Buildbucket for the build status
[15:01:26.445] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:01:26.445] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:35:0) after 1m0s
[15:02:26.476] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:35:0)
[15:02:26.476] Timer tick, asking Buildbucket for the build status
[15:02:26.560] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:02:26.560] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:36:0) after 1m0s
[15:03:26.614] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:36:0)
[15:03:26.614] Timer tick, asking Buildbucket for the build status
[15:03:26.692] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:03:26.692] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:37:0) after 1m0s
[15:04:26.728] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:37:0)
[15:04:26.728] Timer tick, asking Buildbucket for the build status
[15:04:27.000] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:04:27.000] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:38:0) after 1m0s
[15:05:27.052] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:38:0)
[15:05:27.052] Timer tick, asking Buildbucket for the build status
[15:05:27.129] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:05:27.129] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:39:0) after 1m0s
[15:06:27.250] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:39:0)
[15:06:27.250] Timer tick, asking Buildbucket for the build status
[15:06:27.336] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:06:27.336] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:40:0) after 1m0s
[15:07:27.428] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:40:0)
[15:07:27.428] Timer tick, asking Buildbucket for the build status
[15:07:27.523] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:07:27.524] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:41:0) after 1m0s
[15:08:27.721] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:41:0)
[15:08:27.721] Timer tick, asking Buildbucket for the build status
[15:08:27.793] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:08:27.793] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:42:0) after 1m0s
[15:09:27.819] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:42:0)
[15:09:27.819] Timer tick, asking Buildbucket for the build status
[15:09:27.908] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:09:27.908] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:43:0) after 1m0s
[15:10:27.968] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:43:0)
[15:10:27.968] Timer tick, asking Buildbucket for the build status
[15:10:28.046] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:10:28.046] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:44:0) after 1m0s
[15:11:28.106] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:44:0)
[15:11:28.106] Timer tick, asking Buildbucket for the build status
[15:11:28.442] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:11:28.442] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:45:0) after 1m0s
[15:12:28.548] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:45:0)
[15:12:28.548] Timer tick, asking Buildbucket for the build status
[15:12:28.621] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:12:28.621] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:46:0) after 1m0s
[15:13:28.716] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:46:0)
[15:13:28.716] Timer tick, asking Buildbucket for the build status
[15:13:28.797] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:13:28.797] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:47:0) after 1m0s
[15:14:28.905] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:47:0)
[15:14:28.905] Timer tick, asking Buildbucket for the build status
[15:14:28.989] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:14:28.989] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:48:0) after 1m0s
[15:15:29.066] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:48:0)
[15:15:29.066] Timer tick, asking Buildbucket for the build status
[15:15:29.153] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:15:29.153] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:49:0) after 1m0s
[15:16:29.172] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:49:0)
[15:16:29.172] Timer tick, asking Buildbucket for the build status
[15:16:29.243] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:16:29.243] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:50:0) after 1m0s
[15:17:29.312] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:50:0)
[15:17:29.312] Timer tick, asking Buildbucket for the build status
[15:17:29.406] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:17:29.406] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:51:0) after 1m0s
[15:18:29.447] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:51:0)
[15:18:29.447] Timer tick, asking Buildbucket for the build status
[15:18:29.576] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:18:29.576] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:52:0) after 1m0s
[15:19:29.594] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:52:0)
[15:19:29.594] Timer tick, asking Buildbucket for the build status
[15:19:29.679] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:19:29.679] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:53:0) after 1m0s
[15:20:29.756] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:53:0)
[15:20:29.756] Timer tick, asking Buildbucket for the build status
[15:20:29.823] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:20:29.823] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:54:0) after 1m0s
[15:21:29.914] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:54:0)
[15:21:29.914] Timer tick, asking Buildbucket for the build status
[15:21:29.991] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:21:29.991] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:55:0) after 1m0s
[15:22:30.056] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:55:0)
[15:22:30.056] Timer tick, asking Buildbucket for the build status
[15:22:30.206] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:22:30.206] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:56:0) after 1m0s
[15:23:30.312] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:56:0)
[15:23:30.312] Timer tick, asking Buildbucket for the build status
[15:23:30.397] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:23:30.397] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:57:0) after 1m0s
[15:24:30.531] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:57:0)
[15:24:30.531] Timer tick, asking Buildbucket for the build status
[15:24:30.640] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:24:30.640] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:58:0) after 1m0s
[15:25:30.761] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:58:0)
[15:25:30.761] Timer tick, asking Buildbucket for the build status
[15:25:30.912] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:25:30.912] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:59:0) after 1m0s
[15:26:31.058] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:59:0)
[15:26:31.058] Timer tick, asking Buildbucket for the build status
[15:26:31.137] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:26:31.137] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:60:0) after 1m0s
[15:27:31.249] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:60:0)
[15:27:31.249] Timer tick, asking Buildbucket for the build status
[15:27:31.321] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:27:31.321] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:61:0) after 1m0s
[15:28:31.373] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:61:0)
[15:28:31.373] Timer tick, asking Buildbucket for the build status
[15:28:31.462] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:28:31.462] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:62:0) after 1m0s
[15:29:31.518] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:62:0)
[15:29:31.518] Timer tick, asking Buildbucket for the build status
[15:29:31.611] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:29:31.611] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:63:0) after 1m0s
[15:30:31.660] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:63:0)
[15:30:31.660] Timer tick, asking Buildbucket for the build status
[15:30:31.792] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:30:31.792] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:64:0) after 1m0s
[15:31:31.814] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:64:0)
[15:31:31.814] Timer tick, asking Buildbucket for the build status
[15:31:31.908] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:31:31.908] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:65:0) after 1m0s
[15:32:31.948] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:65:0)
[15:32:31.948] Timer tick, asking Buildbucket for the build status
[15:32:32.073] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:32:32.073] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:66:0) after 1m0s
[15:33:32.152] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:66:0)
[15:33:32.152] Timer tick, asking Buildbucket for the build status
[15:33:32.257] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:33:32.257] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:67:0) after 1m0s
[15:34:32.347] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:67:0)
[15:34:32.347] Timer tick, asking Buildbucket for the build status
[15:34:32.420] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:34:32.420] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:68:0) after 1m0s
[15:35:32.442] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:68:0)
[15:35:32.442] Timer tick, asking Buildbucket for the build status
[15:35:32.574] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:35:32.574] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:69:0) after 1m0s
[15:36:32.603] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:69:0)
[15:36:32.603] Timer tick, asking Buildbucket for the build status
[15:36:32.690] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:36:32.690] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:70:0) after 1m0s
[15:37:32.717] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:70:0)
[15:37:32.717] Timer tick, asking Buildbucket for the build status
[15:37:32.806] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:37:32.806] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:71:0) after 1m0s
[15:38:32.905] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:71:0)
[15:38:32.905] Timer tick, asking Buildbucket for the build status
[15:38:32.988] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:38:32.988] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:72:0) after 1m0s
[15:39:33.011] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:72:0)
[15:39:33.011] Timer tick, asking Buildbucket for the build status
[15:39:33.103] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:39:33.103] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:73:0) after 1m0s
[15:40:33.125] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:73:0)
[15:40:33.125] Timer tick, asking Buildbucket for the build status
[15:40:33.200] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:40:33.200] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:74:0) after 1m0s
[15:41:33.304] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:74:0)
[15:41:33.304] Timer tick, asking Buildbucket for the build status
[15:41:33.593] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:41:33.593] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:75:0) after 1m0s
[15:42:33.649] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:75:0)
[15:42:33.649] Timer tick, asking Buildbucket for the build status
[15:42:33.801] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:42:33.801] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:76:0) after 1m0s
[15:43:33.876] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:76:0)
[15:43:33.876] Timer tick, asking Buildbucket for the build status
[15:43:33.966] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:43:33.966] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:77:0) after 1m0s
[15:44:34.004] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:77:0)
[15:44:34.004] Timer tick, asking Buildbucket for the build status
[15:44:34.077] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:44:34.077] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:78:0) after 1m0s
[15:45:34.127] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:78:0)
[15:45:34.127] Timer tick, asking Buildbucket for the build status
[15:45:34.226] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:45:34.226] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:79:0) after 1m0s
[15:46:34.252] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:79:0)
[15:46:34.252] Timer tick, asking Buildbucket for the build status
[15:46:34.356] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:46:34.356] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:80:0) after 1m0s
[15:47:34.413] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:80:0)
[15:47:34.413] Timer tick, asking Buildbucket for the build status
[15:47:34.525] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:47:34.525] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:81:0) after 1m0s
[15:48:34.579] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:81:0)
[15:48:34.579] Timer tick, asking Buildbucket for the build status
[15:48:34.706] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:48:34.706] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:82:0) after 1m0s
[15:49:34.810] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:82:0)
[15:49:34.810] Timer tick, asking Buildbucket for the build status
[15:49:34.896] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:49:34.896] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:83:0) after 1m0s
[15:50:34.918] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:83:0)
[15:50:34.918] Timer tick, asking Buildbucket for the build status
[15:50:35.006] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:50:35.006] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:84:0) after 1m0s
[15:51:35.033] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:84:0)
[15:51:35.033] Timer tick, asking Buildbucket for the build status
[15:51:35.105] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:51:35.105] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:85:0) after 1m0s
[15:52:35.131] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:85:0)
[15:52:35.131] Timer tick, asking Buildbucket for the build status
[15:52:35.286] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:52:35.286] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:86:0) after 1m0s
[15:53:35.312] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:86:0)
[15:53:35.312] Timer tick, asking Buildbucket for the build status
[15:53:35.406] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:53:35.406] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:87:0) after 1m0s
[15:54:35.504] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:87:0)
[15:54:35.504] Timer tick, asking Buildbucket for the build status
[15:54:35.576] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:54:35.576] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:88:0) after 1m0s
[15:55:35.613] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:88:0)
[15:55:35.613] Timer tick, asking Buildbucket for the build status
[15:55:35.680] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:55:35.680] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:89:0) after 1m0s
[15:56:35.751] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:89:0)
[15:56:35.751] Timer tick, asking Buildbucket for the build status
[15:56:35.822] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:56:35.822] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:90:0) after 1m0s
[15:57:35.905] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:90:0)
[15:57:35.905] Timer tick, asking Buildbucket for the build status
[15:57:35.982] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:57:35.982] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:91:0) after 1m0s
[15:58:36.002] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:91:0)
[15:58:36.002] Timer tick, asking Buildbucket for the build status
[15:58:36.068] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:58:36.068] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:92:0) after 1m0s
[15:59:36.114] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:92:0)
[15:59:36.114] Timer tick, asking Buildbucket for the build status
[15:59:36.181] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:59:36.181] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:93:0) after 1m0s
[16:00:36.205] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:93:0)
[16:00:36.205] Timer tick, asking Buildbucket for the build status
[16:00:36.298] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:00:36.298] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:94:0) after 1m0s
[16:01:36.430] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:94:0)
[16:01:36.430] Timer tick, asking Buildbucket for the build status
[16:01:36.525] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:01:36.525] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:95:0) after 1m0s
[16:02:36.558] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:95:0)
[16:02:36.558] Timer tick, asking Buildbucket for the build status
[16:02:36.635] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:02:36.635] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:96:0) after 1m0s
[16:03:36.713] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:96:0)
[16:03:36.713] Timer tick, asking Buildbucket for the build status
[16:03:36.792] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:03:36.792] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:97:0) after 1m0s
[16:04:36.904] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:97:0)
[16:04:36.904] Timer tick, asking Buildbucket for the build status
[16:04:36.977] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:04:36.977] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:98:0) after 1m0s
[16:05:37.015] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:98:0)
[16:05:37.015] Timer tick, asking Buildbucket for the build status
[16:05:37.126] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:05:37.126] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:99:0) after 1m0s
[16:06:37.152] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:99:0)
[16:06:37.152] Timer tick, asking Buildbucket for the build status
[16:06:37.234] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:06:37.234] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:100:0) after 1m0s
[16:07:37.306] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:100:0)
[16:07:37.306] Timer tick, asking Buildbucket for the build status
[16:07:37.397] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:07:37.397] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:101:0) after 1m0s
[16:08:37.421] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:101:0)
[16:08:37.421] Timer tick, asking Buildbucket for the build status
[16:08:37.733] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:08:37.733] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:102:0) after 1m0s
[16:09:37.803] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:102:0)
[16:09:37.803] Timer tick, asking Buildbucket for the build status
[16:09:37.888] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:09:37.888] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:103:0) after 1m0s
[16:10:37.962] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:103:0)
[16:10:37.962] Timer tick, asking Buildbucket for the build status
[16:10:38.048] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:10:38.048] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:104:0) after 1m0s
[16:11:38.109] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:104:0)
[16:11:38.109] Timer tick, asking Buildbucket for the build status
[16:11:38.205] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:11:38.205] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:105:0) after 1m0s
[16:12:38.234] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:105:0)
[16:12:38.234] Timer tick, asking Buildbucket for the build status
[16:12:38.314] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:12:38.314] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:106:0) after 1m0s
[16:13:38.348] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:106:0)
[16:13:38.348] Timer tick, asking Buildbucket for the build status
[16:13:38.422] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:13:38.422] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:107:0) after 1m0s
[16:14:38.521] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:107:0)
[16:14:38.521] Timer tick, asking Buildbucket for the build status
[16:14:38.618] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:14:38.618] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:108:0) after 1m0s
[16:15:38.646] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:108:0)
[16:15:38.646] Timer tick, asking Buildbucket for the build status
[16:15:38.753] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:15:38.753] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:109:0) after 1m0s
[16:16:38.850] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:109:0)
[16:16:38.850] Timer tick, asking Buildbucket for the build status
[16:16:38.924] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:16:38.924] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:110:0) after 1m0s
[16:17:38.947] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:110:0)
[16:17:38.947] Timer tick, asking Buildbucket for the build status
[16:17:39.033] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:17:39.033] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:111:0) after 1m0s
[16:18:39.062] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:111:0)
[16:18:39.062] Timer tick, asking Buildbucket for the build status
[16:18:39.130] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:18:39.130] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:112:0) after 1m0s
[16:19:39.213] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:112:0)
[16:19:39.213] Timer tick, asking Buildbucket for the build status
[16:19:39.289] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:19:39.289] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:113:0) after 1m0s
[16:20:39.314] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:113:0)
[16:20:39.314] Timer tick, asking Buildbucket for the build status
[16:20:39.397] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:20:39.397] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:114:0) after 1m0s
[16:21:39.422] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:114:0)
[16:21:39.422] Timer tick, asking Buildbucket for the build status
[16:21:39.502] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:21:39.502] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:115:0) after 1m0s
[16:22:39.558] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:115:0)
[16:22:39.558] Timer tick, asking Buildbucket for the build status
[16:22:39.635] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:22:39.635] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:116:0) after 1m0s
[16:23:39.657] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:116:0)
[16:23:39.657] Timer tick, asking Buildbucket for the build status
[16:23:39.739] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:23:39.739] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:117:0) after 1m0s
[16:24:39.865] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:117:0)
[16:24:39.865] Timer tick, asking Buildbucket for the build status
[16:24:40.021] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:24:40.021] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:118:0) after 1m0s
[16:25:40.115] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:118:0)
[16:25:40.115] Timer tick, asking Buildbucket for the build status
[16:25:40.194] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:25:40.194] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:119:0) after 1m0s
[16:26:40.260] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:119:0)
[16:26:40.260] Timer tick, asking Buildbucket for the build status
[16:26:40.346] Build 8853862975521656960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:26:40.346] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:120:0) after 1m0s
[16:26:58.316] Received PubSub notification, asking Buildbucket for the build status
[16:26:58.385] Build 8853862975521656960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:40.448] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:120:0)
[16:27:40.448] Timer tick, asking Buildbucket for the build status
[16:27:40.524] Build 8853862975521656960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:40.524] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:122:0) after 1m0s
[16:28:40.605] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:122:0)
[16:28:40.605] Timer tick, asking Buildbucket for the build status
[16:28:40.710] Build 8853862975521656960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:40.710] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:123:0) after 1m0s
[16:29:40.732] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:123:0)
[16:29:40.732] Timer tick, asking Buildbucket for the build status
[16:29:40.801] Build 8853862975521656960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:40.801] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:124:0) after 1m0s
[16:30:40.913] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:124:0)
[16:30:40.913] Timer tick, asking Buildbucket for the build status
[16:30:40.982] Build 8853862975521656960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:40.982] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:125:0) after 1m0s
[16:31:41.058] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:125:0)
[16:31:41.058] Timer tick, asking Buildbucket for the build status
[16:31:41.158] Build 8853862975521656960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:41.158] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:126:0) after 1m0s
[16:32:41.266] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:126:0)
[16:32:41.266] Timer tick, asking Buildbucket for the build status
[16:32:41.353] Build 8853862975521656960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:41.353] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019293037627534192:127:0) after 1m0s
[16:33:00.410] Received PubSub notification, asking Buildbucket for the build status
[16:33:00.488] Build 8853862975521656960: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:33:00.488] Buildbucket build:
{
"build": {
"bucket": "luci.v8.ci",
"canary_preference": "PROD",
"completed_ts": "1614702777676398",
"created_by": "project:v8",
"created_ts": "1614695301472961",
"id": "8853862975521656960",
"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\": \"eb976c3ef96484facecce291ebf5fd97ce34ccfb\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@eb976c3ef96484facecce291ebf5fd97ce34ccfb\", \"title\": \"eb976c3ef96484facecce291ebf5fd97ce34ccfb\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/eb976c3ef96484facecce291ebf5fd97ce34ccfb\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"eb976c3ef96484facecce291ebf5fd97ce34ccfb\"}}",
"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\": \"eb976c3ef96484facecce291ebf5fd97ce34ccfb\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@eb976c3ef96484facecce291ebf5fd97ce34ccfb\", \"title\": \"eb976c3ef96484facecce291ebf5fd97ce34ccfb\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/eb976c3ef96484facecce291ebf5fd97ce34ccfb\"}]}, \"bot_id\": \"dev-luci-chromium-ci-win10-0-p3ju\", \"branch\": \"refs/heads/master\", \"builder_group\": \"client.v8\", \"buildername\": \"V8 Win64 - dev image\", \"buildnumber\": 640, \"got_revision\": \"eb976c3ef96484facecce291ebf5fd97ce34ccfb\", \"got_revision_cp\": \"refs/heads/master@{#73126}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"path_config\": \"generic\", \"recipe\": \"v8\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"eb976c3ef96484facecce291ebf5fd97ce34ccfb\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_5de1032f0e9ef497433aad28840ee258c037976023b410f653133cee0ad04e2c_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_v2\", \"git\", \"goma\", \"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-p3ju\"], \"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\": [\"5728-2109f40\"], \"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": "1614702416662468",
"status": "COMPLETED",
"status_changed_ts": "1614702778104979",
"tags": [
"build_address:luci.v8.ci/V8 Win64 - dev image/640",
"builder:V8 Win64 - dev image",
"buildset:commit/git/eb976c3ef96484facecce291ebf5fd97ce34ccfb",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/eb976c3ef96484facecce291ebf5fd97ce34ccfb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019293037627534192",
"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/8853862975521656960/+/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:520c288d6f728010",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1614702778124786",
"url": "https://luci-milo-dev.appspot.com/b/8853862975521656960",
"utcnow_ts": "1614702780478854"
}
}
[16:33:00.488] Invocation finished in 2h4m40.408113726s with status SUCCEEDED