[12:30:02.321] New invocation is queued and will start shortly
[12:30:03.420] Starting the invocation (attempt 1)
[12:30:03.467] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[12:30:03.467] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[12:30:03.467] Buildbucket request:
{
"bucket": "luci.v8.ci",
"client_operation_id": "9019934658951533248",
"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\":\"ffa4d2b78574f92742e0695493d2c6ffdd4ce548\"},\"id\":\"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@ffa4d2b78574f92742e0695493d2c6ffdd4ce548\",\"title\":\"ffa4d2b78574f92742e0695493d2c6ffdd4ce548\",\"url\":\"https://chromium.googlesource.com/v8/v8/+/ffa4d2b78574f92742e0695493d2c6ffdd4ce548\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"ffa4d2b78574f92742e0695493d2c6ffdd4ce548\"}}",
"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:9019934658951533248",
"scheduler_job_id:v8/V8 Win64 - dev image",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/ffa4d2b78574f92742e0695493d2c6ffdd4ce548",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/ffa4d2b78574f92742e0695493d2c6ffdd4ce548",
"gitiles_ref:refs/heads/master"
]
}
[12:30:04.417] Buildbucket response:
{
"build": {
"bucket": "luci.v8.ci",
"canary_preference": "PROD",
"created_by": "project:v8",
"created_ts": "1614083403590968",
"id": "8854504596955701168",
"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\": \"ffa4d2b78574f92742e0695493d2c6ffdd4ce548\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@ffa4d2b78574f92742e0695493d2c6ffdd4ce548\", \"title\": \"ffa4d2b78574f92742e0695493d2c6ffdd4ce548\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/ffa4d2b78574f92742e0695493d2c6ffdd4ce548\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"ffa4d2b78574f92742e0695493d2c6ffdd4ce548\"}}",
"project": "v8",
"result_details_json": "{\"properties\": {}}",
"service_account": "v8-ci-builder-dev@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614083404217784",
"tags": [
"build_address:luci.v8.ci/V8 Win64 - dev image/567",
"builder:V8 Win64 - dev image",
"buildset:commit/git/ffa4d2b78574f92742e0695493d2c6ffdd4ce548",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/ffa4d2b78574f92742e0695493d2c6ffdd4ce548",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019934658951533248",
"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/8854504596955701168/+/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": "1614083404217985",
"url": "https://luci-milo-dev.appspot.com/b/8854504596955701168",
"utcnow_ts": "1614083404409154"
}
}
[12:30:04.417] Task URL: https://luci-milo-dev.appspot.com/b/8854504596955701168
[12:30:04.417] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:2:0) after 1m0s
[12:31:04.456] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:2:0)
[12:31:04.456] Timer tick, asking Buildbucket for the build status
[12:31:04.570] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:31:04.570] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:3:0) after 1m0s
[12:32:04.591] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:3:0)
[12:32:04.591] Timer tick, asking Buildbucket for the build status
[12:32:04.709] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:32:04.709] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:4:0) after 1m0s
[12:33:04.731] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:4:0)
[12:33:04.731] Timer tick, asking Buildbucket for the build status
[12:33:04.810] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:33:04.810] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:5:0) after 1m0s
[12:34:04.857] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:5:0)
[12:34:04.857] Timer tick, asking Buildbucket for the build status
[12:34:05.154] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:34:05.154] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:6:0) after 1m0s
[12:35:05.222] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:6:0)
[12:35:05.222] Timer tick, asking Buildbucket for the build status
[12:35:05.322] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:35:05.322] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:7:0) after 1m0s
[12:36:05.379] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:7:0)
[12:36:05.379] Timer tick, asking Buildbucket for the build status
[12:36:05.647] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:36:05.647] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:8:0) after 1m0s
[12:37:05.712] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:8:0)
[12:37:05.712] Timer tick, asking Buildbucket for the build status
[12:37:05.830] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:37:05.830] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:9:0) after 1m0s
[12:38:05.927] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:9:0)
[12:38:05.927] Timer tick, asking Buildbucket for the build status
[12:38:06.306] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:38:06.306] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:10:0) after 1m0s
[12:39:06.345] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:10:0)
[12:39:06.345] Timer tick, asking Buildbucket for the build status
[12:39:06.569] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:39:06.569] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:11:0) after 1m0s
[12:40:06.590] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:11:0)
[12:40:06.590] Timer tick, asking Buildbucket for the build status
[12:40:06.882] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:40:06.882] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:12:0) after 1m0s
[12:41:06.912] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:12:0)
[12:41:06.912] Timer tick, asking Buildbucket for the build status
[12:41:07.391] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:41:07.391] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:13:0) after 1m0s
[12:42:07.414] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:13:0)
[12:42:07.414] Timer tick, asking Buildbucket for the build status
[12:42:08.094] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:42:08.094] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:14:0) after 1m0s
[12:43:08.167] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:14:0)
[12:43:08.167] Timer tick, asking Buildbucket for the build status
[12:43:08.455] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:43:08.455] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:15:0) after 1m0s
[12:44:08.512] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:15:0)
[12:44:08.512] Timer tick, asking Buildbucket for the build status
[12:44:08.895] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:44:08.895] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:16:0) after 1m0s
[12:45:08.958] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:16:0)
[12:45:08.958] Timer tick, asking Buildbucket for the build status
[12:45:09.204] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:45:09.204] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:17:0) after 1m0s
[12:46:09.225] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:17:0)
[12:46:09.225] Timer tick, asking Buildbucket for the build status
[12:46:09.493] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:46:09.493] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:18:0) after 1m0s
[12:47:09.529] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:18:0)
[12:47:09.529] Timer tick, asking Buildbucket for the build status
[12:47:10.010] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:47:10.010] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:19:0) after 1m0s
[12:48:10.067] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:19:0)
[12:48:10.067] Timer tick, asking Buildbucket for the build status
[12:48:10.379] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:48:10.379] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:20:0) after 1m0s
[12:49:10.427] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:20:0)
[12:49:10.427] Timer tick, asking Buildbucket for the build status
[12:49:10.684] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:49:10.684] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:21:0) after 1m0s
[12:50:10.757] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:21:0)
[12:50:10.757] Timer tick, asking Buildbucket for the build status
[12:50:11.137] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:50:11.137] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:22:0) after 1m0s
[12:51:11.208] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:22:0)
[12:51:11.208] Timer tick, asking Buildbucket for the build status
[12:51:11.421] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:51:11.421] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:23:0) after 1m0s
[12:52:11.457] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:23:0)
[12:52:11.457] Timer tick, asking Buildbucket for the build status
[12:52:11.665] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:52:11.665] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:24:0) after 1m0s
[12:53:11.714] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:24:0)
[12:53:11.714] Timer tick, asking Buildbucket for the build status
[12:53:11.997] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:53:11.997] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:25:0) after 1m0s
[12:54:12.081] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:25:0)
[12:54:12.081] Timer tick, asking Buildbucket for the build status
[12:54:12.546] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:54:12.546] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:26:0) after 1m0s
[12:55:12.595] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:26:0)
[12:55:12.595] Timer tick, asking Buildbucket for the build status
[12:55:12.938] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:55:12.938] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:27:0) after 1m0s
[12:56:12.976] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:27:0)
[12:56:12.976] Timer tick, asking Buildbucket for the build status
[12:56:13.282] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:56:13.282] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:28:0) after 1m0s
[12:57:13.321] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:28:0)
[12:57:13.321] Timer tick, asking Buildbucket for the build status
[12:57:13.662] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:57:13.662] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:29:0) after 1m0s
[12:58:13.692] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:29:0)
[12:58:13.693] Timer tick, asking Buildbucket for the build status
[12:58:14.129] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:58:14.129] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:30:0) after 1m0s
[12:59:14.213] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:30:0)
[12:59:14.213] Timer tick, asking Buildbucket for the build status
[12:59:14.609] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:59:14.609] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:31:0) after 1m0s
[13:00:14.634] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:31:0)
[13:00:14.634] Timer tick, asking Buildbucket for the build status
[13:00:14.886] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:00:14.886] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:32:0) after 1m0s
[13:01:14.928] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:32:0)
[13:01:14.928] Timer tick, asking Buildbucket for the build status
[13:01:15.152] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:01:15.152] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:33:0) after 1m0s
[13:02:15.181] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:33:0)
[13:02:15.181] Timer tick, asking Buildbucket for the build status
[13:02:15.479] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:02:15.479] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:34:0) after 1m0s
[13:03:15.572] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:34:0)
[13:03:15.572] Timer tick, asking Buildbucket for the build status
[13:03:15.833] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:03:15.833] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:35:0) after 1m0s
[13:04:15.928] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:35:0)
[13:04:15.928] Timer tick, asking Buildbucket for the build status
[13:04:16.188] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:04:16.188] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:36:0) after 1m0s
[13:05:16.223] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:36:0)
[13:05:16.223] Timer tick, asking Buildbucket for the build status
[13:05:16.490] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:05:16.490] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:37:0) after 1m0s
[13:06:16.514] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:37:0)
[13:06:16.514] Timer tick, asking Buildbucket for the build status
[13:06:16.802] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:06:16.802] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:38:0) after 1m0s
[13:07:16.911] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:38:0)
[13:07:16.911] Timer tick, asking Buildbucket for the build status
[13:07:17.205] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:07:17.205] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:39:0) after 1m0s
[13:08:17.229] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:39:0)
[13:08:17.229] Timer tick, asking Buildbucket for the build status
[13:08:17.618] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:08:17.618] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:40:0) after 1m0s
[13:09:17.649] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:40:0)
[13:09:17.649] Timer tick, asking Buildbucket for the build status
[13:09:17.884] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:09:17.884] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:41:0) after 1m0s
[13:10:17.974] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:41:0)
[13:10:17.974] Timer tick, asking Buildbucket for the build status
[13:10:18.265] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:10:18.265] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:42:0) after 1m0s
[13:11:18.328] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:42:0)
[13:11:18.328] Timer tick, asking Buildbucket for the build status
[13:11:18.544] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:11:18.544] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:43:0) after 1m0s
[13:12:18.617] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:43:0)
[13:12:18.617] Timer tick, asking Buildbucket for the build status
[13:12:18.910] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:12:18.910] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:44:0) after 1m0s
[13:13:18.965] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:44:0)
[13:13:18.965] Timer tick, asking Buildbucket for the build status
[13:13:19.226] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:13:19.226] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:45:0) after 1m0s
[13:14:19.315] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:45:0)
[13:14:19.315] Timer tick, asking Buildbucket for the build status
[13:14:19.765] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:14:19.765] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:46:0) after 1m0s
[13:15:19.860] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:46:0)
[13:15:19.860] Timer tick, asking Buildbucket for the build status
[13:15:20.133] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:15:20.133] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:47:0) after 1m0s
[13:16:20.227] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:47:0)
[13:16:20.227] Timer tick, asking Buildbucket for the build status
[13:16:20.443] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:16:20.443] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:48:0) after 1m0s
[13:17:20.463] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:48:0)
[13:17:20.463] Timer tick, asking Buildbucket for the build status
[13:17:20.827] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:17:20.827] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:49:0) after 1m0s
[13:18:20.926] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:49:0)
[13:18:20.926] Timer tick, asking Buildbucket for the build status
[13:18:21.485] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:18:21.485] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:50:0) after 1m0s
[13:19:21.541] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:50:0)
[13:19:21.541] Timer tick, asking Buildbucket for the build status
[13:19:21.689] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:19:21.689] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:51:0) after 1m0s
[13:20:21.735] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:51:0)
[13:20:21.735] Timer tick, asking Buildbucket for the build status
[13:20:21.833] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:20:21.833] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:52:0) after 1m0s
[13:21:21.933] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:52:0)
[13:21:21.933] Timer tick, asking Buildbucket for the build status
[13:21:22.039] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:21:22.039] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:53:0) after 1m0s
[13:22:22.077] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:53:0)
[13:22:22.078] Timer tick, asking Buildbucket for the build status
[13:22:22.218] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:22:22.218] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:54:0) after 1m0s
[13:23:22.356] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:54:0)
[13:23:22.356] Timer tick, asking Buildbucket for the build status
[13:23:22.453] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:23:22.453] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:55:0) after 1m0s
[13:24:22.530] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:55:0)
[13:24:22.530] Timer tick, asking Buildbucket for the build status
[13:24:22.640] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:24:22.640] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:56:0) after 1m0s
[13:25:22.680] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:56:0)
[13:25:22.680] Timer tick, asking Buildbucket for the build status
[13:25:22.784] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:25:22.784] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:57:0) after 1m0s
[13:26:22.829] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:57:0)
[13:26:22.829] Timer tick, asking Buildbucket for the build status
[13:26:22.914] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:26:22.914] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:58:0) after 1m0s
[13:27:22.957] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:58:0)
[13:27:22.957] Timer tick, asking Buildbucket for the build status
[13:27:23.046] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:27:23.046] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:59:0) after 1m0s
[13:28:23.068] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:59:0)
[13:28:23.068] Timer tick, asking Buildbucket for the build status
[13:28:23.184] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:28:23.184] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:60:0) after 1m0s
[13:29:23.258] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:60:0)
[13:29:23.258] Timer tick, asking Buildbucket for the build status
[13:29:23.362] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:29:23.362] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:61:0) after 1m0s
[13:30:23.477] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:61:0)
[13:30:23.477] Timer tick, asking Buildbucket for the build status
[13:30:23.574] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:30:23.574] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:62:0) after 1m0s
[13:31:23.658] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:62:0)
[13:31:23.658] Timer tick, asking Buildbucket for the build status
[13:31:23.741] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:31:23.741] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:63:0) after 1m0s
[13:32:23.762] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:63:0)
[13:32:23.762] Timer tick, asking Buildbucket for the build status
[13:32:23.844] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:32:23.844] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:64:0) after 1m0s
[13:33:23.922] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:64:0)
[13:33:23.922] Timer tick, asking Buildbucket for the build status
[13:33:24.019] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:33:24.019] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:65:0) after 1m0s
[13:34:24.113] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:65:0)
[13:34:24.114] Timer tick, asking Buildbucket for the build status
[13:34:24.205] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:34:24.205] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:66:0) after 1m0s
[13:35:24.259] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:66:0)
[13:35:24.259] Timer tick, asking Buildbucket for the build status
[13:35:24.386] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:35:24.386] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:67:0) after 1m0s
[13:36:24.423] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:67:0)
[13:36:24.423] Timer tick, asking Buildbucket for the build status
[13:36:24.513] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:36:24.513] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:68:0) after 1m0s
[13:37:24.563] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:68:0)
[13:37:24.563] Timer tick, asking Buildbucket for the build status
[13:37:24.648] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:37:24.648] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:69:0) after 1m0s
[13:38:24.676] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:69:0)
[13:38:24.676] Timer tick, asking Buildbucket for the build status
[13:38:24.778] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:38:24.778] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:70:0) after 1m0s
[13:39:24.810] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:70:0)
[13:39:24.810] Timer tick, asking Buildbucket for the build status
[13:39:24.938] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:39:24.938] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:71:0) after 1m0s
[13:40:24.974] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:71:0)
[13:40:24.974] Timer tick, asking Buildbucket for the build status
[13:40:25.059] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:40:25.059] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:72:0) after 1m0s
[13:41:25.093] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:72:0)
[13:41:25.093] Timer tick, asking Buildbucket for the build status
[13:41:25.178] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:41:25.178] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:73:0) after 1m0s
[13:42:25.234] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:73:0)
[13:42:25.234] Timer tick, asking Buildbucket for the build status
[13:42:25.315] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:42:25.315] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:74:0) after 1m0s
[13:43:25.373] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:74:0)
[13:43:25.373] Timer tick, asking Buildbucket for the build status
[13:43:25.468] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:43:25.468] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:75:0) after 1m0s
[13:44:25.504] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:75:0)
[13:44:25.504] Timer tick, asking Buildbucket for the build status
[13:44:25.699] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:44:25.699] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:76:0) after 1m0s
[13:45:25.756] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:76:0)
[13:45:25.756] Timer tick, asking Buildbucket for the build status
[13:45:25.881] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:45:25.881] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:77:0) after 1m0s
[13:46:25.958] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:77:0)
[13:46:25.958] Timer tick, asking Buildbucket for the build status
[13:46:26.064] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:46:26.064] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:78:0) after 1m0s
[13:47:26.159] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:78:0)
[13:47:26.159] Timer tick, asking Buildbucket for the build status
[13:47:26.283] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:47:26.283] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:79:0) after 1m0s
[13:48:26.307] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:79:0)
[13:48:26.307] Timer tick, asking Buildbucket for the build status
[13:48:26.393] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:48:26.393] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:80:0) after 1m0s
[13:49:26.469] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:80:0)
[13:49:26.469] Timer tick, asking Buildbucket for the build status
[13:49:26.564] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:49:26.564] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:81:0) after 1m0s
[13:50:26.596] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:81:0)
[13:50:26.596] Timer tick, asking Buildbucket for the build status
[13:50:26.695] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:50:26.695] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:82:0) after 1m0s
[13:51:26.760] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:82:0)
[13:51:26.760] Timer tick, asking Buildbucket for the build status
[13:51:26.858] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:51:26.858] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:83:0) after 1m0s
[13:52:26.945] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:83:0)
[13:52:26.945] Timer tick, asking Buildbucket for the build status
[13:52:27.044] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:52:27.044] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:84:0) after 1m0s
[13:53:27.066] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:84:0)
[13:53:27.066] Timer tick, asking Buildbucket for the build status
[13:53:27.146] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:53:27.146] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:85:0) after 1m0s
[13:54:27.167] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:85:0)
[13:54:27.167] Timer tick, asking Buildbucket for the build status
[13:54:27.280] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:54:27.280] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:86:0) after 1m0s
[13:55:27.311] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:86:0)
[13:55:27.311] Timer tick, asking Buildbucket for the build status
[13:55:27.396] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:55:27.396] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:87:0) after 1m0s
[13:56:27.426] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:87:0)
[13:56:27.426] Timer tick, asking Buildbucket for the build status
[13:56:27.526] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:56:27.526] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:88:0) after 1m0s
[13:57:27.573] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:88:0)
[13:57:27.573] Timer tick, asking Buildbucket for the build status
[13:57:27.670] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:57:27.670] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:89:0) after 1m0s
[13:58:27.700] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:89:0)
[13:58:27.700] Timer tick, asking Buildbucket for the build status
[13:58:27.787] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:58:27.787] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:90:0) after 1m0s
[13:59:27.833] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:90:0)
[13:59:27.833] Timer tick, asking Buildbucket for the build status
[13:59:27.930] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:59:27.930] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:91:0) after 1m0s
[14:00:27.958] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:91:0)
[14:00:27.958] Timer tick, asking Buildbucket for the build status
[14:00:28.203] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:00:28.203] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:92:0) after 1m0s
[14:01:28.258] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:92:0)
[14:01:28.258] Timer tick, asking Buildbucket for the build status
[14:01:28.371] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:01:28.371] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:93:0) after 1m0s
[14:02:28.426] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:93:0)
[14:02:28.426] Timer tick, asking Buildbucket for the build status
[14:02:28.521] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:02:28.521] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:94:0) after 1m0s
[14:03:28.630] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:94:0)
[14:03:28.630] Timer tick, asking Buildbucket for the build status
[14:03:28.776] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:03:28.776] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:95:0) after 1m0s
[14:04:28.815] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:95:0)
[14:04:28.815] Timer tick, asking Buildbucket for the build status
[14:04:28.904] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:04:28.904] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:96:0) after 1m0s
[14:05:29.015] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:96:0)
[14:05:29.015] Timer tick, asking Buildbucket for the build status
[14:05:29.113] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:05:29.113] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:97:0) after 1m0s
[14:06:29.137] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:97:0)
[14:06:29.137] Timer tick, asking Buildbucket for the build status
[14:06:29.225] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:06:29.225] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:98:0) after 1m0s
[14:07:29.273] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:98:0)
[14:07:29.273] Timer tick, asking Buildbucket for the build status
[14:07:29.363] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:07:29.363] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:99:0) after 1m0s
[14:08:29.395] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:99:0)
[14:08:29.395] Timer tick, asking Buildbucket for the build status
[14:08:29.507] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:08:29.507] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:100:0) after 1m0s
[14:09:29.558] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:100:0)
[14:09:29.558] Timer tick, asking Buildbucket for the build status
[14:09:29.683] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:09:29.683] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:101:0) after 1m0s
[14:10:29.772] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:101:0)
[14:10:29.772] Timer tick, asking Buildbucket for the build status
[14:10:29.867] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:10:29.867] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:102:0) after 1m0s
[14:11:29.898] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:102:0)
[14:11:29.898] Timer tick, asking Buildbucket for the build status
[14:11:29.979] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:11:29.979] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:103:0) after 1m0s
[14:12:30.060] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:103:0)
[14:12:30.060] Timer tick, asking Buildbucket for the build status
[14:12:30.158] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:12:30.158] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:104:0) after 1m0s
[14:13:30.226] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:104:0)
[14:13:30.226] Timer tick, asking Buildbucket for the build status
[14:13:30.316] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:13:30.316] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:105:0) after 1m0s
[14:14:30.342] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:105:0)
[14:14:30.342] Timer tick, asking Buildbucket for the build status
[14:14:30.419] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:14:30.419] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:106:0) after 1m0s
[14:15:30.531] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:106:0)
[14:15:30.531] Timer tick, asking Buildbucket for the build status
[14:15:30.676] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:15:30.676] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:107:0) after 1m0s
[14:16:30.700] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:107:0)
[14:16:30.701] Timer tick, asking Buildbucket for the build status
[14:16:30.783] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:16:30.783] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:108:0) after 1m0s
[14:17:30.871] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:108:0)
[14:17:30.871] Timer tick, asking Buildbucket for the build status
[14:17:30.974] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:17:30.974] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:109:0) after 1m0s
[14:18:31.058] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:109:0)
[14:18:31.058] Timer tick, asking Buildbucket for the build status
[14:18:31.212] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:18:31.212] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:110:0) after 1m0s
[14:19:31.321] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:110:0)
[14:19:31.321] Timer tick, asking Buildbucket for the build status
[14:19:31.414] Build 8854504596955701168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:19:31.414] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:111:0) after 1m0s
[14:20:22.715] Received PubSub notification, asking Buildbucket for the build status
[14:20:22.891] Build 8854504596955701168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:31.477] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:111:0)
[14:20:31.477] Timer tick, asking Buildbucket for the build status
[14:20:31.565] Build 8854504596955701168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:31.565] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:113:0) after 1m0s
[14:21:31.586] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:113:0)
[14:21:31.586] Timer tick, asking Buildbucket for the build status
[14:21:31.674] Build 8854504596955701168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:31.675] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:114:0) after 1m0s
[14:22:31.713] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:114:0)
[14:22:31.713] Timer tick, asking Buildbucket for the build status
[14:22:31.896] Build 8854504596955701168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:31.896] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9019934658951533248:115:0) after 1m0s
[14:23:17.160] Received PubSub notification, asking Buildbucket for the build status
[14:23:17.251] Build 8854504596955701168: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:23:17.251] Buildbucket build:
{
"build": {
"bucket": "luci.v8.ci",
"canary_preference": "PROD",
"completed_ts": "1614090195625983",
"created_by": "project:v8",
"created_ts": "1614083403590968",
"id": "8854504596955701168",
"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\": \"ffa4d2b78574f92742e0695493d2c6ffdd4ce548\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@ffa4d2b78574f92742e0695493d2c6ffdd4ce548\", \"title\": \"ffa4d2b78574f92742e0695493d2c6ffdd4ce548\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/ffa4d2b78574f92742e0695493d2c6ffdd4ce548\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"ffa4d2b78574f92742e0695493d2c6ffdd4ce548\"}}",
"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\": \"ffa4d2b78574f92742e0695493d2c6ffdd4ce548\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/heads/master@ffa4d2b78574f92742e0695493d2c6ffdd4ce548\", \"title\": \"ffa4d2b78574f92742e0695493d2c6ffdd4ce548\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/ffa4d2b78574f92742e0695493d2c6ffdd4ce548\"}]}, \"bot_id\": \"dev-luci-chromium-ci-win10-0-tc1z\", \"branch\": \"refs/heads/master\", \"builder_group\": \"client.v8\", \"buildername\": \"V8 Win64 - dev image\", \"buildnumber\": 567, \"got_revision\": \"ffa4d2b78574f92742e0695493d2c6ffdd4ce548\", \"got_revision_cp\": \"refs/heads/master@{#72949}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"path_config\": \"generic\", \"recipe\": \"v8\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"ffa4d2b78574f92742e0695493d2c6ffdd4ce548\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_044445a3bc7a4bfe795493d7956aa02cd526172de1fa7300f69fe152e82ea631_v2\", \"builder_5de1032f0e9ef497433aad28840ee258c037976023b410f653133cee0ad04e2c_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_v2\", \"builder_d38bdc976f893fc6930479dce8a6d7dc793b2a74fe1392d4af00713a5c0bb46e_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-tc1z\"], \"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\": [\"5709-d3c4474\"], \"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": "1614090019547438",
"status": "COMPLETED",
"status_changed_ts": "1614090195940091",
"tags": [
"build_address:luci.v8.ci/V8 Win64 - dev image/567",
"builder:V8 Win64 - dev image",
"buildset:commit/git/ffa4d2b78574f92742e0695493d2c6ffdd4ce548",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/ffa4d2b78574f92742e0695493d2c6ffdd4ce548",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019934658951533248",
"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/8854504596955701168/+/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:51e7afbd286ba310",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1614090195960946",
"url": "https://luci-milo-dev.appspot.com/b/8854504596955701168",
"utcnow_ts": "1614090197243210"
}
}
[14:23:17.251] Invocation finished in 1h53m14.947818088s with status SUCCEEDED