[00:39:08.092] New invocation is queued and will start shortly
[00:39:09.190] Starting the invocation (attempt 1)
[00:39:09.226] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[00:39:09.226] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[00:39:09.226] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9032119378649327392",
"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\":\"ba2395588475b56c32b2bc9316a3ead3bfd939a0\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ba2395588475b56c32b2bc9316a3ead3bfd939a0\",\"title\":\"ba2395588475b56c32b2bc9316a3ead3bfd939a0\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/ba2395588475b56c32b2bc9316a3ead3bfd939a0\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"ba2395588475b56c32b2bc9316a3ead3bfd939a0\"}}",
"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:9032119378649327392",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/ba2395588475b56c32b2bc9316a3ead3bfd939a0",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/ba2395588475b56c32b2bc9316a3ead3bfd939a0",
"gitiles_ref:refs/heads/master"
]
}
[00:39:09.857] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:infra",
"created_ts": "1602463149375750",
"id": "8866689316639132720",
"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\": \"ba2395588475b56c32b2bc9316a3ead3bfd939a0\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ba2395588475b56c32b2bc9316a3ead3bfd939a0\", \"title\": \"ba2395588475b56c32b2bc9316a3ead3bfd939a0\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/ba2395588475b56c32b2bc9316a3ead3bfd939a0\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"ba2395588475b56c32b2bc9316a3ead3bfd939a0\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1602463149760858",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17857",
"builder:infra-continuous-win10-64",
"buildset:commit/git/ba2395588475b56c32b2bc9316a3ead3bfd939a0",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/ba2395588475b56c32b2bc9316a3ead3bfd939a0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032119378649327392",
"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/8866689316639132720/+/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": "1602463149760977",
"url": "https://luci-milo-dev.appspot.com/b/8866689316639132720",
"utcnow_ts": "1602463149848949"
}
}
[00:39:09.857] Task URL: https://luci-milo-dev.appspot.com/b/8866689316639132720
[00:39:09.857] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:2:0) after 1m0s
[00:40:09.958] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:2:0)
[00:40:09.958] Timer tick, asking Buildbucket for the build status
[00:40:10.048] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:10.048] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:3:0) after 1m0s
[00:41:10.071] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:3:0)
[00:41:10.071] Timer tick, asking Buildbucket for the build status
[00:41:10.229] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:10.229] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:4:0) after 1m0s
[00:42:10.332] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:4:0)
[00:42:10.332] Timer tick, asking Buildbucket for the build status
[00:42:10.415] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:10.415] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:5:0) after 1m0s
[00:43:10.458] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:5:0)
[00:43:10.458] Timer tick, asking Buildbucket for the build status
[00:43:10.594] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:10.594] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:6:0) after 1m0s
[00:44:10.635] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:6:0)
[00:44:10.635] Timer tick, asking Buildbucket for the build status
[00:44:10.718] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:10.718] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:7:0) after 1m0s
[00:45:10.741] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:7:0)
[00:45:10.741] Timer tick, asking Buildbucket for the build status
[00:45:10.825] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:10.825] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:8:0) after 1m0s
[00:46:10.866] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:8:0)
[00:46:10.866] Timer tick, asking Buildbucket for the build status
[00:46:10.939] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:10.939] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:9:0) after 1m0s
[00:47:10.982] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:9:0)
[00:47:10.982] Timer tick, asking Buildbucket for the build status
[00:47:11.059] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:11.059] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:10:0) after 1m0s
[00:48:11.120] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:10:0)
[00:48:11.120] Timer tick, asking Buildbucket for the build status
[00:48:11.211] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:11.211] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:11:0) after 1m0s
[00:49:11.273] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:11:0)
[00:49:11.273] Timer tick, asking Buildbucket for the build status
[00:49:11.363] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:49:11.363] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:12:0) after 1m0s
[00:50:11.472] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:12:0)
[00:50:11.472] Timer tick, asking Buildbucket for the build status
[00:50:11.568] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:50:11.568] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:13:0) after 1m0s
[00:51:11.674] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:13:0)
[00:51:11.674] Timer tick, asking Buildbucket for the build status
[00:51:11.749] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:11.749] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:14:0) after 1m0s
[00:52:11.862] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:14:0)
[00:52:11.862] Timer tick, asking Buildbucket for the build status
[00:52:11.958] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:11.958] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:15:0) after 1m0s
[00:53:11.985] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:15:0)
[00:53:11.985] Timer tick, asking Buildbucket for the build status
[00:53:12.068] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:12.068] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:16:0) after 1m0s
[00:54:12.158] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:16:0)
[00:54:12.158] Timer tick, asking Buildbucket for the build status
[00:54:12.237] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:54:12.237] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:17:0) after 1m0s
[00:55:12.333] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:17:0)
[00:55:12.333] Timer tick, asking Buildbucket for the build status
[00:55:12.430] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:55:12.430] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:18:0) after 1m0s
[00:56:12.534] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:18:0)
[00:56:12.534] Timer tick, asking Buildbucket for the build status
[00:56:12.643] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:56:12.643] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:19:0) after 1m0s
[00:57:12.694] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:19:0)
[00:57:12.694] Timer tick, asking Buildbucket for the build status
[00:57:12.777] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:12.777] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:20:0) after 1m0s
[00:58:12.832] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:20:0)
[00:58:12.832] Timer tick, asking Buildbucket for the build status
[00:58:12.922] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:12.922] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:21:0) after 1m0s
[00:59:12.942] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:21:0)
[00:59:12.942] Timer tick, asking Buildbucket for the build status
[00:59:13.031] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:13.031] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:22:0) after 1m0s
[01:00:13.148] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:22:0)
[01:00:13.148] Timer tick, asking Buildbucket for the build status
[01:00:13.313] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:13.313] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:23:0) after 1m0s
[01:01:13.359] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:23:0)
[01:01:13.359] Timer tick, asking Buildbucket for the build status
[01:01:13.481] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:13.481] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:24:0) after 1m0s
[01:02:13.508] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:24:0)
[01:02:13.508] Timer tick, asking Buildbucket for the build status
[01:02:13.612] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:13.612] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:25:0) after 1m0s
[01:03:13.638] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:25:0)
[01:03:13.638] Timer tick, asking Buildbucket for the build status
[01:03:13.723] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:13.723] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:26:0) after 1m0s
[01:04:13.749] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:26:0)
[01:04:13.749] Timer tick, asking Buildbucket for the build status
[01:04:13.842] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:13.842] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:27:0) after 1m0s
[01:05:13.867] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:27:0)
[01:05:13.867] Timer tick, asking Buildbucket for the build status
[01:05:13.983] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:13.983] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:28:0) after 1m0s
[01:06:14.042] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:28:0)
[01:06:14.042] Timer tick, asking Buildbucket for the build status
[01:06:14.145] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:14.145] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:29:0) after 1m0s
[01:07:14.174] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:29:0)
[01:07:14.174] Timer tick, asking Buildbucket for the build status
[01:07:14.410] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:14.410] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:30:0) after 1m0s
[01:08:14.444] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:30:0)
[01:08:14.444] Timer tick, asking Buildbucket for the build status
[01:08:14.529] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:14.529] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:31:0) after 1m0s
[01:09:14.630] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:31:0)
[01:09:14.630] Timer tick, asking Buildbucket for the build status
[01:09:14.717] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:14.718] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:32:0) after 1m0s
[01:10:14.751] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:32:0)
[01:10:14.751] Timer tick, asking Buildbucket for the build status
[01:10:14.831] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:14.831] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:33:0) after 1m0s
[01:11:14.859] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:33:0)
[01:11:14.859] Timer tick, asking Buildbucket for the build status
[01:11:14.947] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:14.947] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:34:0) after 1m0s
[01:12:15.039] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:34:0)
[01:12:15.039] Timer tick, asking Buildbucket for the build status
[01:12:15.152] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:15.152] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:35:0) after 1m0s
[01:13:15.233] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:35:0)
[01:13:15.233] Timer tick, asking Buildbucket for the build status
[01:13:15.341] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:15.342] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:36:0) after 1m0s
[01:14:15.371] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:36:0)
[01:14:15.371] Timer tick, asking Buildbucket for the build status
[01:14:15.466] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:15.466] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:37:0) after 1m0s
[01:15:15.574] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:37:0)
[01:15:15.574] Timer tick, asking Buildbucket for the build status
[01:15:15.664] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:15.664] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:38:0) after 1m0s
[01:16:15.734] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:38:0)
[01:16:15.734] Timer tick, asking Buildbucket for the build status
[01:16:15.809] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:15.809] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:39:0) after 1m0s
[01:17:15.869] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:39:0)
[01:17:15.869] Timer tick, asking Buildbucket for the build status
[01:17:15.956] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:17:15.956] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:40:0) after 1m0s
[01:18:15.985] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:40:0)
[01:18:15.985] Timer tick, asking Buildbucket for the build status
[01:18:16.073] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:18:16.073] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:41:0) after 1m0s
[01:19:16.176] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:41:0)
[01:19:16.176] Timer tick, asking Buildbucket for the build status
[01:19:16.326] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:19:16.326] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:42:0) after 1m0s
[01:20:16.447] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:42:0)
[01:20:16.447] Timer tick, asking Buildbucket for the build status
[01:20:16.559] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:20:16.559] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:43:0) after 1m0s
[01:21:16.646] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:43:0)
[01:21:16.646] Timer tick, asking Buildbucket for the build status
[01:21:16.726] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:21:16.727] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:44:0) after 1m0s
[01:22:16.761] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:44:0)
[01:22:16.761] Timer tick, asking Buildbucket for the build status
[01:22:16.844] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:22:16.844] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:45:0) after 1m0s
[01:23:16.935] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:45:0)
[01:23:16.935] Timer tick, asking Buildbucket for the build status
[01:23:17.028] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:17.028] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:46:0) after 1m0s
[01:24:17.062] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:46:0)
[01:24:17.062] Timer tick, asking Buildbucket for the build status
[01:24:17.141] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:24:17.141] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:47:0) after 1m0s
[01:25:17.246] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:47:0)
[01:25:17.246] Timer tick, asking Buildbucket for the build status
[01:25:17.325] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:25:17.325] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:48:0) after 1m0s
[01:26:17.364] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:48:0)
[01:26:17.364] Timer tick, asking Buildbucket for the build status
[01:26:17.437] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:26:17.437] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:49:0) after 1m0s
[01:27:17.548] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:49:0)
[01:27:17.548] Timer tick, asking Buildbucket for the build status
[01:27:17.642] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:27:17.642] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:50:0) after 1m0s
[01:28:17.733] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:50:0)
[01:28:17.733] Timer tick, asking Buildbucket for the build status
[01:28:17.821] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:28:17.821] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:51:0) after 1m0s
[01:29:17.843] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:51:0)
[01:29:17.843] Timer tick, asking Buildbucket for the build status
[01:29:17.940] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:29:17.940] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:52:0) after 1m0s
[01:30:18.035] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:52:0)
[01:30:18.035] Timer tick, asking Buildbucket for the build status
[01:30:18.122] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:30:18.122] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:53:0) after 1m0s
[01:31:18.162] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:53:0)
[01:31:18.162] Timer tick, asking Buildbucket for the build status
[01:31:18.248] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:31:18.249] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:54:0) after 1m0s
[01:32:18.332] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:54:0)
[01:32:18.333] Timer tick, asking Buildbucket for the build status
[01:32:18.430] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:32:18.430] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:55:0) after 1m0s
[01:33:18.452] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:55:0)
[01:33:18.452] Timer tick, asking Buildbucket for the build status
[01:33:18.561] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:33:18.561] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:56:0) after 1m0s
[01:34:18.672] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:56:0)
[01:34:18.672] Timer tick, asking Buildbucket for the build status
[01:34:18.755] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:34:18.755] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:57:0) after 1m0s
[01:35:18.782] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:57:0)
[01:35:18.782] Timer tick, asking Buildbucket for the build status
[01:35:18.945] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:35:18.945] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:58:0) after 1m0s
[01:36:18.985] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:58:0)
[01:36:18.986] Timer tick, asking Buildbucket for the build status
[01:36:19.078] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:36:19.078] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:59:0) after 1m0s
[01:37:19.170] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:59:0)
[01:37:19.170] Timer tick, asking Buildbucket for the build status
[01:37:19.238] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:37:19.238] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:60:0) after 1m0s
[01:38:19.265] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:60:0)
[01:38:19.265] Timer tick, asking Buildbucket for the build status
[01:38:19.424] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:38:19.424] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:61:0) after 1m0s
[01:39:19.471] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:61:0)
[01:39:19.471] Timer tick, asking Buildbucket for the build status
[01:39:19.549] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:39:19.549] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:62:0) after 1m0s
[01:40:19.573] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:62:0)
[01:40:19.573] Timer tick, asking Buildbucket for the build status
[01:40:19.656] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:40:19.656] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:63:0) after 1m0s
[01:41:19.774] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:63:0)
[01:41:19.774] Timer tick, asking Buildbucket for the build status
[01:41:19.847] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:41:19.847] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:64:0) after 1m0s
[01:42:19.950] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:64:0)
[01:42:19.950] Timer tick, asking Buildbucket for the build status
[01:42:20.027] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:42:20.027] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:65:0) after 1m0s
[01:43:20.064] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:65:0)
[01:43:20.064] Timer tick, asking Buildbucket for the build status
[01:43:20.156] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:43:20.156] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:66:0) after 1m0s
[01:44:20.183] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:66:0)
[01:44:20.183] Timer tick, asking Buildbucket for the build status
[01:44:20.271] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:44:20.271] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:67:0) after 1m0s
[01:45:20.301] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:67:0)
[01:45:20.301] Timer tick, asking Buildbucket for the build status
[01:45:20.380] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:45:20.380] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:68:0) after 1m0s
[01:46:20.461] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:68:0)
[01:46:20.461] Timer tick, asking Buildbucket for the build status
[01:46:20.553] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:46:20.553] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:69:0) after 1m0s
[01:47:20.677] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:69:0)
[01:47:20.677] Timer tick, asking Buildbucket for the build status
[01:47:20.837] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:47:20.837] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:70:0) after 1m0s
[01:48:20.946] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:70:0)
[01:48:20.946] Timer tick, asking Buildbucket for the build status
[01:48:21.044] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:48:21.044] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:71:0) after 1m0s
[01:49:21.067] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:71:0)
[01:49:21.067] Timer tick, asking Buildbucket for the build status
[01:49:21.151] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:49:21.151] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:72:0) after 1m0s
[01:50:21.180] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:72:0)
[01:50:21.180] Timer tick, asking Buildbucket for the build status
[01:50:21.263] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:50:21.263] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:73:0) after 1m0s
[01:51:21.287] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:73:0)
[01:51:21.287] Timer tick, asking Buildbucket for the build status
[01:51:21.395] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:51:21.395] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:74:0) after 1m0s
[01:52:21.451] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:74:0)
[01:52:21.451] Timer tick, asking Buildbucket for the build status
[01:52:21.535] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:52:21.535] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:75:0) after 1m0s
[01:53:21.557] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:75:0)
[01:53:21.557] Timer tick, asking Buildbucket for the build status
[01:53:21.638] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:53:21.638] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:76:0) after 1m0s
[01:54:21.745] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:76:0)
[01:54:21.745] Timer tick, asking Buildbucket for the build status
[01:54:21.833] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:54:21.833] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:77:0) after 1m0s
[01:55:21.873] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:77:0)
[01:55:21.873] Timer tick, asking Buildbucket for the build status
[01:55:21.979] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:55:21.979] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:78:0) after 1m0s
[01:56:22.048] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:78:0)
[01:56:22.048] Timer tick, asking Buildbucket for the build status
[01:56:22.154] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:56:22.154] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:79:0) after 1m0s
[01:57:22.179] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:79:0)
[01:57:22.179] Timer tick, asking Buildbucket for the build status
[01:57:22.277] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:57:22.277] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:80:0) after 1m0s
[01:58:22.303] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:80:0)
[01:58:22.303] Timer tick, asking Buildbucket for the build status
[01:58:22.436] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:58:22.436] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:81:0) after 1m0s
[01:59:22.534] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:81:0)
[01:59:22.534] Timer tick, asking Buildbucket for the build status
[01:59:22.610] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:59:22.610] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:82:0) after 1m0s
[02:00:22.654] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:82:0)
[02:00:22.654] Timer tick, asking Buildbucket for the build status
[02:00:22.750] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:00:22.750] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:83:0) after 1m0s
[02:01:22.852] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:83:0)
[02:01:22.852] Timer tick, asking Buildbucket for the build status
[02:01:22.941] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:01:22.941] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:84:0) after 1m0s
[02:02:22.975] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:84:0)
[02:02:22.975] Timer tick, asking Buildbucket for the build status
[02:02:23.073] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:02:23.073] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:85:0) after 1m0s
[02:03:23.110] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:85:0)
[02:03:23.110] Timer tick, asking Buildbucket for the build status
[02:03:23.220] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:03:23.220] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:86:0) after 1m0s
[02:04:23.242] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:86:0)
[02:04:23.242] Timer tick, asking Buildbucket for the build status
[02:04:23.477] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:04:23.477] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:87:0) after 1m0s
[02:05:23.539] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:87:0)
[02:05:23.539] Timer tick, asking Buildbucket for the build status
[02:05:23.836] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:05:23.836] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:88:0) after 1m0s
[02:06:23.917] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:88:0)
[02:06:23.917] Timer tick, asking Buildbucket for the build status
[02:06:24.042] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:06:24.042] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:89:0) after 1m0s
[02:07:24.071] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:89:0)
[02:07:24.071] Timer tick, asking Buildbucket for the build status
[02:07:24.371] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:07:24.371] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:90:0) after 1m0s
[02:08:24.418] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:90:0)
[02:08:24.418] Timer tick, asking Buildbucket for the build status
[02:08:24.594] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:08:24.594] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:91:0) after 1m0s
[02:09:24.621] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:91:0)
[02:09:24.621] Timer tick, asking Buildbucket for the build status
[02:09:24.708] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:09:24.708] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:92:0) after 1m0s
[02:10:24.737] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:92:0)
[02:10:24.737] Timer tick, asking Buildbucket for the build status
[02:10:24.809] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:10:24.809] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:93:0) after 1m0s
[02:11:24.835] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:93:0)
[02:11:24.835] Timer tick, asking Buildbucket for the build status
[02:11:24.908] Build 8866689316639132720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:11:24.908] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:94:0) after 1m0s
[02:12:14.175] Received PubSub notification, asking Buildbucket for the build status
[02:12:14.327] Build 8866689316639132720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:24.970] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:94:0)
[02:12:24.970] Timer tick, asking Buildbucket for the build status
[02:12:25.048] Build 8866689316639132720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:25.048] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:96:0) after 1m0s
[02:13:25.075] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:96:0)
[02:13:25.075] Timer tick, asking Buildbucket for the build status
[02:13:25.154] Build 8866689316639132720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:25.154] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:97:0) after 1m0s
[02:14:25.177] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:97:0)
[02:14:25.177] Timer tick, asking Buildbucket for the build status
[02:14:25.251] Build 8866689316639132720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:25.251] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:98:0) after 1m0s
[02:15:25.361] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:98:0)
[02:15:25.361] Timer tick, asking Buildbucket for the build status
[02:15:25.468] Build 8866689316639132720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:25.468] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:99:0) after 1m0s
[02:16:25.556] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:99:0)
[02:16:25.556] Timer tick, asking Buildbucket for the build status
[02:16:25.656] Build 8866689316639132720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:25.656] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:100:0) after 1m0s
[02:17:25.684] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:100:0)
[02:17:25.684] Timer tick, asking Buildbucket for the build status
[02:17:25.769] Build 8866689316639132720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:25.769] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:101:0) after 1m0s
[02:18:25.877] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:101:0)
[02:18:25.877] Timer tick, asking Buildbucket for the build status
[02:18:25.966] Build 8866689316639132720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:25.966] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:102:0) after 1m0s
[02:19:25.998] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:102:0)
[02:19:25.998] Timer tick, asking Buildbucket for the build status
[02:19:26.079] Build 8866689316639132720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:26.079] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:103:0) after 1m0s
[02:20:26.131] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:103:0)
[02:20:26.131] Timer tick, asking Buildbucket for the build status
[02:20:26.222] Build 8866689316639132720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:26.222] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:104:0) after 1m0s
[02:21:26.340] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:104:0)
[02:21:26.340] Timer tick, asking Buildbucket for the build status
[02:21:26.428] Build 8866689316639132720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:26.428] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9032119378649327392:105:0) after 1m0s
[02:21:44.862] Received PubSub notification, asking Buildbucket for the build status
[02:21:44.955] Build 8866689316639132720: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:21:44.955] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1602469300207127",
"created_by": "project:infra",
"created_ts": "1602463149375750",
"id": "8866689316639132720",
"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\": \"ba2395588475b56c32b2bc9316a3ead3bfd939a0\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ba2395588475b56c32b2bc9316a3ead3bfd939a0\", \"title\": \"ba2395588475b56c32b2bc9316a3ead3bfd939a0\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/ba2395588475b56c32b2bc9316a3ead3bfd939a0\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"ba2395588475b56c32b2bc9316a3ead3bfd939a0\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"ba2395588475b56c32b2bc9316a3ead3bfd939a0\", \"got_revision_cp\": \"refs/heads/master@{#35681}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_82ed111fed7ea8169e482f00b5f3e5338a65daa41208585de67cb0a736b1d538_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_v2\", \"git\", \"goma\", \"infra_gclient_with_go\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"dev-luci-chromium-ci-win10-0-w3qq\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5583-c590254\"], \"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": "1602468722726588",
"status": "COMPLETED",
"status_changed_ts": "1602469301561911",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17857",
"builder:infra-continuous-win10-64",
"buildset:commit/git/ba2395588475b56c32b2bc9316a3ead3bfd939a0",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/ba2395588475b56c32b2bc9316a3ead3bfd939a0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032119378649327392",
"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/8866689316639132720/+/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:4f3310b8a3f65410",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1602469301573422",
"url": "https://luci-milo-dev.appspot.com/b/8866689316639132720",
"utcnow_ts": "1602469304946751"
}
}
[02:21:44.955] Invocation finished in 1h42m36.880099588s with status SUCCEEDED