[23:58:22.077] New invocation is queued and will start shortly
[23:58:23.201] Starting the invocation (attempt 1)
[23:58:23.238] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[23:58:23.238] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[23:58:23.239] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9021793889309954400",
"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\":\"a52dc4f8bf1c5a7a245fa7e55a1b77e65164bc42\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a52dc4f8bf1c5a7a245fa7e55a1b77e65164bc42\",\"title\":\"a52dc4f8bf1c5a7a245fa7e55a1b77e65164bc42\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/a52dc4f8bf1c5a7a245fa7e55a1b77e65164bc42\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"6d3fc9b12de3e8fe430c05e5b29a0157343a2c42\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@6d3fc9b12de3e8fe430c05e5b29a0157343a2c42\",\"title\":\"6d3fc9b12de3e8fe430c05e5b29a0157343a2c42\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/6d3fc9b12de3e8fe430c05e5b29a0157343a2c42\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"b1ac43a5bdd21660cd2852d63d35b5f1656a6142\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b1ac43a5bdd21660cd2852d63d35b5f1656a6142\",\"title\":\"b1ac43a5bdd21660cd2852d63d35b5f1656a6142\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/b1ac43a5bdd21660cd2852d63d35b5f1656a6142\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"7a092a0b1c4e4edda8aec0d91172f13404755580\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@7a092a0b1c4e4edda8aec0d91172f13404755580\",\"title\":\"7a092a0b1c4e4edda8aec0d91172f13404755580\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/7a092a0b1c4e4edda8aec0d91172f13404755580\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"033daf18ef636c44a30984b74040e3f649d8f10c\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@033daf18ef636c44a30984b74040e3f649d8f10c\",\"title\":\"033daf18ef636c44a30984b74040e3f649d8f10c\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/033daf18ef636c44a30984b74040e3f649d8f10c\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"b6e4d068aaeb7ff5cabf2cf4de0aa7410bebb765\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b6e4d068aaeb7ff5cabf2cf4de0aa7410bebb765\",\"title\":\"b6e4d068aaeb7ff5cabf2cf4de0aa7410bebb765\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/b6e4d068aaeb7ff5cabf2cf4de0aa7410bebb765\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"2d87d42d822afef3c8b6864b067eded0b77722ad\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@2d87d42d822afef3c8b6864b067eded0b77722ad\",\"title\":\"2d87d42d822afef3c8b6864b067eded0b77722ad\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/2d87d42d822afef3c8b6864b067eded0b77722ad\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"2b4ac3658ee8fe6fbba22731460a2cc68a18f616\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@2b4ac3658ee8fe6fbba22731460a2cc68a18f616\",\"title\":\"2b4ac3658ee8fe6fbba22731460a2cc68a18f616\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/2b4ac3658ee8fe6fbba22731460a2cc68a18f616\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"1f59097280ae312881566dbee13b4a78e0ccb82a\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@1f59097280ae312881566dbee13b4a78e0ccb82a\",\"title\":\"1f59097280ae312881566dbee13b4a78e0ccb82a\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/1f59097280ae312881566dbee13b4a78e0ccb82a\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"1f59097280ae312881566dbee13b4a78e0ccb82a\"}}",
"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:9021793889309954400",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/1f59097280ae312881566dbee13b4a78e0ccb82a",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/1f59097280ae312881566dbee13b4a78e0ccb82a",
"gitiles_ref:refs/heads/master"
]
}
[23:58:24.147] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1612310303325260",
"id": "8856363827338583280",
"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\": \"a52dc4f8bf1c5a7a245fa7e55a1b77e65164bc42\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a52dc4f8bf1c5a7a245fa7e55a1b77e65164bc42\", \"title\": \"a52dc4f8bf1c5a7a245fa7e55a1b77e65164bc42\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/a52dc4f8bf1c5a7a245fa7e55a1b77e65164bc42\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"6d3fc9b12de3e8fe430c05e5b29a0157343a2c42\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@6d3fc9b12de3e8fe430c05e5b29a0157343a2c42\", \"title\": \"6d3fc9b12de3e8fe430c05e5b29a0157343a2c42\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/6d3fc9b12de3e8fe430c05e5b29a0157343a2c42\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"b1ac43a5bdd21660cd2852d63d35b5f1656a6142\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b1ac43a5bdd21660cd2852d63d35b5f1656a6142\", \"title\": \"b1ac43a5bdd21660cd2852d63d35b5f1656a6142\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/b1ac43a5bdd21660cd2852d63d35b5f1656a6142\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"7a092a0b1c4e4edda8aec0d91172f13404755580\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@7a092a0b1c4e4edda8aec0d91172f13404755580\", \"title\": \"7a092a0b1c4e4edda8aec0d91172f13404755580\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/7a092a0b1c4e4edda8aec0d91172f13404755580\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"033daf18ef636c44a30984b74040e3f649d8f10c\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@033daf18ef636c44a30984b74040e3f649d8f10c\", \"title\": \"033daf18ef636c44a30984b74040e3f649d8f10c\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/033daf18ef636c44a30984b74040e3f649d8f10c\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"b6e4d068aaeb7ff5cabf2cf4de0aa7410bebb765\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b6e4d068aaeb7ff5cabf2cf4de0aa7410bebb765\", \"title\": \"b6e4d068aaeb7ff5cabf2cf4de0aa7410bebb765\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/b6e4d068aaeb7ff5cabf2cf4de0aa7410bebb765\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"2d87d42d822afef3c8b6864b067eded0b77722ad\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@2d87d42d822afef3c8b6864b067eded0b77722ad\", \"title\": \"2d87d42d822afef3c8b6864b067eded0b77722ad\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/2d87d42d822afef3c8b6864b067eded0b77722ad\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"2b4ac3658ee8fe6fbba22731460a2cc68a18f616\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@2b4ac3658ee8fe6fbba22731460a2cc68a18f616\", \"title\": \"2b4ac3658ee8fe6fbba22731460a2cc68a18f616\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/2b4ac3658ee8fe6fbba22731460a2cc68a18f616\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"1f59097280ae312881566dbee13b4a78e0ccb82a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@1f59097280ae312881566dbee13b4a78e0ccb82a\", \"title\": \"1f59097280ae312881566dbee13b4a78e0ccb82a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/1f59097280ae312881566dbee13b4a78e0ccb82a\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"1f59097280ae312881566dbee13b4a78e0ccb82a\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612310303951694",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18501",
"builder:infra-continuous-win10-64",
"buildset:commit/git/1f59097280ae312881566dbee13b4a78e0ccb82a",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/1f59097280ae312881566dbee13b4a78e0ccb82a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021793889309954400",
"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/8856363827338583280/+/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": "1612310303951820",
"url": "https://luci-milo-dev.appspot.com/b/8856363827338583280",
"utcnow_ts": "1612310304138725"
}
}
[23:58:24.147] Task URL: https://luci-milo-dev.appspot.com/b/8856363827338583280
[23:58:24.147] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:2:0) after 1m0s
[23:59:24.234] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:2:0)
[23:59:24.234] Timer tick, asking Buildbucket for the build status
[23:59:24.326] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:59:24.326] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:3:0) after 1m0s
[00:00:24.399] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:3:0)
[00:00:24.399] Timer tick, asking Buildbucket for the build status
[00:00:24.508] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:00:24.508] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:4:0) after 1m0s
[00:01:24.583] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:4:0)
[00:01:24.583] Timer tick, asking Buildbucket for the build status
[00:01:24.687] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:01:24.687] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:5:0) after 1m0s
[00:02:24.793] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:5:0)
[00:02:24.793] Timer tick, asking Buildbucket for the build status
[00:02:24.904] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:02:24.904] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:6:0) after 1m0s
[00:03:24.999] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:6:0)
[00:03:24.999] Timer tick, asking Buildbucket for the build status
[00:03:25.101] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:03:25.101] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:7:0) after 1m0s
[00:04:25.141] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:7:0)
[00:04:25.141] Timer tick, asking Buildbucket for the build status
[00:04:25.333] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:04:25.333] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:8:0) after 1m0s
[00:05:25.385] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:8:0)
[00:05:25.385] Timer tick, asking Buildbucket for the build status
[00:05:25.486] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:05:25.486] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:9:0) after 1m0s
[00:06:25.508] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:9:0)
[00:06:25.508] Timer tick, asking Buildbucket for the build status
[00:06:25.636] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:06:25.637] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:10:0) after 1m0s
[00:07:25.690] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:10:0)
[00:07:25.690] Timer tick, asking Buildbucket for the build status
[00:07:25.801] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:07:25.801] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:11:0) after 1m0s
[00:08:25.880] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:11:0)
[00:08:25.880] Timer tick, asking Buildbucket for the build status
[00:08:25.989] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:08:25.989] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:12:0) after 1m0s
[00:09:26.022] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:12:0)
[00:09:26.022] Timer tick, asking Buildbucket for the build status
[00:09:26.121] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:09:26.122] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:13:0) after 1m0s
[00:10:26.180] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:13:0)
[00:10:26.180] Timer tick, asking Buildbucket for the build status
[00:10:26.270] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:10:26.270] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:14:0) after 1m0s
[00:11:26.298] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:14:0)
[00:11:26.298] Timer tick, asking Buildbucket for the build status
[00:11:27.616] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:11:27.616] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:15:0) after 1m0s
[00:12:27.697] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:15:0)
[00:12:27.697] Timer tick, asking Buildbucket for the build status
[00:12:27.826] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:12:27.826] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:16:0) after 1m0s
[00:13:27.904] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:16:0)
[00:13:27.907] Timer tick, asking Buildbucket for the build status
[00:13:28.015] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:13:28.015] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:17:0) after 1m0s
[00:14:28.118] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:17:0)
[00:14:28.118] Timer tick, asking Buildbucket for the build status
[00:14:28.222] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:14:28.223] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:18:0) after 1m0s
[00:15:28.249] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:18:0)
[00:15:28.249] Timer tick, asking Buildbucket for the build status
[00:15:28.345] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:15:28.345] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:19:0) after 1m0s
[00:16:28.386] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:19:0)
[00:16:28.386] Timer tick, asking Buildbucket for the build status
[00:16:28.499] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:16:28.499] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:20:0) after 1m0s
[00:17:28.601] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:20:0)
[00:17:28.601] Timer tick, asking Buildbucket for the build status
[00:17:28.711] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:17:28.711] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:21:0) after 1m0s
[00:18:28.794] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:21:0)
[00:18:28.794] Timer tick, asking Buildbucket for the build status
[00:18:28.881] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:18:28.881] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:22:0) after 1m0s
[00:19:28.983] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:22:0)
[00:19:28.983] Timer tick, asking Buildbucket for the build status
[00:19:29.076] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:19:29.076] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:23:0) after 1m0s
[00:20:29.096] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:23:0)
[00:20:29.096] Timer tick, asking Buildbucket for the build status
[00:20:29.186] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:20:29.186] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:24:0) after 1m0s
[00:21:29.277] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:24:0)
[00:21:29.277] Timer tick, asking Buildbucket for the build status
[00:21:29.386] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:29.386] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:25:0) after 1m0s
[00:22:29.495] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:25:0)
[00:22:29.495] Timer tick, asking Buildbucket for the build status
[00:22:29.651] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:29.651] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:26:0) after 1m0s
[00:23:29.679] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:26:0)
[00:23:29.679] Timer tick, asking Buildbucket for the build status
[00:23:29.769] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:23:29.769] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:27:0) after 1m0s
[00:24:29.879] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:27:0)
[00:24:29.879] Timer tick, asking Buildbucket for the build status
[00:24:30.026] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:24:30.026] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:28:0) after 1m0s
[00:25:30.048] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:28:0)
[00:25:30.048] Timer tick, asking Buildbucket for the build status
[00:25:30.138] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:25:30.138] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:29:0) after 1m0s
[00:26:30.227] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:29:0)
[00:26:30.227] Timer tick, asking Buildbucket for the build status
[00:26:30.335] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:26:30.335] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:30:0) after 1m0s
[00:27:30.356] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:30:0)
[00:27:30.356] Timer tick, asking Buildbucket for the build status
[00:27:30.452] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:27:30.452] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:31:0) after 1m0s
[00:28:30.479] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:31:0)
[00:28:30.479] Timer tick, asking Buildbucket for the build status
[00:28:30.651] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:28:30.651] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:32:0) after 1m0s
[00:29:30.697] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:32:0)
[00:29:30.697] Timer tick, asking Buildbucket for the build status
[00:29:30.799] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:29:30.799] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:33:0) after 1m0s
[00:30:30.819] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:33:0)
[00:30:30.819] Timer tick, asking Buildbucket for the build status
[00:30:30.913] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:30:30.913] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:34:0) after 1m0s
[00:31:30.979] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:34:0)
[00:31:30.979] Timer tick, asking Buildbucket for the build status
[00:31:31.077] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:31:31.077] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:35:0) after 1m0s
[00:32:31.199] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:35:0)
[00:32:31.199] Timer tick, asking Buildbucket for the build status
[00:32:31.342] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:32:31.342] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:36:0) after 1m0s
[00:33:31.363] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:36:0)
[00:33:31.363] Timer tick, asking Buildbucket for the build status
[00:33:31.439] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:33:31.439] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:37:0) after 1m0s
[00:34:31.485] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:37:0)
[00:34:31.485] Timer tick, asking Buildbucket for the build status
[00:34:31.584] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:34:31.584] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:38:0) after 1m0s
[00:35:31.607] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:38:0)
[00:35:31.607] Timer tick, asking Buildbucket for the build status
[00:35:31.690] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:31.690] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:39:0) after 1m0s
[00:36:31.800] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:39:0)
[00:36:31.800] Timer tick, asking Buildbucket for the build status
[00:36:31.893] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:31.893] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:40:0) after 1m0s
[00:37:31.989] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:40:0)
[00:37:31.989] Timer tick, asking Buildbucket for the build status
[00:37:32.088] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:32.088] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:41:0) after 1m0s
[00:38:32.179] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:41:0)
[00:38:32.179] Timer tick, asking Buildbucket for the build status
[00:38:32.264] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:32.264] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:42:0) after 1m0s
[00:39:32.295] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:42:0)
[00:39:32.295] Timer tick, asking Buildbucket for the build status
[00:39:32.404] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:32.404] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:43:0) after 1m0s
[00:40:32.480] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:43:0)
[00:40:32.480] Timer tick, asking Buildbucket for the build status
[00:40:32.606] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:32.606] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:44:0) after 1m0s
[00:41:32.680] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:44:0)
[00:41:32.680] Timer tick, asking Buildbucket for the build status
[00:41:32.770] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:32.770] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:45:0) after 1m0s
[00:42:32.884] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:45:0)
[00:42:32.884] Timer tick, asking Buildbucket for the build status
[00:42:33.011] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:33.011] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:46:0) after 1m0s
[00:43:33.094] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:46:0)
[00:43:33.094] Timer tick, asking Buildbucket for the build status
[00:43:33.268] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:33.268] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:47:0) after 1m0s
[00:44:33.293] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:47:0)
[00:44:33.293] Timer tick, asking Buildbucket for the build status
[00:44:33.401] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:33.401] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:48:0) after 1m0s
[00:45:33.488] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:48:0)
[00:45:33.488] Timer tick, asking Buildbucket for the build status
[00:45:33.602] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:33.602] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:49:0) after 1m0s
[00:46:33.695] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:49:0)
[00:46:33.695] Timer tick, asking Buildbucket for the build status
[00:46:33.805] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:33.805] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:50:0) after 1m0s
[00:47:33.845] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:50:0)
[00:47:33.845] Timer tick, asking Buildbucket for the build status
[00:47:33.980] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:33.980] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:51:0) after 1m0s
[00:48:34.080] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:51:0)
[00:48:34.080] Timer tick, asking Buildbucket for the build status
[00:48:34.180] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:34.180] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:52:0) after 1m0s
[00:49:34.296] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:52:0)
[00:49:34.296] Timer tick, asking Buildbucket for the build status
[00:49:34.431] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:49:34.431] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:53:0) after 1m0s
[00:50:34.461] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:53:0)
[00:50:34.461] Timer tick, asking Buildbucket for the build status
[00:50:34.933] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:50:34.933] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:54:0) after 1m0s
[00:51:34.957] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:54:0)
[00:51:34.957] Timer tick, asking Buildbucket for the build status
[00:51:35.043] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:35.043] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:55:0) after 1m0s
[00:52:35.103] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:55:0)
[00:52:35.103] Timer tick, asking Buildbucket for the build status
[00:52:35.193] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:35.193] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:56:0) after 1m0s
[00:53:35.280] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:56:0)
[00:53:35.280] Timer tick, asking Buildbucket for the build status
[00:53:35.462] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:35.462] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:57:0) after 1m0s
[00:54:35.485] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:57:0)
[00:54:35.485] Timer tick, asking Buildbucket for the build status
[00:54:35.585] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:54:35.585] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:58:0) after 1m0s
[00:55:35.685] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:58:0)
[00:55:35.685] Timer tick, asking Buildbucket for the build status
[00:55:35.782] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:55:35.782] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:59:0) after 1m0s
[00:56:35.883] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:59:0)
[00:56:35.884] Timer tick, asking Buildbucket for the build status
[00:56:35.976] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:56:35.976] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:60:0) after 1m0s
[00:57:36.092] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:60:0)
[00:57:36.092] Timer tick, asking Buildbucket for the build status
[00:57:36.184] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:36.184] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:61:0) after 1m0s
[00:58:36.286] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:61:0)
[00:58:36.286] Timer tick, asking Buildbucket for the build status
[00:58:36.364] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:36.364] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:62:0) after 1m0s
[00:59:36.411] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:62:0)
[00:59:36.411] Timer tick, asking Buildbucket for the build status
[00:59:36.543] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:36.543] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:63:0) after 1m0s
[01:00:36.594] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:63:0)
[01:00:36.594] Timer tick, asking Buildbucket for the build status
[01:00:36.680] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:36.680] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:64:0) after 1m0s
[01:01:36.707] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:64:0)
[01:01:36.707] Timer tick, asking Buildbucket for the build status
[01:01:36.802] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:36.802] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:65:0) after 1m0s
[01:02:36.894] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:65:0)
[01:02:36.894] Timer tick, asking Buildbucket for the build status
[01:02:37.065] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:37.065] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:66:0) after 1m0s
[01:03:37.096] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:66:0)
[01:03:37.096] Timer tick, asking Buildbucket for the build status
[01:03:37.203] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:37.203] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:67:0) after 1m0s
[01:04:37.308] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:67:0)
[01:04:37.308] Timer tick, asking Buildbucket for the build status
[01:04:37.435] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:37.435] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:68:0) after 1m0s
[01:05:37.508] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:68:0)
[01:05:37.508] Timer tick, asking Buildbucket for the build status
[01:05:37.608] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:37.608] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:69:0) after 1m0s
[01:06:37.710] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:69:0)
[01:06:37.710] Timer tick, asking Buildbucket for the build status
[01:06:37.841] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:37.841] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:70:0) after 1m0s
[01:07:37.905] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:70:0)
[01:07:37.905] Timer tick, asking Buildbucket for the build status
[01:07:37.996] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:37.996] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:71:0) after 1m0s
[01:08:38.093] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:71:0)
[01:08:38.093] Timer tick, asking Buildbucket for the build status
[01:08:38.178] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:38.178] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:72:0) after 1m0s
[01:09:38.205] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:72:0)
[01:09:38.205] Timer tick, asking Buildbucket for the build status
[01:09:38.284] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:38.284] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:73:0) after 1m0s
[01:10:38.400] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:73:0)
[01:10:38.400] Timer tick, asking Buildbucket for the build status
[01:10:38.480] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:38.480] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:74:0) after 1m0s
[01:11:38.522] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:74:0)
[01:11:38.522] Timer tick, asking Buildbucket for the build status
[01:11:38.598] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:38.598] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:75:0) after 1m0s
[01:12:38.627] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:75:0)
[01:12:38.627] Timer tick, asking Buildbucket for the build status
[01:12:38.711] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:38.711] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:76:0) after 1m0s
[01:13:38.746] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:76:0)
[01:13:38.746] Timer tick, asking Buildbucket for the build status
[01:13:38.826] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:38.826] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:77:0) after 1m0s
[01:14:38.908] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:77:0)
[01:14:38.908] Timer tick, asking Buildbucket for the build status
[01:14:38.992] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:38.992] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:78:0) after 1m0s
[01:15:39.040] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:78:0)
[01:15:39.040] Timer tick, asking Buildbucket for the build status
[01:15:39.541] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:39.541] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:79:0) after 1m0s
[01:16:39.708] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:79:0)
[01:16:39.708] Timer tick, asking Buildbucket for the build status
[01:16:39.794] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:39.794] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:80:0) after 1m0s
[01:17:39.894] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:80:0)
[01:17:39.894] Timer tick, asking Buildbucket for the build status
[01:17:39.989] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:17:39.989] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:81:0) after 1m0s
[01:18:40.015] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:81:0)
[01:18:40.015] Timer tick, asking Buildbucket for the build status
[01:18:40.099] Build 8856363827338583280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:18:40.099] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:82:0) after 1m0s
[01:19:11.709] Received PubSub notification, asking Buildbucket for the build status
[01:19:11.856] Build 8856363827338583280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:40.123] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:82:0)
[01:19:40.123] Timer tick, asking Buildbucket for the build status
[01:19:40.220] Build 8856363827338583280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:40.220] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:84:0) after 1m0s
[01:20:40.245] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:84:0)
[01:20:40.245] Timer tick, asking Buildbucket for the build status
[01:20:40.330] Build 8856363827338583280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:40.330] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:85:0) after 1m0s
[01:21:40.357] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:85:0)
[01:21:40.357] Timer tick, asking Buildbucket for the build status
[01:21:40.439] Build 8856363827338583280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:40.440] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:86:0) after 1m0s
[01:22:40.474] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:86:0)
[01:22:40.474] Timer tick, asking Buildbucket for the build status
[01:22:40.572] Build 8856363827338583280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:40.572] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:87:0) after 1m0s
[01:23:40.633] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:87:0)
[01:23:40.633] Timer tick, asking Buildbucket for the build status
[01:23:40.718] Build 8856363827338583280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:40.718] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:88:0) after 1m0s
[01:24:40.801] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:88:0)
[01:24:40.801] Timer tick, asking Buildbucket for the build status
[01:24:40.887] Build 8856363827338583280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:40.887] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:89:0) after 1m0s
[01:25:40.996] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:89:0)
[01:25:40.996] Timer tick, asking Buildbucket for the build status
[01:25:41.095] Build 8856363827338583280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:41.095] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:90:0) after 1m0s
[01:26:41.182] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:90:0)
[01:26:41.182] Timer tick, asking Buildbucket for the build status
[01:26:41.278] Build 8856363827338583280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:41.278] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:91:0) after 1m0s
[01:27:41.367] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:91:0)
[01:27:41.367] Timer tick, asking Buildbucket for the build status
[01:27:41.463] Build 8856363827338583280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:41.463] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:92:0) after 1m0s
[01:28:41.484] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:92:0)
[01:28:41.484] Timer tick, asking Buildbucket for the build status
[01:28:41.590] Build 8856363827338583280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:41.590] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:93:0) after 1m0s
[01:29:41.702] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:93:0)
[01:29:41.702] Timer tick, asking Buildbucket for the build status
[01:29:41.905] Build 8856363827338583280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:41.905] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021793889309954400:94:0) after 1m0s
[01:30:21.443] Received PubSub notification, asking Buildbucket for the build status
[01:30:21.534] Build 8856363827338583280: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:30:21.534] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1612315818583687",
"created_by": "project:infra",
"created_ts": "1612310303325260",
"id": "8856363827338583280",
"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\": \"a52dc4f8bf1c5a7a245fa7e55a1b77e65164bc42\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a52dc4f8bf1c5a7a245fa7e55a1b77e65164bc42\", \"title\": \"a52dc4f8bf1c5a7a245fa7e55a1b77e65164bc42\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/a52dc4f8bf1c5a7a245fa7e55a1b77e65164bc42\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"6d3fc9b12de3e8fe430c05e5b29a0157343a2c42\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@6d3fc9b12de3e8fe430c05e5b29a0157343a2c42\", \"title\": \"6d3fc9b12de3e8fe430c05e5b29a0157343a2c42\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/6d3fc9b12de3e8fe430c05e5b29a0157343a2c42\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"b1ac43a5bdd21660cd2852d63d35b5f1656a6142\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b1ac43a5bdd21660cd2852d63d35b5f1656a6142\", \"title\": \"b1ac43a5bdd21660cd2852d63d35b5f1656a6142\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/b1ac43a5bdd21660cd2852d63d35b5f1656a6142\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"7a092a0b1c4e4edda8aec0d91172f13404755580\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@7a092a0b1c4e4edda8aec0d91172f13404755580\", \"title\": \"7a092a0b1c4e4edda8aec0d91172f13404755580\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/7a092a0b1c4e4edda8aec0d91172f13404755580\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"033daf18ef636c44a30984b74040e3f649d8f10c\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@033daf18ef636c44a30984b74040e3f649d8f10c\", \"title\": \"033daf18ef636c44a30984b74040e3f649d8f10c\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/033daf18ef636c44a30984b74040e3f649d8f10c\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"b6e4d068aaeb7ff5cabf2cf4de0aa7410bebb765\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b6e4d068aaeb7ff5cabf2cf4de0aa7410bebb765\", \"title\": \"b6e4d068aaeb7ff5cabf2cf4de0aa7410bebb765\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/b6e4d068aaeb7ff5cabf2cf4de0aa7410bebb765\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"2d87d42d822afef3c8b6864b067eded0b77722ad\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@2d87d42d822afef3c8b6864b067eded0b77722ad\", \"title\": \"2d87d42d822afef3c8b6864b067eded0b77722ad\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/2d87d42d822afef3c8b6864b067eded0b77722ad\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"2b4ac3658ee8fe6fbba22731460a2cc68a18f616\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@2b4ac3658ee8fe6fbba22731460a2cc68a18f616\", \"title\": \"2b4ac3658ee8fe6fbba22731460a2cc68a18f616\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/2b4ac3658ee8fe6fbba22731460a2cc68a18f616\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"1f59097280ae312881566dbee13b4a78e0ccb82a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@1f59097280ae312881566dbee13b4a78e0ccb82a\", \"title\": \"1f59097280ae312881566dbee13b4a78e0ccb82a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/1f59097280ae312881566dbee13b4a78e0ccb82a\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"1f59097280ae312881566dbee13b4a78e0ccb82a\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"1f59097280ae312881566dbee13b4a78e0ccb82a\", \"got_revision_cp\": \"refs/heads/master@{#38173}\"}, \"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-ou51\"], \"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\": [\"5676-c76598c\"], \"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": "1612315147241664",
"status": "COMPLETED",
"status_changed_ts": "1612315818911764",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18501",
"builder:infra-continuous-win10-64",
"buildset:commit/git/1f59097280ae312881566dbee13b4a78e0ccb82a",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/1f59097280ae312881566dbee13b4a78e0ccb82a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021793889309954400",
"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/8856363827338583280/+/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:517e005f9a8aed10",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1612315818984429",
"url": "https://luci-milo-dev.appspot.com/b/8856363827338583280",
"utcnow_ts": "1612315821526025"
}
}
[01:30:21.534] Invocation finished in 1h31m59.472467336s with status SUCCEEDED