[22:47:30.155] New invocation is queued and will start shortly
[22:47:31.325] Starting the invocation (attempt 1)
[22:47:31.370] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[22:47:31.370] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[22:47:31.370] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9020258199355581152",
"parameters_json": "{\"builder_name\":\"infra-continuous-win10-64\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler-dev.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"46cf5a5d7c1a4bb35177cb9748e2f78095fde092\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@46cf5a5d7c1a4bb35177cb9748e2f78095fde092\",\"title\":\"46cf5a5d7c1a4bb35177cb9748e2f78095fde092\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/46cf5a5d7c1a4bb35177cb9748e2f78095fde092\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"39c91f60aac117204a36cd692f9600cf49f376f7\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@39c91f60aac117204a36cd692f9600cf49f376f7\",\"title\":\"39c91f60aac117204a36cd692f9600cf49f376f7\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/39c91f60aac117204a36cd692f9600cf49f376f7\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"c17acb4bef3c19172fdac4e3c4856604776c98c3\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c17acb4bef3c19172fdac4e3c4856604776c98c3\",\"title\":\"c17acb4bef3c19172fdac4e3c4856604776c98c3\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/c17acb4bef3c19172fdac4e3c4856604776c98c3\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"317d421aa3b55ca27b7f0acbef4b9f1d711dc51e\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@317d421aa3b55ca27b7f0acbef4b9f1d711dc51e\",\"title\":\"317d421aa3b55ca27b7f0acbef4b9f1d711dc51e\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/317d421aa3b55ca27b7f0acbef4b9f1d711dc51e\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"e394b01650fda25b4b9c07b95148468d12e2d067\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@e394b01650fda25b4b9c07b95148468d12e2d067\",\"title\":\"e394b01650fda25b4b9c07b95148468d12e2d067\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/e394b01650fda25b4b9c07b95148468d12e2d067\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"596f34911a32088b30175fba020875665815a0ef\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@596f34911a32088b30175fba020875665815a0ef\",\"title\":\"596f34911a32088b30175fba020875665815a0ef\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/596f34911a32088b30175fba020875665815a0ef\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"ba96b1dc608ee055e5bf9492838b4fbed5dc5db3\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ba96b1dc608ee055e5bf9492838b4fbed5dc5db3\",\"title\":\"ba96b1dc608ee055e5bf9492838b4fbed5dc5db3\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/ba96b1dc608ee055e5bf9492838b4fbed5dc5db3\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"ba96b1dc608ee055e5bf9492838b4fbed5dc5db3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
},
"tags": [
"builder:infra-continuous-win10-64",
"scheduler_invocation_id:9020258199355581152",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/ba96b1dc608ee055e5bf9492838b4fbed5dc5db3",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/ba96b1dc608ee055e5bf9492838b4fbed5dc5db3",
"gitiles_ref:refs/heads/master"
]
}
[22:47:32.123] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1613774851470154",
"id": "8854828137303102192",
"parameters_json": "{\"builder_name\": \"infra-continuous-win10-64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler-dev.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"46cf5a5d7c1a4bb35177cb9748e2f78095fde092\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@46cf5a5d7c1a4bb35177cb9748e2f78095fde092\", \"title\": \"46cf5a5d7c1a4bb35177cb9748e2f78095fde092\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/46cf5a5d7c1a4bb35177cb9748e2f78095fde092\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"39c91f60aac117204a36cd692f9600cf49f376f7\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@39c91f60aac117204a36cd692f9600cf49f376f7\", \"title\": \"39c91f60aac117204a36cd692f9600cf49f376f7\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/39c91f60aac117204a36cd692f9600cf49f376f7\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"c17acb4bef3c19172fdac4e3c4856604776c98c3\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c17acb4bef3c19172fdac4e3c4856604776c98c3\", \"title\": \"c17acb4bef3c19172fdac4e3c4856604776c98c3\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/c17acb4bef3c19172fdac4e3c4856604776c98c3\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"317d421aa3b55ca27b7f0acbef4b9f1d711dc51e\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@317d421aa3b55ca27b7f0acbef4b9f1d711dc51e\", \"title\": \"317d421aa3b55ca27b7f0acbef4b9f1d711dc51e\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/317d421aa3b55ca27b7f0acbef4b9f1d711dc51e\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"e394b01650fda25b4b9c07b95148468d12e2d067\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@e394b01650fda25b4b9c07b95148468d12e2d067\", \"title\": \"e394b01650fda25b4b9c07b95148468d12e2d067\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/e394b01650fda25b4b9c07b95148468d12e2d067\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"596f34911a32088b30175fba020875665815a0ef\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@596f34911a32088b30175fba020875665815a0ef\", \"title\": \"596f34911a32088b30175fba020875665815a0ef\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/596f34911a32088b30175fba020875665815a0ef\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"ba96b1dc608ee055e5bf9492838b4fbed5dc5db3\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ba96b1dc608ee055e5bf9492838b4fbed5dc5db3\", \"title\": \"ba96b1dc608ee055e5bf9492838b4fbed5dc5db3\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/ba96b1dc608ee055e5bf9492838b4fbed5dc5db3\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"ba96b1dc608ee055e5bf9492838b4fbed5dc5db3\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613774851935831",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18662",
"builder:infra-continuous-win10-64",
"buildset:commit/git/ba96b1dc608ee055e5bf9492838b4fbed5dc5db3",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/ba96b1dc608ee055e5bf9492838b4fbed5dc5db3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020258199355581152",
"scheduler_job_id:infra/infra-continuous-win10-64",
"swarming_hostname:chromium-swarm-dev.appspot.com",
"swarming_tag:log_location:logdog://luci-logdog-dev.appspot.com/infra/buildbucket/cr-buildbucket-dev.appspot.com/8854828137303102192/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:recipe_name:infra_continuous",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1613774851935934",
"url": "https://luci-milo-dev.appspot.com/b/8854828137303102192",
"utcnow_ts": "1613774852113692"
}
}
[22:47:32.123] Task URL: https://luci-milo-dev.appspot.com/b/8854828137303102192
[22:47:32.123] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:2:0) after 1m0s
[22:48:32.211] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:2:0)
[22:48:32.211] Timer tick, asking Buildbucket for the build status
[22:48:32.324] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:32.324] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:3:0) after 1m0s
[22:49:32.343] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:3:0)
[22:49:32.343] Timer tick, asking Buildbucket for the build status
[22:49:32.427] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:32.427] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:4:0) after 1m0s
[22:50:32.491] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:4:0)
[22:50:32.491] Timer tick, asking Buildbucket for the build status
[22:50:32.577] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:32.577] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:5:0) after 1m0s
[22:51:32.600] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:5:0)
[22:51:32.600] Timer tick, asking Buildbucket for the build status
[22:51:32.700] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:32.700] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:6:0) after 1m0s
[22:52:32.807] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:6:0)
[22:52:32.807] Timer tick, asking Buildbucket for the build status
[22:52:32.895] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:32.895] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:7:0) after 1m0s
[22:53:33.008] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:7:0)
[22:53:33.008] Timer tick, asking Buildbucket for the build status
[22:53:33.117] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:33.117] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:8:0) after 1m0s
[22:54:33.202] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:8:0)
[22:54:33.202] Timer tick, asking Buildbucket for the build status
[22:54:33.281] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:33.281] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:9:0) after 1m0s
[22:55:33.306] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:9:0)
[22:55:33.306] Timer tick, asking Buildbucket for the build status
[22:55:33.480] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:33.480] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:10:0) after 1m0s
[22:56:33.508] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:10:0)
[22:56:33.508] Timer tick, asking Buildbucket for the build status
[22:56:33.599] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:33.599] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:11:0) after 1m0s
[22:57:33.696] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:11:0)
[22:57:33.696] Timer tick, asking Buildbucket for the build status
[22:57:33.781] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:33.781] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:12:0) after 1m0s
[22:58:33.804] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:12:0)
[22:58:33.804] Timer tick, asking Buildbucket for the build status
[22:58:33.973] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:33.973] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:13:0) after 1m0s
[22:59:33.996] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:13:0)
[22:59:33.996] Timer tick, asking Buildbucket for the build status
[22:59:34.171] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:34.171] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:14:0) after 1m0s
[23:00:34.194] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:14:0)
[23:00:34.194] Timer tick, asking Buildbucket for the build status
[23:00:34.315] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:34.315] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:15:0) after 1m0s
[23:01:34.345] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:15:0)
[23:01:34.345] Timer tick, asking Buildbucket for the build status
[23:01:34.464] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:34.464] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:16:0) after 1m0s
[23:02:34.496] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:16:0)
[23:02:34.496] Timer tick, asking Buildbucket for the build status
[23:02:34.593] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:34.593] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:17:0) after 1m0s
[23:03:34.703] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:17:0)
[23:03:34.703] Timer tick, asking Buildbucket for the build status
[23:03:34.784] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:34.784] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:18:0) after 1m0s
[23:04:34.807] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:18:0)
[23:04:34.807] Timer tick, asking Buildbucket for the build status
[23:04:34.916] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:34.916] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:19:0) after 1m0s
[23:05:34.993] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:19:0)
[23:05:34.993] Timer tick, asking Buildbucket for the build status
[23:05:35.089] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:35.089] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:20:0) after 1m0s
[23:06:35.210] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:20:0)
[23:06:35.210] Timer tick, asking Buildbucket for the build status
[23:06:35.296] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:35.296] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:21:0) after 1m0s
[23:07:35.424] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:21:0)
[23:07:35.424] Timer tick, asking Buildbucket for the build status
[23:07:35.507] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:35.507] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:22:0) after 1m0s
[23:08:35.603] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:22:0)
[23:08:35.603] Timer tick, asking Buildbucket for the build status
[23:08:35.752] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:35.752] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:23:0) after 1m0s
[23:09:35.807] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:23:0)
[23:09:35.807] Timer tick, asking Buildbucket for the build status
[23:09:35.971] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:35.971] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:24:0) after 1m0s
[23:10:36.007] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:24:0)
[23:10:36.007] Timer tick, asking Buildbucket for the build status
[23:10:36.157] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:36.157] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:25:0) after 1m0s
[23:11:36.195] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:25:0)
[23:11:36.195] Timer tick, asking Buildbucket for the build status
[23:11:36.308] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:36.308] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:26:0) after 1m0s
[23:12:36.332] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:26:0)
[23:12:36.332] Timer tick, asking Buildbucket for the build status
[23:12:36.435] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:36.435] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:27:0) after 1m0s
[23:13:36.511] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:27:0)
[23:13:36.511] Timer tick, asking Buildbucket for the build status
[23:13:36.666] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:36.666] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:28:0) after 1m0s
[23:14:36.697] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:28:0)
[23:14:36.697] Timer tick, asking Buildbucket for the build status
[23:14:36.781] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:36.781] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:29:0) after 1m0s
[23:15:36.810] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:29:0)
[23:15:36.810] Timer tick, asking Buildbucket for the build status
[23:15:36.898] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:36.898] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:30:0) after 1m0s
[23:16:36.997] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:30:0)
[23:16:36.997] Timer tick, asking Buildbucket for the build status
[23:16:37.086] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:37.087] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:31:0) after 1m0s
[23:17:37.114] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:31:0)
[23:17:37.114] Timer tick, asking Buildbucket for the build status
[23:17:37.191] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:37.191] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:32:0) after 1m0s
[23:18:37.305] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:32:0)
[23:18:37.305] Timer tick, asking Buildbucket for the build status
[23:18:37.444] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:37.444] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:33:0) after 1m0s
[23:19:37.498] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:33:0)
[23:19:37.498] Timer tick, asking Buildbucket for the build status
[23:19:37.571] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:37.571] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:34:0) after 1m0s
[23:20:37.599] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:34:0)
[23:20:37.599] Timer tick, asking Buildbucket for the build status
[23:20:37.678] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:37.678] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:35:0) after 1m0s
[23:21:37.703] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:35:0)
[23:21:37.703] Timer tick, asking Buildbucket for the build status
[23:21:37.779] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:37.779] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:36:0) after 1m0s
[23:22:37.804] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:36:0)
[23:22:37.804] Timer tick, asking Buildbucket for the build status
[23:22:37.927] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:37.927] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:37:0) after 1m0s
[23:23:37.950] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:37:0)
[23:23:37.950] Timer tick, asking Buildbucket for the build status
[23:23:38.029] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:38.029] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:38:0) after 1m0s
[23:24:38.109] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:38:0)
[23:24:38.109] Timer tick, asking Buildbucket for the build status
[23:24:38.196] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:38.196] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:39:0) after 1m0s
[23:25:38.221] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:39:0)
[23:25:38.221] Timer tick, asking Buildbucket for the build status
[23:25:38.342] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:38.342] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:40:0) after 1m0s
[23:26:38.393] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:40:0)
[23:26:38.393] Timer tick, asking Buildbucket for the build status
[23:26:38.480] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:38.480] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:41:0) after 1m0s
[23:27:38.502] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:41:0)
[23:27:38.502] Timer tick, asking Buildbucket for the build status
[23:27:38.587] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:38.587] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:42:0) after 1m0s
[23:28:38.716] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:42:0)
[23:28:38.716] Timer tick, asking Buildbucket for the build status
[23:28:38.801] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:38.801] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:43:0) after 1m0s
[23:29:38.907] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:43:0)
[23:29:38.907] Timer tick, asking Buildbucket for the build status
[23:29:39.059] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:39.059] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:44:0) after 1m0s
[23:30:39.094] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:44:0)
[23:30:39.094] Timer tick, asking Buildbucket for the build status
[23:30:39.241] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:39.241] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:45:0) after 1m0s
[23:31:39.261] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:45:0)
[23:31:39.261] Timer tick, asking Buildbucket for the build status
[23:31:39.337] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:39.337] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:46:0) after 1m0s
[23:32:39.395] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:46:0)
[23:32:39.395] Timer tick, asking Buildbucket for the build status
[23:32:39.480] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:39.480] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:47:0) after 1m0s
[23:33:39.506] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:47:0)
[23:33:39.506] Timer tick, asking Buildbucket for the build status
[23:33:39.836] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:39.836] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:48:0) after 1m0s
[23:34:39.864] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:48:0)
[23:34:39.864] Timer tick, asking Buildbucket for the build status
[23:34:40.118] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:40.118] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:49:0) after 1m0s
[23:35:40.147] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:49:0)
[23:35:40.147] Timer tick, asking Buildbucket for the build status
[23:35:40.248] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:40.248] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:50:0) after 1m0s
[23:36:40.269] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:50:0)
[23:36:40.269] Timer tick, asking Buildbucket for the build status
[23:36:40.357] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:40.357] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:51:0) after 1m0s
[23:37:40.426] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:51:0)
[23:37:40.426] Timer tick, asking Buildbucket for the build status
[23:37:40.534] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:40.534] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:52:0) after 1m0s
[23:38:40.556] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:52:0)
[23:38:40.556] Timer tick, asking Buildbucket for the build status
[23:38:40.643] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:40.643] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:53:0) after 1m0s
[23:39:40.708] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:53:0)
[23:39:40.708] Timer tick, asking Buildbucket for the build status
[23:39:40.793] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:40.793] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:54:0) after 1m0s
[23:40:40.918] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:54:0)
[23:40:40.918] Timer tick, asking Buildbucket for the build status
[23:40:41.024] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:41.025] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:55:0) after 1m0s
[23:41:41.094] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:55:0)
[23:41:41.094] Timer tick, asking Buildbucket for the build status
[23:41:41.177] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:41.177] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:56:0) after 1m0s
[23:42:41.208] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:56:0)
[23:42:41.208] Timer tick, asking Buildbucket for the build status
[23:42:41.289] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:41.289] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:57:0) after 1m0s
[23:43:41.407] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:57:0)
[23:43:41.407] Timer tick, asking Buildbucket for the build status
[23:43:41.511] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:41.511] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:58:0) after 1m0s
[23:44:41.544] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:58:0)
[23:44:41.544] Timer tick, asking Buildbucket for the build status
[23:44:41.630] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:41.630] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:59:0) after 1m0s
[23:45:41.697] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:59:0)
[23:45:41.697] Timer tick, asking Buildbucket for the build status
[23:45:41.787] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:41.787] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:60:0) after 1m0s
[23:46:41.896] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:60:0)
[23:46:41.896] Timer tick, asking Buildbucket for the build status
[23:46:42.090] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:42.090] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:61:0) after 1m0s
[23:47:42.127] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:61:0)
[23:47:42.127] Timer tick, asking Buildbucket for the build status
[23:47:42.208] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:42.208] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:62:0) after 1m0s
[23:48:42.295] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:62:0)
[23:48:42.295] Timer tick, asking Buildbucket for the build status
[23:48:42.383] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:42.383] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:63:0) after 1m0s
[23:49:42.416] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:63:0)
[23:49:42.416] Timer tick, asking Buildbucket for the build status
[23:49:42.488] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:42.488] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:64:0) after 1m0s
[23:50:42.626] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:64:0)
[23:50:42.626] Timer tick, asking Buildbucket for the build status
[23:50:42.746] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:42.746] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:65:0) after 1m0s
[23:51:42.782] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:65:0)
[23:51:42.782] Timer tick, asking Buildbucket for the build status
[23:51:42.973] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:42.973] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:66:0) after 1m0s
[23:52:43.018] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:66:0)
[23:52:43.018] Timer tick, asking Buildbucket for the build status
[23:52:43.103] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:43.103] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:67:0) after 1m0s
[23:53:43.222] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:67:0)
[23:53:43.222] Timer tick, asking Buildbucket for the build status
[23:53:43.311] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:53:43.311] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:68:0) after 1m0s
[23:54:43.407] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:68:0)
[23:54:43.407] Timer tick, asking Buildbucket for the build status
[23:54:43.499] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:54:43.499] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:69:0) after 1m0s
[23:55:43.598] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:69:0)
[23:55:43.598] Timer tick, asking Buildbucket for the build status
[23:55:43.691] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:55:43.691] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:70:0) after 1m0s
[23:56:43.804] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:70:0)
[23:56:43.804] Timer tick, asking Buildbucket for the build status
[23:56:43.990] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:56:43.990] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:71:0) after 1m0s
[23:57:44.100] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:71:0)
[23:57:44.100] Timer tick, asking Buildbucket for the build status
[23:57:44.202] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:44.202] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:72:0) after 1m0s
[23:58:44.309] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:72:0)
[23:58:44.309] Timer tick, asking Buildbucket for the build status
[23:58:44.467] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:58:44.467] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:73:0) after 1m0s
[23:59:44.504] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:73:0)
[23:59:44.504] Timer tick, asking Buildbucket for the build status
[23:59:44.613] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:59:44.613] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:74:0) after 1m0s
[00:00:44.713] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:74:0)
[00:00:44.713] Timer tick, asking Buildbucket for the build status
[00:00:44.848] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:00:44.848] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:75:0) after 1m0s
[00:01:44.911] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:75:0)
[00:01:44.911] Timer tick, asking Buildbucket for the build status
[00:01:45.054] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:01:45.054] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:76:0) after 1m0s
[00:02:45.083] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:76:0)
[00:02:45.083] Timer tick, asking Buildbucket for the build status
[00:02:45.196] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:02:45.196] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:77:0) after 1m0s
[00:03:45.224] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:77:0)
[00:03:45.224] Timer tick, asking Buildbucket for the build status
[00:03:45.323] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:03:45.323] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:78:0) after 1m0s
[00:04:45.415] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:78:0)
[00:04:45.415] Timer tick, asking Buildbucket for the build status
[00:04:45.520] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:04:45.520] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:79:0) after 1m0s
[00:05:45.616] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:79:0)
[00:05:45.616] Timer tick, asking Buildbucket for the build status
[00:05:45.725] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:05:45.725] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:80:0) after 1m0s
[00:06:45.796] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:80:0)
[00:06:45.796] Timer tick, asking Buildbucket for the build status
[00:06:45.902] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:06:45.902] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:81:0) after 1m0s
[00:07:46.005] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:81:0)
[00:07:46.005] Timer tick, asking Buildbucket for the build status
[00:07:46.100] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:07:46.100] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:82:0) after 1m0s
[00:08:46.209] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:82:0)
[00:08:46.209] Timer tick, asking Buildbucket for the build status
[00:08:46.334] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:08:46.334] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:83:0) after 1m0s
[00:09:46.359] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:83:0)
[00:09:46.359] Timer tick, asking Buildbucket for the build status
[00:09:46.477] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:09:46.477] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:84:0) after 1m0s
[00:10:46.509] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:84:0)
[00:10:46.509] Timer tick, asking Buildbucket for the build status
[00:10:46.605] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:10:46.605] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:85:0) after 1m0s
[00:11:46.732] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:85:0)
[00:11:46.732] Timer tick, asking Buildbucket for the build status
[00:11:46.844] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:11:46.844] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:86:0) after 1m0s
[00:12:46.899] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:86:0)
[00:12:46.899] Timer tick, asking Buildbucket for the build status
[00:12:47.276] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:12:47.276] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:87:0) after 1m0s
[00:13:47.305] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:87:0)
[00:13:47.305] Timer tick, asking Buildbucket for the build status
[00:13:47.407] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:13:47.407] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:88:0) after 1m0s
[00:14:47.479] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:88:0)
[00:14:47.479] Timer tick, asking Buildbucket for the build status
[00:14:47.604] Build 8854828137303102192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:14:47.604] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:89:0) after 1m0s
[00:15:31.104] Received PubSub notification, asking Buildbucket for the build status
[00:15:31.177] Build 8854828137303102192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:47.698] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:89:0)
[00:15:47.698] Timer tick, asking Buildbucket for the build status
[00:15:47.865] Build 8854828137303102192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:47.865] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:91:0) after 1m0s
[00:16:47.905] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:91:0)
[00:16:47.905] Timer tick, asking Buildbucket for the build status
[00:16:48.007] Build 8854828137303102192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:48.007] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:92:0) after 1m0s
[00:17:48.119] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:92:0)
[00:17:48.119] Timer tick, asking Buildbucket for the build status
[00:17:48.230] Build 8854828137303102192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:48.230] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:93:0) after 1m0s
[00:18:48.294] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:93:0)
[00:18:48.294] Timer tick, asking Buildbucket for the build status
[00:18:48.477] Build 8854828137303102192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:48.477] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:94:0) after 1m0s
[00:19:48.502] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:94:0)
[00:19:48.502] Timer tick, asking Buildbucket for the build status
[00:19:48.625] Build 8854828137303102192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:48.625] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:95:0) after 1m0s
[00:20:48.694] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:95:0)
[00:20:48.695] Timer tick, asking Buildbucket for the build status
[00:20:48.884] Build 8854828137303102192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:48.884] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:96:0) after 1m0s
[00:21:49.005] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:96:0)
[00:21:49.005] Timer tick, asking Buildbucket for the build status
[00:21:49.136] Build 8854828137303102192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:49.136] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:97:0) after 1m0s
[00:22:49.220] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:97:0)
[00:22:49.220] Timer tick, asking Buildbucket for the build status
[00:22:49.338] Build 8854828137303102192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:49.338] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:98:0) after 1m0s
[00:23:49.410] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:98:0)
[00:23:49.410] Timer tick, asking Buildbucket for the build status
[00:23:49.511] Build 8854828137303102192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:49.511] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:99:0) after 1m0s
[00:24:49.611] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:99:0)
[00:24:49.611] Timer tick, asking Buildbucket for the build status
[00:24:49.728] Build 8854828137303102192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:49.728] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:100:0) after 1m0s
[00:25:49.797] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:100:0)
[00:25:49.797] Timer tick, asking Buildbucket for the build status
[00:25:49.959] Build 8854828137303102192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:49.960] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:101:0) after 1m0s
[00:26:49.986] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:101:0)
[00:26:49.986] Timer tick, asking Buildbucket for the build status
[00:26:50.081] Build 8854828137303102192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:50.081] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:102:0) after 1m0s
[00:27:50.117] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:102:0)
[00:27:50.117] Timer tick, asking Buildbucket for the build status
[00:27:50.223] Build 8854828137303102192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:50.223] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:103:0) after 1m0s
[00:28:50.245] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:103:0)
[00:28:50.245] Timer tick, asking Buildbucket for the build status
[00:28:50.405] Build 8854828137303102192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:50.405] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020258199355581152:104:0) after 1m0s
[00:29:13.182] Received PubSub notification, asking Buildbucket for the build status
[00:29:13.270] Build 8854828137303102192: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:29:13.270] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1613780945814533",
"created_by": "project:infra",
"created_ts": "1613774851470154",
"id": "8854828137303102192",
"parameters_json": "{\"builder_name\": \"infra-continuous-win10-64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler-dev.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"46cf5a5d7c1a4bb35177cb9748e2f78095fde092\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@46cf5a5d7c1a4bb35177cb9748e2f78095fde092\", \"title\": \"46cf5a5d7c1a4bb35177cb9748e2f78095fde092\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/46cf5a5d7c1a4bb35177cb9748e2f78095fde092\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"39c91f60aac117204a36cd692f9600cf49f376f7\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@39c91f60aac117204a36cd692f9600cf49f376f7\", \"title\": \"39c91f60aac117204a36cd692f9600cf49f376f7\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/39c91f60aac117204a36cd692f9600cf49f376f7\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"c17acb4bef3c19172fdac4e3c4856604776c98c3\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c17acb4bef3c19172fdac4e3c4856604776c98c3\", \"title\": \"c17acb4bef3c19172fdac4e3c4856604776c98c3\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/c17acb4bef3c19172fdac4e3c4856604776c98c3\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"317d421aa3b55ca27b7f0acbef4b9f1d711dc51e\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@317d421aa3b55ca27b7f0acbef4b9f1d711dc51e\", \"title\": \"317d421aa3b55ca27b7f0acbef4b9f1d711dc51e\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/317d421aa3b55ca27b7f0acbef4b9f1d711dc51e\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"e394b01650fda25b4b9c07b95148468d12e2d067\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@e394b01650fda25b4b9c07b95148468d12e2d067\", \"title\": \"e394b01650fda25b4b9c07b95148468d12e2d067\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/e394b01650fda25b4b9c07b95148468d12e2d067\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"596f34911a32088b30175fba020875665815a0ef\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@596f34911a32088b30175fba020875665815a0ef\", \"title\": \"596f34911a32088b30175fba020875665815a0ef\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/596f34911a32088b30175fba020875665815a0ef\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"ba96b1dc608ee055e5bf9492838b4fbed5dc5db3\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ba96b1dc608ee055e5bf9492838b4fbed5dc5db3\", \"title\": \"ba96b1dc608ee055e5bf9492838b4fbed5dc5db3\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/ba96b1dc608ee055e5bf9492838b4fbed5dc5db3\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"ba96b1dc608ee055e5bf9492838b4fbed5dc5db3\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"ba96b1dc608ee055e5bf9492838b4fbed5dc5db3\", \"got_revision_cp\": \"refs/heads/master@{#38680}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"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-lqe7\"], \"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\": [\"5707-2446c6f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"started_ts": "1613780127843177",
"status": "COMPLETED",
"status_changed_ts": "1613780128190616",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18662",
"builder:infra-continuous-win10-64",
"buildset:commit/git/ba96b1dc608ee055e5bf9492838b4fbed5dc5db3",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/ba96b1dc608ee055e5bf9492838b4fbed5dc5db3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020258199355581152",
"scheduler_job_id:infra/infra-continuous-win10-64",
"swarming_hostname:chromium-swarm-dev.appspot.com",
"swarming_tag:log_location:logdog://luci-logdog-dev.appspot.com/infra/buildbucket/cr-buildbucket-dev.appspot.com/8854828137303102192/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:infra_continuous",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:51d54b98d36ba810",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1613780945814928",
"url": "https://luci-milo-dev.appspot.com/b/8854828137303102192",
"utcnow_ts": "1613780953263381"
}
}
[00:29:13.270] Invocation finished in 1h41m43.141358495s with status SUCCEEDED