[05:18:28.011] New invocation is queued and will start shortly
[05:18:29.111] Starting the invocation (attempt 1)
[05:18:29.162] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[05:18:29.162] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[05:18:29.162] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9020233601999324032",
"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\":\"db86f1544980d9bae2261ba476e8861341b71d0a\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@db86f1544980d9bae2261ba476e8861341b71d0a\",\"title\":\"db86f1544980d9bae2261ba476e8861341b71d0a\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/db86f1544980d9bae2261ba476e8861341b71d0a\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"c8a362e9ca624e48f9b1f8deaf1123cb14ae406c\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c8a362e9ca624e48f9b1f8deaf1123cb14ae406c\",\"title\":\"c8a362e9ca624e48f9b1f8deaf1123cb14ae406c\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/c8a362e9ca624e48f9b1f8deaf1123cb14ae406c\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"c8a362e9ca624e48f9b1f8deaf1123cb14ae406c\"}}",
"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:9020233601999324032",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/c8a362e9ca624e48f9b1f8deaf1123cb14ae406c",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/c8a362e9ca624e48f9b1f8deaf1123cb14ae406c",
"gitiles_ref:refs/heads/master"
]
}
[05:18:29.828] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1613798309262411",
"id": "8854803540025936464",
"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\": \"db86f1544980d9bae2261ba476e8861341b71d0a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@db86f1544980d9bae2261ba476e8861341b71d0a\", \"title\": \"db86f1544980d9bae2261ba476e8861341b71d0a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/db86f1544980d9bae2261ba476e8861341b71d0a\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"c8a362e9ca624e48f9b1f8deaf1123cb14ae406c\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c8a362e9ca624e48f9b1f8deaf1123cb14ae406c\", \"title\": \"c8a362e9ca624e48f9b1f8deaf1123cb14ae406c\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/c8a362e9ca624e48f9b1f8deaf1123cb14ae406c\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"c8a362e9ca624e48f9b1f8deaf1123cb14ae406c\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613798309707371",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18666",
"builder:infra-continuous-win10-64",
"buildset:commit/git/c8a362e9ca624e48f9b1f8deaf1123cb14ae406c",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/c8a362e9ca624e48f9b1f8deaf1123cb14ae406c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020233601999324032",
"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/8854803540025936464/+/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": "1613798309707473",
"url": "https://luci-milo-dev.appspot.com/b/8854803540025936464",
"utcnow_ts": "1613798309821819"
}
}
[05:18:29.828] Task URL: https://luci-milo-dev.appspot.com/b/8854803540025936464
[05:18:29.828] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:2:0) after 1m0s
[05:19:29.901] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:2:0)
[05:19:29.901] Timer tick, asking Buildbucket for the build status
[05:19:29.972] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:19:29.972] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:3:0) after 1m0s
[05:20:29.991] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:3:0)
[05:20:29.991] Timer tick, asking Buildbucket for the build status
[05:20:30.064] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:20:30.064] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:4:0) after 1m0s
[05:21:30.107] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:4:0)
[05:21:30.107] Timer tick, asking Buildbucket for the build status
[05:21:30.178] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:21:30.178] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:5:0) after 1m0s
[05:22:30.204] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:5:0)
[05:22:30.204] Timer tick, asking Buildbucket for the build status
[05:22:30.270] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:22:30.270] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:6:0) after 1m0s
[05:23:30.288] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:6:0)
[05:23:30.288] Timer tick, asking Buildbucket for the build status
[05:23:30.367] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:23:30.367] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:7:0) after 1m0s
[05:24:30.414] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:7:0)
[05:24:30.414] Timer tick, asking Buildbucket for the build status
[05:24:30.539] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:24:30.539] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:8:0) after 1m0s
[05:25:30.593] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:8:0)
[05:25:30.593] Timer tick, asking Buildbucket for the build status
[05:25:30.743] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:25:30.743] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:9:0) after 1m0s
[05:26:30.802] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:9:0)
[05:26:30.802] Timer tick, asking Buildbucket for the build status
[05:26:30.880] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:26:30.880] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:10:0) after 1m0s
[05:27:30.899] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:10:0)
[05:27:30.899] Timer tick, asking Buildbucket for the build status
[05:27:31.058] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:27:31.058] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:11:0) after 1m0s
[05:28:31.074] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:11:0)
[05:28:31.074] Timer tick, asking Buildbucket for the build status
[05:28:31.155] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:28:31.155] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:12:0) after 1m0s
[05:29:31.174] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:12:0)
[05:29:31.174] Timer tick, asking Buildbucket for the build status
[05:29:31.250] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:29:31.250] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:13:0) after 1m0s
[05:30:31.291] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:13:0)
[05:30:31.291] Timer tick, asking Buildbucket for the build status
[05:30:31.374] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:30:31.374] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:14:0) after 1m0s
[05:31:31.399] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:14:0)
[05:31:31.399] Timer tick, asking Buildbucket for the build status
[05:31:31.473] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:31:31.473] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:15:0) after 1m0s
[05:32:31.497] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:15:0)
[05:32:31.497] Timer tick, asking Buildbucket for the build status
[05:32:31.567] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:32:31.567] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:16:0) after 1m0s
[05:33:31.595] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:16:0)
[05:33:31.595] Timer tick, asking Buildbucket for the build status
[05:33:31.733] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:33:31.733] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:17:0) after 1m0s
[05:34:31.802] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:17:0)
[05:34:31.802] Timer tick, asking Buildbucket for the build status
[05:34:31.872] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:34:31.872] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:18:0) after 1m0s
[05:35:31.903] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:18:0)
[05:35:31.903] Timer tick, asking Buildbucket for the build status
[05:35:31.988] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:35:31.988] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:19:0) after 1m0s
[05:36:32.107] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:19:0)
[05:36:32.107] Timer tick, asking Buildbucket for the build status
[05:36:32.196] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:36:32.196] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:20:0) after 1m0s
[05:37:32.300] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:20:0)
[05:37:32.300] Timer tick, asking Buildbucket for the build status
[05:37:32.379] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:37:32.379] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:21:0) after 1m0s
[05:38:32.407] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:21:0)
[05:38:32.407] Timer tick, asking Buildbucket for the build status
[05:38:32.492] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:38:32.492] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:22:0) after 1m0s
[05:39:32.599] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:22:0)
[05:39:32.599] Timer tick, asking Buildbucket for the build status
[05:39:32.735] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:39:32.735] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:23:0) after 1m0s
[05:40:32.792] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:23:0)
[05:40:32.792] Timer tick, asking Buildbucket for the build status
[05:40:32.861] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:40:32.861] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:24:0) after 1m0s
[05:41:32.908] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:24:0)
[05:41:32.908] Timer tick, asking Buildbucket for the build status
[05:41:32.981] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:41:32.981] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:25:0) after 1m0s
[05:42:33.009] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:25:0)
[05:42:33.009] Timer tick, asking Buildbucket for the build status
[05:42:33.073] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:42:33.073] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:26:0) after 1m0s
[05:43:33.104] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:26:0)
[05:43:33.104] Timer tick, asking Buildbucket for the build status
[05:43:33.172] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:43:33.172] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:27:0) after 1m0s
[05:44:33.192] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:27:0)
[05:44:33.192] Timer tick, asking Buildbucket for the build status
[05:44:33.267] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:44:33.267] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:28:0) after 1m0s
[05:45:33.302] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:28:0)
[05:45:33.302] Timer tick, asking Buildbucket for the build status
[05:45:33.373] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:45:33.373] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:29:0) after 1m0s
[05:46:33.390] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:29:0)
[05:46:33.390] Timer tick, asking Buildbucket for the build status
[05:46:33.533] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:46:33.533] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:30:0) after 1m0s
[05:47:33.601] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:30:0)
[05:47:33.601] Timer tick, asking Buildbucket for the build status
[05:47:33.679] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:47:33.679] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:31:0) after 1m0s
[05:48:33.705] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:31:0)
[05:48:33.705] Timer tick, asking Buildbucket for the build status
[05:48:33.772] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:48:33.772] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:32:0) after 1m0s
[05:49:33.792] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:32:0)
[05:49:33.792] Timer tick, asking Buildbucket for the build status
[05:49:33.937] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:49:33.937] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:33:0) after 1m0s
[05:50:33.955] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:33:0)
[05:50:33.955] Timer tick, asking Buildbucket for the build status
[05:50:34.021] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:50:34.021] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:34:0) after 1m0s
[05:51:34.118] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:34:0)
[05:51:34.118] Timer tick, asking Buildbucket for the build status
[05:51:34.205] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:51:34.205] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:35:0) after 1m0s
[05:52:34.301] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:35:0)
[05:52:34.301] Timer tick, asking Buildbucket for the build status
[05:52:34.373] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:52:34.373] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:36:0) after 1m0s
[05:53:34.405] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:36:0)
[05:53:34.405] Timer tick, asking Buildbucket for the build status
[05:53:34.479] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:53:34.479] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:37:0) after 1m0s
[05:54:34.498] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:37:0)
[05:54:34.498] Timer tick, asking Buildbucket for the build status
[05:54:34.562] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:54:34.562] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:38:0) after 1m0s
[05:55:34.584] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:38:0)
[05:55:34.584] Timer tick, asking Buildbucket for the build status
[05:55:34.655] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:55:34.655] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:39:0) after 1m0s
[05:56:34.719] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:39:0)
[05:56:34.719] Timer tick, asking Buildbucket for the build status
[05:56:34.847] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:56:34.847] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:40:0) after 1m0s
[05:57:34.896] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:40:0)
[05:57:34.896] Timer tick, asking Buildbucket for the build status
[05:57:34.989] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:57:34.989] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:41:0) after 1m0s
[05:58:35.111] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:41:0)
[05:58:35.111] Timer tick, asking Buildbucket for the build status
[05:58:35.181] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:58:35.181] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:42:0) after 1m0s
[05:59:35.203] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:42:0)
[05:59:35.203] Timer tick, asking Buildbucket for the build status
[05:59:35.270] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:59:35.270] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:43:0) after 1m0s
[06:00:35.303] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:43:0)
[06:00:35.303] Timer tick, asking Buildbucket for the build status
[06:00:35.381] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:00:35.381] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:44:0) after 1m0s
[06:01:35.405] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:44:0)
[06:01:35.405] Timer tick, asking Buildbucket for the build status
[06:01:35.540] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:01:35.540] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:45:0) after 1m0s
[06:02:35.600] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:45:0)
[06:02:35.600] Timer tick, asking Buildbucket for the build status
[06:02:35.686] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:02:35.686] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:46:0) after 1m0s
[06:03:35.803] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:46:0)
[06:03:35.803] Timer tick, asking Buildbucket for the build status
[06:03:35.887] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:03:35.887] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:47:0) after 1m0s
[06:04:35.992] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:47:0)
[06:04:35.992] Timer tick, asking Buildbucket for the build status
[06:04:36.067] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:04:36.067] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:48:0) after 1m0s
[06:05:36.098] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:48:0)
[06:05:36.098] Timer tick, asking Buildbucket for the build status
[06:05:36.172] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:05:36.172] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:49:0) after 1m0s
[06:06:36.201] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:49:0)
[06:06:36.201] Timer tick, asking Buildbucket for the build status
[06:06:36.284] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:06:36.284] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:50:0) after 1m0s
[06:07:36.307] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:50:0)
[06:07:36.307] Timer tick, asking Buildbucket for the build status
[06:07:36.378] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:07:36.378] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:51:0) after 1m0s
[06:08:36.397] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:51:0)
[06:08:36.397] Timer tick, asking Buildbucket for the build status
[06:08:36.479] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:08:36.479] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:52:0) after 1m0s
[06:09:36.507] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:52:0)
[06:09:36.507] Timer tick, asking Buildbucket for the build status
[06:09:36.587] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:09:36.587] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:53:0) after 1m0s
[06:10:36.634] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:53:0)
[06:10:36.634] Timer tick, asking Buildbucket for the build status
[06:10:36.704] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:10:36.704] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:54:0) after 1m0s
[06:11:36.804] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:54:0)
[06:11:36.804] Timer tick, asking Buildbucket for the build status
[06:11:36.866] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:11:36.866] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:55:0) after 1m0s
[06:12:36.902] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:55:0)
[06:12:36.902] Timer tick, asking Buildbucket for the build status
[06:12:36.974] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:12:36.974] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:56:0) after 1m0s
[06:13:37.006] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:56:0)
[06:13:37.006] Timer tick, asking Buildbucket for the build status
[06:13:37.090] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:13:37.090] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:57:0) after 1m0s
[06:14:37.203] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:57:0)
[06:14:37.203] Timer tick, asking Buildbucket for the build status
[06:14:37.341] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:14:37.341] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:58:0) after 1m0s
[06:15:37.399] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:58:0)
[06:15:37.399] Timer tick, asking Buildbucket for the build status
[06:15:37.466] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:15:37.466] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:59:0) after 1m0s
[06:16:37.507] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:59:0)
[06:16:37.507] Timer tick, asking Buildbucket for the build status
[06:16:37.586] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:16:37.586] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:60:0) after 1m0s
[06:17:37.613] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:60:0)
[06:17:37.613] Timer tick, asking Buildbucket for the build status
[06:17:37.681] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:17:37.681] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:61:0) after 1m0s
[06:18:37.702] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:61:0)
[06:18:37.702] Timer tick, asking Buildbucket for the build status
[06:18:37.838] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:18:37.838] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:62:0) after 1m0s
[06:19:37.901] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:62:0)
[06:19:37.901] Timer tick, asking Buildbucket for the build status
[06:19:37.966] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:19:37.966] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:63:0) after 1m0s
[06:20:38.004] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:63:0)
[06:20:38.004] Timer tick, asking Buildbucket for the build status
[06:20:38.079] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:20:38.079] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:64:0) after 1m0s
[06:21:38.103] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:64:0)
[06:21:38.103] Timer tick, asking Buildbucket for the build status
[06:21:38.176] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:21:38.176] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:65:0) after 1m0s
[06:22:38.217] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:65:0)
[06:22:38.217] Timer tick, asking Buildbucket for the build status
[06:22:38.293] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:22:38.293] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:66:0) after 1m0s
[06:23:38.392] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:66:0)
[06:23:38.392] Timer tick, asking Buildbucket for the build status
[06:23:38.481] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:23:38.481] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:67:0) after 1m0s
[06:24:38.502] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:67:0)
[06:24:38.502] Timer tick, asking Buildbucket for the build status
[06:24:38.586] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:24:38.586] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:68:0) after 1m0s
[06:25:38.709] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:68:0)
[06:25:38.709] Timer tick, asking Buildbucket for the build status
[06:25:38.787] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:25:38.787] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:69:0) after 1m0s
[06:26:38.904] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:69:0)
[06:26:38.904] Timer tick, asking Buildbucket for the build status
[06:26:39.042] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:26:39.042] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:70:0) after 1m0s
[06:27:39.095] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:70:0)
[06:27:39.095] Timer tick, asking Buildbucket for the build status
[06:27:39.169] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:27:39.169] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:71:0) after 1m0s
[06:28:39.191] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:71:0)
[06:28:39.191] Timer tick, asking Buildbucket for the build status
[06:28:39.265] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:28:39.265] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:72:0) after 1m0s
[06:29:39.289] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:72:0)
[06:29:39.289] Timer tick, asking Buildbucket for the build status
[06:29:39.352] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:29:39.352] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:73:0) after 1m0s
[06:30:39.373] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:73:0)
[06:30:39.373] Timer tick, asking Buildbucket for the build status
[06:30:39.447] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:30:39.447] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:74:0) after 1m0s
[06:31:39.501] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:74:0)
[06:31:39.501] Timer tick, asking Buildbucket for the build status
[06:31:39.570] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:31:39.570] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:75:0) after 1m0s
[06:32:39.596] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:75:0)
[06:32:39.596] Timer tick, asking Buildbucket for the build status
[06:32:39.671] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:32:39.671] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:76:0) after 1m0s
[06:33:39.780] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:76:0)
[06:33:39.780] Timer tick, asking Buildbucket for the build status
[06:33:39.932] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:33:39.932] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:77:0) after 1m0s
[06:34:39.993] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:77:0)
[06:34:39.993] Timer tick, asking Buildbucket for the build status
[06:34:40.136] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:34:40.136] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:78:0) after 1m0s
[06:35:40.213] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:78:0)
[06:35:40.213] Timer tick, asking Buildbucket for the build status
[06:35:40.281] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:35:40.281] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:79:0) after 1m0s
[06:36:40.308] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:79:0)
[06:36:40.308] Timer tick, asking Buildbucket for the build status
[06:36:40.387] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:36:40.388] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:80:0) after 1m0s
[06:37:40.505] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:80:0)
[06:37:40.505] Timer tick, asking Buildbucket for the build status
[06:37:40.574] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:37:40.574] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:81:0) after 1m0s
[06:38:40.611] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:81:0)
[06:38:40.611] Timer tick, asking Buildbucket for the build status
[06:38:40.692] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:38:40.692] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:82:0) after 1m0s
[06:39:40.726] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:82:0)
[06:39:40.726] Timer tick, asking Buildbucket for the build status
[06:39:40.825] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:39:40.825] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:83:0) after 1m0s
[06:40:40.895] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:83:0)
[06:40:40.895] Timer tick, asking Buildbucket for the build status
[06:40:40.967] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:40:40.967] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:84:0) after 1m0s
[06:41:40.995] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:84:0)
[06:41:40.995] Timer tick, asking Buildbucket for the build status
[06:41:42.011] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:41:42.011] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:85:0) after 1m0s
[06:42:42.033] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:85:0)
[06:42:42.033] Timer tick, asking Buildbucket for the build status
[06:42:42.134] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:42:42.134] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:86:0) after 1m0s
[06:43:42.175] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:86:0)
[06:43:42.175] Timer tick, asking Buildbucket for the build status
[06:43:42.258] Build 8854803540025936464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:43:42.258] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:87:0) after 1m0s
[06:44:12.810] Received PubSub notification, asking Buildbucket for the build status
[06:44:12.881] Build 8854803540025936464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:42.294] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:87:0)
[06:44:42.294] Timer tick, asking Buildbucket for the build status
[06:44:42.394] Build 8854803540025936464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:42.394] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:89:0) after 1m0s
[06:45:42.424] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:89:0)
[06:45:42.424] Timer tick, asking Buildbucket for the build status
[06:45:42.501] Build 8854803540025936464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:42.501] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:90:0) after 1m0s
[06:46:42.611] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:90:0)
[06:46:42.611] Timer tick, asking Buildbucket for the build status
[06:46:42.691] Build 8854803540025936464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:42.691] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:91:0) after 1m0s
[06:47:42.803] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:91:0)
[06:47:42.803] Timer tick, asking Buildbucket for the build status
[06:47:42.877] Build 8854803540025936464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:42.877] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:92:0) after 1m0s
[06:48:42.904] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:92:0)
[06:48:42.904] Timer tick, asking Buildbucket for the build status
[06:48:42.974] Build 8854803540025936464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:42.974] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:93:0) after 1m0s
[06:49:43.007] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:93:0)
[06:49:43.007] Timer tick, asking Buildbucket for the build status
[06:49:43.164] Build 8854803540025936464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:43.164] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:94:0) after 1m0s
[06:50:43.210] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:94:0)
[06:50:43.210] Timer tick, asking Buildbucket for the build status
[06:50:43.286] Build 8854803540025936464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:43.286] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:95:0) after 1m0s
[06:51:43.398] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:95:0)
[06:51:43.398] Timer tick, asking Buildbucket for the build status
[06:51:43.482] Build 8854803540025936464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:43.482] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:96:0) after 1m0s
[06:52:43.617] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:96:0)
[06:52:43.617] Timer tick, asking Buildbucket for the build status
[06:52:43.687] Build 8854803540025936464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:43.687] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:97:0) after 1m0s
[06:53:43.712] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:97:0)
[06:53:43.712] Timer tick, asking Buildbucket for the build status
[06:53:43.787] Build 8854803540025936464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:43.787] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:98:0) after 1m0s
[06:54:43.903] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:98:0)
[06:54:43.903] Timer tick, asking Buildbucket for the build status
[06:54:43.998] Build 8854803540025936464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:43.998] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:99:0) after 1m0s
[06:55:44.111] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:99:0)
[06:55:44.111] Timer tick, asking Buildbucket for the build status
[06:55:44.268] Build 8854803540025936464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:44.268] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:100:0) after 1m0s
[06:56:44.295] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:100:0)
[06:56:44.295] Timer tick, asking Buildbucket for the build status
[06:56:44.374] Build 8854803540025936464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:44.374] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020233601999324032:101:0) after 1m0s
[06:57:19.420] Received PubSub notification, asking Buildbucket for the build status
[06:57:19.516] Build 8854803540025936464: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:57:19.516] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1613804238944615",
"created_by": "project:infra",
"created_ts": "1613798309262411",
"id": "8854803540025936464",
"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\": \"db86f1544980d9bae2261ba476e8861341b71d0a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@db86f1544980d9bae2261ba476e8861341b71d0a\", \"title\": \"db86f1544980d9bae2261ba476e8861341b71d0a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/db86f1544980d9bae2261ba476e8861341b71d0a\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"c8a362e9ca624e48f9b1f8deaf1123cb14ae406c\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c8a362e9ca624e48f9b1f8deaf1123cb14ae406c\", \"title\": \"c8a362e9ca624e48f9b1f8deaf1123cb14ae406c\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/c8a362e9ca624e48f9b1f8deaf1123cb14ae406c\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"c8a362e9ca624e48f9b1f8deaf1123cb14ae406c\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"c8a362e9ca624e48f9b1f8deaf1123cb14ae406c\", \"got_revision_cp\": \"refs/heads/master@{#38688}\"}, \"swarming\": {\"bot_dimensions\": {\"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-4rxr\"], \"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": "1613803451204242",
"status": "COMPLETED",
"status_changed_ts": "1613803451520640",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18666",
"builder:infra-continuous-win10-64",
"buildset:commit/git/c8a362e9ca624e48f9b1f8deaf1123cb14ae406c",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/c8a362e9ca624e48f9b1f8deaf1123cb14ae406c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020233601999324032",
"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/8854803540025936464/+/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:51d6b1884b976910",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1613804238945139",
"url": "https://luci-milo-dev.appspot.com/b/8854803540025936464",
"utcnow_ts": "1613804239506799"
}
}
[06:57:19.516] Invocation finished in 1h38m51.52019796s with status SUCCEEDED