[19:49:02.242] New invocation is queued and will start shortly
[19:49:03.311] Starting the invocation (attempt 1)
[19:49:03.351] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[19:49:03.355] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[19:49:03.355] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9019001069876408000",
"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\":\"0bdc0b01e7e395818c1a206cbe0bb75a45f74a3d\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0bdc0b01e7e395818c1a206cbe0bb75a45f74a3d\",\"title\":\"0bdc0b01e7e395818c1a206cbe0bb75a45f74a3d\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/0bdc0b01e7e395818c1a206cbe0bb75a45f74a3d\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"7fa86f3e8a9ee3ac4cefb0cffab74876ae128f8a\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@7fa86f3e8a9ee3ac4cefb0cffab74876ae128f8a\",\"title\":\"7fa86f3e8a9ee3ac4cefb0cffab74876ae128f8a\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/7fa86f3e8a9ee3ac4cefb0cffab74876ae128f8a\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"ca70237ee4003d441806235da39310a4b5344f99\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ca70237ee4003d441806235da39310a4b5344f99\",\"title\":\"ca70237ee4003d441806235da39310a4b5344f99\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/ca70237ee4003d441806235da39310a4b5344f99\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"a46e5eab5c0f5ba393c00c1741514e34c07ace46\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a46e5eab5c0f5ba393c00c1741514e34c07ace46\",\"title\":\"a46e5eab5c0f5ba393c00c1741514e34c07ace46\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/a46e5eab5c0f5ba393c00c1741514e34c07ace46\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"d2c2fe9849303550a7032ab1f097b2c190093652\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d2c2fe9849303550a7032ab1f097b2c190093652\",\"title\":\"d2c2fe9849303550a7032ab1f097b2c190093652\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/d2c2fe9849303550a7032ab1f097b2c190093652\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"ddbf05390e26c766538bafad5ee815a3e7568aea\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ddbf05390e26c766538bafad5ee815a3e7568aea\",\"title\":\"ddbf05390e26c766538bafad5ee815a3e7568aea\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/ddbf05390e26c766538bafad5ee815a3e7568aea\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"ddbf05390e26c766538bafad5ee815a3e7568aea\"}}",
"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:9019001069876408000",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/ddbf05390e26c766538bafad5ee815a3e7568aea",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/ddbf05390e26c766538bafad5ee815a3e7568aea",
"gitiles_ref:refs/heads/master"
]
}
[19:49:03.951] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:infra",
"created_ts": "1614973743453453",
"id": "8853571007943100864",
"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\": \"0bdc0b01e7e395818c1a206cbe0bb75a45f74a3d\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0bdc0b01e7e395818c1a206cbe0bb75a45f74a3d\", \"title\": \"0bdc0b01e7e395818c1a206cbe0bb75a45f74a3d\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0bdc0b01e7e395818c1a206cbe0bb75a45f74a3d\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"7fa86f3e8a9ee3ac4cefb0cffab74876ae128f8a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@7fa86f3e8a9ee3ac4cefb0cffab74876ae128f8a\", \"title\": \"7fa86f3e8a9ee3ac4cefb0cffab74876ae128f8a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/7fa86f3e8a9ee3ac4cefb0cffab74876ae128f8a\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"ca70237ee4003d441806235da39310a4b5344f99\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ca70237ee4003d441806235da39310a4b5344f99\", \"title\": \"ca70237ee4003d441806235da39310a4b5344f99\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/ca70237ee4003d441806235da39310a4b5344f99\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"a46e5eab5c0f5ba393c00c1741514e34c07ace46\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a46e5eab5c0f5ba393c00c1741514e34c07ace46\", \"title\": \"a46e5eab5c0f5ba393c00c1741514e34c07ace46\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/a46e5eab5c0f5ba393c00c1741514e34c07ace46\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"d2c2fe9849303550a7032ab1f097b2c190093652\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d2c2fe9849303550a7032ab1f097b2c190093652\", \"title\": \"d2c2fe9849303550a7032ab1f097b2c190093652\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/d2c2fe9849303550a7032ab1f097b2c190093652\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"ddbf05390e26c766538bafad5ee815a3e7568aea\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ddbf05390e26c766538bafad5ee815a3e7568aea\", \"title\": \"ddbf05390e26c766538bafad5ee815a3e7568aea\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/ddbf05390e26c766538bafad5ee815a3e7568aea\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"ddbf05390e26c766538bafad5ee815a3e7568aea\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614973743835929",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18792",
"builder:infra-continuous-win10-64",
"buildset:commit/git/ddbf05390e26c766538bafad5ee815a3e7568aea",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/ddbf05390e26c766538bafad5ee815a3e7568aea",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019001069876408000",
"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/8853571007943100864/+/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": "1614973743836031",
"url": "https://luci-milo-dev.appspot.com/b/8853571007943100864",
"utcnow_ts": "1614973743943170"
}
}
[19:49:03.951] Task URL: https://luci-milo-dev.appspot.com/b/8853571007943100864
[19:49:03.951] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:2:0) after 1m0s
[19:50:03.986] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:2:0)
[19:50:03.986] Timer tick, asking Buildbucket for the build status
[19:50:04.070] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:50:04.070] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:3:0) after 1m0s
[19:51:04.095] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:3:0)
[19:51:04.095] Timer tick, asking Buildbucket for the build status
[19:51:04.181] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:51:04.181] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:4:0) after 1m0s
[19:52:04.290] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:4:0)
[19:52:04.290] Timer tick, asking Buildbucket for the build status
[19:52:04.439] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:52:04.439] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:5:0) after 1m0s
[19:53:04.580] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:5:0)
[19:53:04.580] Timer tick, asking Buildbucket for the build status
[19:53:04.665] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:53:04.665] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:6:0) after 1m0s
[19:54:04.683] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:6:0)
[19:54:04.683] Timer tick, asking Buildbucket for the build status
[19:54:04.835] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:54:04.835] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:7:0) after 1m0s
[19:55:04.855] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:7:0)
[19:55:04.855] Timer tick, asking Buildbucket for the build status
[19:55:04.937] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:55:04.937] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:8:0) after 1m0s
[19:56:04.989] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:8:0)
[19:56:04.989] Timer tick, asking Buildbucket for the build status
[19:56:05.072] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:56:05.072] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:9:0) after 1m0s
[19:57:05.142] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:9:0)
[19:57:05.142] Timer tick, asking Buildbucket for the build status
[19:57:05.223] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:57:05.223] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:10:0) after 1m0s
[19:58:05.255] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:10:0)
[19:58:05.255] Timer tick, asking Buildbucket for the build status
[19:58:05.327] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:58:05.327] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:11:0) after 1m0s
[19:59:05.390] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:11:0)
[19:59:05.390] Timer tick, asking Buildbucket for the build status
[19:59:05.478] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:59:05.478] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:12:0) after 1m0s
[20:00:05.591] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:12:0)
[20:00:05.592] Timer tick, asking Buildbucket for the build status
[20:00:05.745] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:00:05.745] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:13:0) after 1m0s
[20:01:05.790] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:13:0)
[20:01:05.790] Timer tick, asking Buildbucket for the build status
[20:01:05.958] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:01:05.958] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:14:0) after 1m0s
[20:02:06.042] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:14:0)
[20:02:06.042] Timer tick, asking Buildbucket for the build status
[20:02:06.156] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:02:06.156] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:15:0) after 1m0s
[20:03:06.201] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:15:0)
[20:03:06.202] Timer tick, asking Buildbucket for the build status
[20:03:06.291] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:03:06.291] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:16:0) after 1m0s
[20:04:06.327] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:16:0)
[20:04:06.327] Timer tick, asking Buildbucket for the build status
[20:04:06.409] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:04:06.409] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:17:0) after 1m0s
[20:05:06.491] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:17:0)
[20:05:06.491] Timer tick, asking Buildbucket for the build status
[20:05:06.567] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:05:06.567] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:18:0) after 1m0s
[20:06:06.643] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:18:0)
[20:06:06.643] Timer tick, asking Buildbucket for the build status
[20:06:06.720] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:06:06.720] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:19:0) after 1m0s
[20:07:06.802] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:19:0)
[20:07:06.802] Timer tick, asking Buildbucket for the build status
[20:07:06.891] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:07:06.891] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:20:0) after 1m0s
[20:08:06.946] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:20:0)
[20:08:06.946] Timer tick, asking Buildbucket for the build status
[20:08:07.021] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:08:07.021] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:21:0) after 1m0s
[20:09:07.094] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:21:0)
[20:09:07.094] Timer tick, asking Buildbucket for the build status
[20:09:07.183] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:09:07.184] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:22:0) after 1m0s
[20:10:07.305] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:22:0)
[20:10:07.305] Timer tick, asking Buildbucket for the build status
[20:10:07.430] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:10:07.430] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:23:0) after 1m0s
[20:11:07.506] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:23:0)
[20:11:07.506] Timer tick, asking Buildbucket for the build status
[20:11:07.591] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:11:07.591] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:24:0) after 1m0s
[20:12:07.700] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:24:0)
[20:12:07.700] Timer tick, asking Buildbucket for the build status
[20:12:07.789] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:12:07.789] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:25:0) after 1m0s
[20:13:07.920] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:25:0)
[20:13:07.920] Timer tick, asking Buildbucket for the build status
[20:13:08.002] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:13:08.002] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:26:0) after 1m0s
[20:14:08.054] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:26:0)
[20:14:08.054] Timer tick, asking Buildbucket for the build status
[20:14:08.127] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:14:08.127] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:27:0) after 1m0s
[20:15:08.145] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:27:0)
[20:15:08.145] Timer tick, asking Buildbucket for the build status
[20:15:08.218] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:15:08.218] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:28:0) after 1m0s
[20:16:08.302] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:28:0)
[20:16:08.302] Timer tick, asking Buildbucket for the build status
[20:16:08.390] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:16:08.390] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:29:0) after 1m0s
[20:17:08.519] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:29:0)
[20:17:08.519] Timer tick, asking Buildbucket for the build status
[20:17:08.599] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:17:08.599] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:30:0) after 1m0s
[20:18:08.656] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:30:0)
[20:18:08.656] Timer tick, asking Buildbucket for the build status
[20:18:08.743] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:18:08.743] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:31:0) after 1m0s
[20:19:08.852] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:31:0)
[20:19:08.852] Timer tick, asking Buildbucket for the build status
[20:19:08.944] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:19:08.944] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:32:0) after 1m0s
[20:20:09.003] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:32:0)
[20:20:09.003] Timer tick, asking Buildbucket for the build status
[20:20:09.111] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:20:09.111] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:33:0) after 1m0s
[20:21:09.133] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:33:0)
[20:21:09.133] Timer tick, asking Buildbucket for the build status
[20:21:09.213] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:21:09.213] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:34:0) after 1m0s
[20:22:09.252] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:34:0)
[20:22:09.252] Timer tick, asking Buildbucket for the build status
[20:22:09.331] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:22:09.331] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:35:0) after 1m0s
[20:23:09.367] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:35:0)
[20:23:09.367] Timer tick, asking Buildbucket for the build status
[20:23:09.448] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:23:09.448] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:36:0) after 1m0s
[20:24:09.555] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:36:0)
[20:24:09.555] Timer tick, asking Buildbucket for the build status
[20:24:09.632] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:24:09.632] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:37:0) after 1m0s
[20:25:09.654] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:37:0)
[20:25:09.654] Timer tick, asking Buildbucket for the build status
[20:25:09.752] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:25:09.752] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:38:0) after 1m0s
[20:26:09.791] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:38:0)
[20:26:09.791] Timer tick, asking Buildbucket for the build status
[20:26:09.875] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:26:09.875] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:39:0) after 1m0s
[20:27:09.903] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:39:0)
[20:27:09.903] Timer tick, asking Buildbucket for the build status
[20:27:10.028] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:27:10.028] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:40:0) after 1m0s
[20:28:10.121] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:40:0)
[20:28:10.121] Timer tick, asking Buildbucket for the build status
[20:28:10.245] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:28:10.245] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:41:0) after 1m0s
[20:29:10.266] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:41:0)
[20:29:10.266] Timer tick, asking Buildbucket for the build status
[20:29:10.345] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:29:10.345] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:42:0) after 1m0s
[20:30:10.372] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:42:0)
[20:30:10.372] Timer tick, asking Buildbucket for the build status
[20:30:10.527] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:30:10.527] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:43:0) after 1m0s
[20:31:10.605] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:43:0)
[20:31:10.605] Timer tick, asking Buildbucket for the build status
[20:31:10.690] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:31:10.690] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:44:0) after 1m0s
[20:32:10.803] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:44:0)
[20:32:10.803] Timer tick, asking Buildbucket for the build status
[20:32:10.901] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:32:10.901] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:45:0) after 1m0s
[20:33:10.953] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:45:0)
[20:33:10.953] Timer tick, asking Buildbucket for the build status
[20:33:11.032] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:33:11.032] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:46:0) after 1m0s
[20:34:11.053] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:46:0)
[20:34:11.053] Timer tick, asking Buildbucket for the build status
[20:34:11.130] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:34:11.130] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:47:0) after 1m0s
[20:35:11.148] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:47:0)
[20:35:11.148] Timer tick, asking Buildbucket for the build status
[20:35:11.229] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:35:11.229] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:48:0) after 1m0s
[20:36:11.309] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:48:0)
[20:36:11.309] Timer tick, asking Buildbucket for the build status
[20:36:11.436] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:36:11.436] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:49:0) after 1m0s
[20:37:11.460] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:49:0)
[20:37:11.460] Timer tick, asking Buildbucket for the build status
[20:37:11.547] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:37:11.547] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:50:0) after 1m0s
[20:38:11.618] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:50:0)
[20:38:11.618] Timer tick, asking Buildbucket for the build status
[20:38:11.687] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:38:11.687] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:51:0) after 1m0s
[20:39:11.761] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:51:0)
[20:39:11.761] Timer tick, asking Buildbucket for the build status
[20:39:11.835] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:39:11.835] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:52:0) after 1m0s
[20:40:11.943] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:52:0)
[20:40:11.943] Timer tick, asking Buildbucket for the build status
[20:40:12.026] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:40:12.026] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:53:0) after 1m0s
[20:41:12.153] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:53:0)
[20:41:12.153] Timer tick, asking Buildbucket for the build status
[20:41:12.246] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:41:12.246] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:54:0) after 1m0s
[20:42:12.371] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:54:0)
[20:42:12.371] Timer tick, asking Buildbucket for the build status
[20:42:12.440] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:42:12.440] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:55:0) after 1m0s
[20:43:12.552] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:55:0)
[20:43:12.552] Timer tick, asking Buildbucket for the build status
[20:43:12.642] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:43:12.642] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:56:0) after 1m0s
[20:44:12.661] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:56:0)
[20:44:12.661] Timer tick, asking Buildbucket for the build status
[20:44:12.740] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:44:12.740] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:57:0) after 1m0s
[20:45:12.798] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:57:0)
[20:45:12.798] Timer tick, asking Buildbucket for the build status
[20:45:12.877] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:45:12.877] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:58:0) after 1m0s
[20:46:12.900] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:58:0)
[20:46:12.900] Timer tick, asking Buildbucket for the build status
[20:46:12.970] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:46:12.970] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:59:0) after 1m0s
[20:47:13.054] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:59:0)
[20:47:13.054] Timer tick, asking Buildbucket for the build status
[20:47:13.139] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:47:13.139] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:60:0) after 1m0s
[20:48:13.256] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:60:0)
[20:48:13.256] Timer tick, asking Buildbucket for the build status
[20:48:13.358] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:48:13.358] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:61:0) after 1m0s
[20:49:13.393] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:61:0)
[20:49:13.393] Timer tick, asking Buildbucket for the build status
[20:49:13.467] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:49:13.467] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:62:0) after 1m0s
[20:50:13.545] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:62:0)
[20:50:13.545] Timer tick, asking Buildbucket for the build status
[20:50:13.619] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:50:13.619] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:63:0) after 1m0s
[20:51:13.657] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:63:0)
[20:51:13.657] Timer tick, asking Buildbucket for the build status
[20:51:13.735] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:51:13.735] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:64:0) after 1m0s
[20:52:13.853] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:64:0)
[20:52:13.853] Timer tick, asking Buildbucket for the build status
[20:52:13.979] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:52:13.979] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:65:0) after 1m0s
[20:53:14.007] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:65:0)
[20:53:14.007] Timer tick, asking Buildbucket for the build status
[20:53:14.128] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:53:14.128] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:66:0) after 1m0s
[20:54:14.159] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:66:0)
[20:54:14.159] Timer tick, asking Buildbucket for the build status
[20:54:14.249] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:54:14.249] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:67:0) after 1m0s
[20:55:14.325] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:67:0)
[20:55:14.325] Timer tick, asking Buildbucket for the build status
[20:55:14.398] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:55:14.398] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:68:0) after 1m0s
[20:56:14.445] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:68:0)
[20:56:14.445] Timer tick, asking Buildbucket for the build status
[20:56:14.528] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:56:14.529] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:69:0) after 1m0s
[20:57:14.561] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:69:0)
[20:57:14.561] Timer tick, asking Buildbucket for the build status
[20:57:14.649] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:57:14.649] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:70:0) after 1m0s
[20:58:14.742] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:70:0)
[20:58:14.742] Timer tick, asking Buildbucket for the build status
[20:58:14.833] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:58:14.833] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:71:0) after 1m0s
[20:59:14.854] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:71:0)
[20:59:14.854] Timer tick, asking Buildbucket for the build status
[20:59:14.934] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:59:14.934] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:72:0) after 1m0s
[21:00:14.994] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:72:0)
[21:00:14.994] Timer tick, asking Buildbucket for the build status
[21:00:15.071] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:00:15.071] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:73:0) after 1m0s
[21:01:15.116] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:73:0)
[21:01:15.116] Timer tick, asking Buildbucket for the build status
[21:01:15.191] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:01:15.191] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:74:0) after 1m0s
[21:02:15.253] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:74:0)
[21:02:15.253] Timer tick, asking Buildbucket for the build status
[21:02:15.342] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:02:15.342] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:75:0) after 1m0s
[21:03:15.364] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:75:0)
[21:03:15.364] Timer tick, asking Buildbucket for the build status
[21:03:15.456] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:03:15.456] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:76:0) after 1m0s
[21:04:15.484] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:76:0)
[21:04:15.484] Timer tick, asking Buildbucket for the build status
[21:04:15.644] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:04:15.644] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:77:0) after 1m0s
[21:05:15.751] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:77:0)
[21:05:15.751] Timer tick, asking Buildbucket for the build status
[21:05:15.819] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:05:15.819] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:78:0) after 1m0s
[21:06:15.851] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:78:0)
[21:06:15.851] Timer tick, asking Buildbucket for the build status
[21:06:15.934] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:06:15.934] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:79:0) after 1m0s
[21:07:16.042] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:79:0)
[21:07:16.042] Timer tick, asking Buildbucket for the build status
[21:07:16.140] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:07:16.140] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:80:0) after 1m0s
[21:08:16.244] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:80:0)
[21:08:16.244] Timer tick, asking Buildbucket for the build status
[21:08:16.325] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:08:16.325] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:81:0) after 1m0s
[21:09:16.354] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:81:0)
[21:09:16.354] Timer tick, asking Buildbucket for the build status
[21:09:16.433] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:09:16.433] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:82:0) after 1m0s
[21:10:16.459] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:82:0)
[21:10:16.459] Timer tick, asking Buildbucket for the build status
[21:10:16.901] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:10:16.901] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:83:0) after 1m0s
[21:11:16.953] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:83:0)
[21:11:16.953] Timer tick, asking Buildbucket for the build status
[21:11:17.352] Build 8853571007943100864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:11:17.352] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:84:0) after 1m0s
[21:12:11.351] Received PubSub notification, asking Buildbucket for the build status
[21:12:11.450] Build 8853571007943100864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:17.407] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:84:0)
[21:12:17.407] Timer tick, asking Buildbucket for the build status
[21:12:17.480] Build 8853571007943100864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:17.480] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:86:0) after 1m0s
[21:13:17.604] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:86:0)
[21:13:17.604] Timer tick, asking Buildbucket for the build status
[21:13:17.685] Build 8853571007943100864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:17.685] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:87:0) after 1m0s
[21:14:17.792] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:87:0)
[21:14:17.792] Timer tick, asking Buildbucket for the build status
[21:14:17.878] Build 8853571007943100864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:17.878] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:88:0) after 1m0s
[21:15:17.943] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:88:0)
[21:15:17.943] Timer tick, asking Buildbucket for the build status
[21:15:18.026] Build 8853571007943100864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:18.026] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:89:0) after 1m0s
[21:16:18.103] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:89:0)
[21:16:18.103] Timer tick, asking Buildbucket for the build status
[21:16:18.201] Build 8853571007943100864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:18.201] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:90:0) after 1m0s
[21:17:18.302] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:90:0)
[21:17:18.302] Timer tick, asking Buildbucket for the build status
[21:17:18.426] Build 8853571007943100864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:18.426] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:91:0) after 1m0s
[21:18:18.459] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:91:0)
[21:18:18.459] Timer tick, asking Buildbucket for the build status
[21:18:18.543] Build 8853571007943100864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:18.543] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:92:0) after 1m0s
[21:19:18.661] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:92:0)
[21:19:18.661] Timer tick, asking Buildbucket for the build status
[21:19:18.741] Build 8853571007943100864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:18.741] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:93:0) after 1m0s
[21:20:18.855] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:93:0)
[21:20:18.855] Timer tick, asking Buildbucket for the build status
[21:20:18.946] Build 8853571007943100864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:18.946] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:94:0) after 1m0s
[21:21:19.056] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:94:0)
[21:21:19.056] Timer tick, asking Buildbucket for the build status
[21:21:19.141] Build 8853571007943100864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:19.141] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9019001069876408000:95:0) after 1m0s
[21:21:35.609] Received PubSub notification, asking Buildbucket for the build status
[21:21:35.735] Build 8853571007943100864: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:21:35.735] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1614979295099413",
"created_by": "project:infra",
"created_ts": "1614973743453453",
"id": "8853571007943100864",
"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\": \"0bdc0b01e7e395818c1a206cbe0bb75a45f74a3d\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0bdc0b01e7e395818c1a206cbe0bb75a45f74a3d\", \"title\": \"0bdc0b01e7e395818c1a206cbe0bb75a45f74a3d\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0bdc0b01e7e395818c1a206cbe0bb75a45f74a3d\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"7fa86f3e8a9ee3ac4cefb0cffab74876ae128f8a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@7fa86f3e8a9ee3ac4cefb0cffab74876ae128f8a\", \"title\": \"7fa86f3e8a9ee3ac4cefb0cffab74876ae128f8a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/7fa86f3e8a9ee3ac4cefb0cffab74876ae128f8a\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"ca70237ee4003d441806235da39310a4b5344f99\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ca70237ee4003d441806235da39310a4b5344f99\", \"title\": \"ca70237ee4003d441806235da39310a4b5344f99\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/ca70237ee4003d441806235da39310a4b5344f99\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"a46e5eab5c0f5ba393c00c1741514e34c07ace46\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a46e5eab5c0f5ba393c00c1741514e34c07ace46\", \"title\": \"a46e5eab5c0f5ba393c00c1741514e34c07ace46\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/a46e5eab5c0f5ba393c00c1741514e34c07ace46\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"d2c2fe9849303550a7032ab1f097b2c190093652\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d2c2fe9849303550a7032ab1f097b2c190093652\", \"title\": \"d2c2fe9849303550a7032ab1f097b2c190093652\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/d2c2fe9849303550a7032ab1f097b2c190093652\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"ddbf05390e26c766538bafad5ee815a3e7568aea\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ddbf05390e26c766538bafad5ee815a3e7568aea\", \"title\": \"ddbf05390e26c766538bafad5ee815a3e7568aea\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/ddbf05390e26c766538bafad5ee815a3e7568aea\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"ddbf05390e26c766538bafad5ee815a3e7568aea\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"ddbf05390e26c766538bafad5ee815a3e7568aea\", \"got_revision_cp\": \"refs/heads/master@{#39077}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_044445a3bc7a4bfe795493d7956aa02cd526172de1fa7300f69fe152e82ea631_v2\", \"builder_5de1032f0e9ef497433aad28840ee258c037976023b410f653133cee0ad04e2c_v2\", \"builder_82ed111fed7ea8169e482f00b5f3e5338a65daa41208585de67cb0a736b1d538_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_v2\", \"builder_d38bdc976f893fc6930479dce8a6d7dc793b2a74fe1392d4af00713a5c0bb46e_v2\", \"git\", \"goma\", \"infra_gclient_with_go\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"dev-luci-chromium-ci-win10-0-0kov\"], \"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\": [\"5742-22b1e04\"], \"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": "1614978729491171",
"status": "COMPLETED",
"status_changed_ts": "1614978730000013",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18792",
"builder:infra-continuous-win10-64",
"buildset:commit/git/ddbf05390e26c766538bafad5ee815a3e7568aea",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/ddbf05390e26c766538bafad5ee815a3e7568aea",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019001069876408000",
"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/8853571007943100864/+/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:521cc13cfed66f10",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1614979295099854",
"url": "https://luci-milo-dev.appspot.com/b/8853571007943100864",
"utcnow_ts": "1614979295706090"
}
}
[21:21:35.735] Invocation finished in 1h32m33.509152249s with status SUCCEEDED