[23:40:20.186] New invocation is queued and will start shortly
[23:40:21.321] Starting the invocation (attempt 1)
[23:40:21.363] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[23:40:21.366] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[23:40:21.366] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9020436069258564320",
"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\":\"100af8333983190e263cc59bb9be365bba2b7ab5\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@100af8333983190e263cc59bb9be365bba2b7ab5\",\"title\":\"100af8333983190e263cc59bb9be365bba2b7ab5\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/100af8333983190e263cc59bb9be365bba2b7ab5\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"a7a9110de5c35a7adba44b6968acd2ed006c2288\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a7a9110de5c35a7adba44b6968acd2ed006c2288\",\"title\":\"a7a9110de5c35a7adba44b6968acd2ed006c2288\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/a7a9110de5c35a7adba44b6968acd2ed006c2288\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"6eeab05a562afbca3cd3e243f032789ad3597fd7\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@6eeab05a562afbca3cd3e243f032789ad3597fd7\",\"title\":\"6eeab05a562afbca3cd3e243f032789ad3597fd7\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/6eeab05a562afbca3cd3e243f032789ad3597fd7\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"b33e26d1c89ef86a0d64a3b60e14ba516b26f740\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b33e26d1c89ef86a0d64a3b60e14ba516b26f740\",\"title\":\"b33e26d1c89ef86a0d64a3b60e14ba516b26f740\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/b33e26d1c89ef86a0d64a3b60e14ba516b26f740\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"97171b61720e78d909e9d7873ca60198d5c21097\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@97171b61720e78d909e9d7873ca60198d5c21097\",\"title\":\"97171b61720e78d909e9d7873ca60198d5c21097\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/97171b61720e78d909e9d7873ca60198d5c21097\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"47b91bc7b688a72e340dc8068b8a383d4c276419\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@47b91bc7b688a72e340dc8068b8a383d4c276419\",\"title\":\"47b91bc7b688a72e340dc8068b8a383d4c276419\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/47b91bc7b688a72e340dc8068b8a383d4c276419\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"8c42d78cbbf25c643b89fc4952fde78dea640395\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@8c42d78cbbf25c643b89fc4952fde78dea640395\",\"title\":\"8c42d78cbbf25c643b89fc4952fde78dea640395\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/8c42d78cbbf25c643b89fc4952fde78dea640395\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"0cb688602df0ebf3a1de185202460d9a4a5d96e0\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0cb688602df0ebf3a1de185202460d9a4a5d96e0\",\"title\":\"0cb688602df0ebf3a1de185202460d9a4a5d96e0\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/0cb688602df0ebf3a1de185202460d9a4a5d96e0\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"0cb688602df0ebf3a1de185202460d9a4a5d96e0\"}}",
"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:9020436069258564320",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/0cb688602df0ebf3a1de185202460d9a4a5d96e0",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/0cb688602df0ebf3a1de185202460d9a4a5d96e0",
"gitiles_ref:refs/heads/master"
]
}
[23:40:22.132] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1613605221519588",
"id": "8855006007198274128",
"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\": \"100af8333983190e263cc59bb9be365bba2b7ab5\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@100af8333983190e263cc59bb9be365bba2b7ab5\", \"title\": \"100af8333983190e263cc59bb9be365bba2b7ab5\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/100af8333983190e263cc59bb9be365bba2b7ab5\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"a7a9110de5c35a7adba44b6968acd2ed006c2288\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a7a9110de5c35a7adba44b6968acd2ed006c2288\", \"title\": \"a7a9110de5c35a7adba44b6968acd2ed006c2288\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/a7a9110de5c35a7adba44b6968acd2ed006c2288\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"6eeab05a562afbca3cd3e243f032789ad3597fd7\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@6eeab05a562afbca3cd3e243f032789ad3597fd7\", \"title\": \"6eeab05a562afbca3cd3e243f032789ad3597fd7\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/6eeab05a562afbca3cd3e243f032789ad3597fd7\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"b33e26d1c89ef86a0d64a3b60e14ba516b26f740\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b33e26d1c89ef86a0d64a3b60e14ba516b26f740\", \"title\": \"b33e26d1c89ef86a0d64a3b60e14ba516b26f740\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/b33e26d1c89ef86a0d64a3b60e14ba516b26f740\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"97171b61720e78d909e9d7873ca60198d5c21097\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@97171b61720e78d909e9d7873ca60198d5c21097\", \"title\": \"97171b61720e78d909e9d7873ca60198d5c21097\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/97171b61720e78d909e9d7873ca60198d5c21097\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"47b91bc7b688a72e340dc8068b8a383d4c276419\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@47b91bc7b688a72e340dc8068b8a383d4c276419\", \"title\": \"47b91bc7b688a72e340dc8068b8a383d4c276419\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/47b91bc7b688a72e340dc8068b8a383d4c276419\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"8c42d78cbbf25c643b89fc4952fde78dea640395\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@8c42d78cbbf25c643b89fc4952fde78dea640395\", \"title\": \"8c42d78cbbf25c643b89fc4952fde78dea640395\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/8c42d78cbbf25c643b89fc4952fde78dea640395\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0cb688602df0ebf3a1de185202460d9a4a5d96e0\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0cb688602df0ebf3a1de185202460d9a4a5d96e0\", \"title\": \"0cb688602df0ebf3a1de185202460d9a4a5d96e0\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0cb688602df0ebf3a1de185202460d9a4a5d96e0\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0cb688602df0ebf3a1de185202460d9a4a5d96e0\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613605222020755",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18638",
"builder:infra-continuous-win10-64",
"buildset:commit/git/0cb688602df0ebf3a1de185202460d9a4a5d96e0",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/0cb688602df0ebf3a1de185202460d9a4a5d96e0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020436069258564320",
"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/8855006007198274128/+/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": "1613605222020869",
"url": "https://luci-milo-dev.appspot.com/b/8855006007198274128",
"utcnow_ts": "1613605222121968"
}
}
[23:40:22.132] Task URL: https://luci-milo-dev.appspot.com/b/8855006007198274128
[23:40:22.132] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:2:0) after 1m0s
[23:41:22.204] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:2:0)
[23:41:22.204] Timer tick, asking Buildbucket for the build status
[23:41:22.310] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:22.310] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:3:0) after 1m0s
[23:42:22.331] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:3:0)
[23:42:22.331] Timer tick, asking Buildbucket for the build status
[23:42:22.422] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:22.422] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:4:0) after 1m0s
[23:43:22.502] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:4:0)
[23:43:22.502] Timer tick, asking Buildbucket for the build status
[23:43:22.586] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:22.586] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:5:0) after 1m0s
[23:44:22.734] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:5:0)
[23:44:22.734] Timer tick, asking Buildbucket for the build status
[23:44:22.826] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:22.826] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:6:0) after 1m0s
[23:45:22.849] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:6:0)
[23:45:22.849] Timer tick, asking Buildbucket for the build status
[23:45:23.063] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:23.063] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:7:0) after 1m0s
[23:46:23.085] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:7:0)
[23:46:23.085] Timer tick, asking Buildbucket for the build status
[23:46:23.179] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:23.179] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:8:0) after 1m0s
[23:47:23.217] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:8:0)
[23:47:23.217] Timer tick, asking Buildbucket for the build status
[23:47:23.313] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:23.313] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:9:0) after 1m0s
[23:48:23.342] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:9:0)
[23:48:23.342] Timer tick, asking Buildbucket for the build status
[23:48:23.430] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:23.431] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:10:0) after 1m0s
[23:49:23.521] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:10:0)
[23:49:23.521] Timer tick, asking Buildbucket for the build status
[23:49:23.617] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:23.617] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:11:0) after 1m0s
[23:50:23.704] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:11:0)
[23:50:23.704] Timer tick, asking Buildbucket for the build status
[23:50:23.853] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:23.853] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:12:0) after 1m0s
[23:51:23.894] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:12:0)
[23:51:23.894] Timer tick, asking Buildbucket for the build status
[23:51:23.985] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:23.985] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:13:0) after 1m0s
[23:52:24.106] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:13:0)
[23:52:24.106] Timer tick, asking Buildbucket for the build status
[23:52:24.257] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:24.257] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:14:0) after 1m0s
[23:53:24.307] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:14:0)
[23:53:24.307] Timer tick, asking Buildbucket for the build status
[23:53:24.400] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:53:24.400] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:15:0) after 1m0s
[23:54:24.426] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:15:0)
[23:54:24.426] Timer tick, asking Buildbucket for the build status
[23:54:24.529] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:54:24.529] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:16:0) after 1m0s
[23:55:24.557] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:16:0)
[23:55:24.557] Timer tick, asking Buildbucket for the build status
[23:55:24.657] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:55:24.657] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:17:0) after 1m0s
[23:56:24.704] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:17:0)
[23:56:24.704] Timer tick, asking Buildbucket for the build status
[23:56:24.982] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:56:24.982] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:18:0) after 1m0s
[23:57:25.005] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:18:0)
[23:57:25.005] Timer tick, asking Buildbucket for the build status
[23:57:25.214] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:25.214] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:19:0) after 1m0s
[23:58:25.240] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:19:0)
[23:58:25.240] Timer tick, asking Buildbucket for the build status
[23:58:25.422] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:58:25.422] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:20:0) after 1m0s
[23:59:25.496] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:20:0)
[23:59:25.496] Timer tick, asking Buildbucket for the build status
[23:59:25.728] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:59:25.728] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:21:0) after 1m0s
[00:00:25.797] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:21:0)
[00:00:25.797] Timer tick, asking Buildbucket for the build status
[00:00:26.013] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:00:26.013] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:22:0) after 1m0s
[00:01:26.037] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:22:0)
[00:01:26.037] Timer tick, asking Buildbucket for the build status
[00:01:26.524] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:01:26.524] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:23:0) after 1m0s
[00:02:26.548] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:23:0)
[00:02:26.548] Timer tick, asking Buildbucket for the build status
[00:02:26.839] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:02:26.839] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:24:0) after 1m0s
[00:03:26.871] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:24:0)
[00:03:26.871] Timer tick, asking Buildbucket for the build status
[00:03:27.275] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:03:27.275] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:25:0) after 1m0s
[00:04:27.305] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:25:0)
[00:04:27.305] Timer tick, asking Buildbucket for the build status
[00:04:27.505] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:04:27.505] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:26:0) after 1m0s
[00:05:27.607] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:26:0)
[00:05:27.608] Timer tick, asking Buildbucket for the build status
[00:05:28.227] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:05:28.227] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:27:0) after 1m0s
[00:06:28.309] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:27:0)
[00:06:28.309] Timer tick, asking Buildbucket for the build status
[00:06:28.650] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:06:28.650] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:28:0) after 1m0s
[00:07:28.695] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:28:0)
[00:07:28.695] Timer tick, asking Buildbucket for the build status
[00:07:28.822] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:07:28.822] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:29:0) after 1m0s
[00:08:28.894] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:29:0)
[00:08:28.894] Timer tick, asking Buildbucket for the build status
[00:08:29.015] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:08:29.015] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:30:0) after 1m0s
[00:09:29.104] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:30:0)
[00:09:29.104] Timer tick, asking Buildbucket for the build status
[00:09:29.196] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:09:29.197] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:31:0) after 1m0s
[00:10:29.219] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:31:0)
[00:10:29.219] Timer tick, asking Buildbucket for the build status
[00:10:29.305] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:10:29.305] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:32:0) after 1m0s
[00:11:29.356] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:32:0)
[00:11:29.356] Timer tick, asking Buildbucket for the build status
[00:11:29.456] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:11:29.456] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:33:0) after 1m0s
[00:12:29.493] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:33:0)
[00:12:29.493] Timer tick, asking Buildbucket for the build status
[00:12:29.744] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:12:29.744] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:34:0) after 1m0s
[00:13:29.776] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:34:0)
[00:13:29.776] Timer tick, asking Buildbucket for the build status
[00:13:29.878] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:13:29.878] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:35:0) after 1m0s
[00:14:29.900] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:35:0)
[00:14:29.900] Timer tick, asking Buildbucket for the build status
[00:14:29.997] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:14:29.997] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:36:0) after 1m0s
[00:15:30.111] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:36:0)
[00:15:30.111] Timer tick, asking Buildbucket for the build status
[00:15:30.205] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:15:30.206] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:37:0) after 1m0s
[00:16:30.229] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:37:0)
[00:16:30.229] Timer tick, asking Buildbucket for the build status
[00:16:30.323] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:16:30.323] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:38:0) after 1m0s
[00:17:30.404] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:38:0)
[00:17:30.404] Timer tick, asking Buildbucket for the build status
[00:17:30.554] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:17:30.554] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:39:0) after 1m0s
[00:18:30.581] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:39:0)
[00:18:30.581] Timer tick, asking Buildbucket for the build status
[00:18:30.675] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:18:30.675] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:40:0) after 1m0s
[00:19:30.711] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:40:0)
[00:19:30.711] Timer tick, asking Buildbucket for the build status
[00:19:30.806] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:19:30.806] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:41:0) after 1m0s
[00:20:30.949] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:41:0)
[00:20:30.949] Timer tick, asking Buildbucket for the build status
[00:20:31.046] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:20:31.046] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:42:0) after 1m0s
[00:21:31.113] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:42:0)
[00:21:31.113] Timer tick, asking Buildbucket for the build status
[00:21:31.222] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:31.222] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:43:0) after 1m0s
[00:22:31.306] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:43:0)
[00:22:31.306] Timer tick, asking Buildbucket for the build status
[00:22:31.423] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:31.423] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:44:0) after 1m0s
[00:23:31.497] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:44:0)
[00:23:31.497] Timer tick, asking Buildbucket for the build status
[00:23:31.599] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:23:31.599] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:45:0) after 1m0s
[00:24:31.708] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:45:0)
[00:24:31.708] Timer tick, asking Buildbucket for the build status
[00:24:31.800] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:24:31.800] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:46:0) after 1m0s
[00:25:31.898] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:46:0)
[00:25:31.898] Timer tick, asking Buildbucket for the build status
[00:25:31.988] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:25:31.989] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:47:0) after 1m0s
[00:26:32.117] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:47:0)
[00:26:32.117] Timer tick, asking Buildbucket for the build status
[00:26:32.212] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:26:32.212] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:48:0) after 1m0s
[00:27:32.306] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:48:0)
[00:27:32.306] Timer tick, asking Buildbucket for the build status
[00:27:32.415] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:27:32.415] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:49:0) after 1m0s
[00:28:32.427] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:49:0)
[00:28:32.427] Timer tick, asking Buildbucket for the build status
[00:28:32.581] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:28:32.581] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:50:0) after 1m0s
[00:29:32.607] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:50:0)
[00:29:32.607] Timer tick, asking Buildbucket for the build status
[00:29:32.755] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:29:32.755] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:51:0) after 1m0s
[00:30:32.795] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:51:0)
[00:30:32.795] Timer tick, asking Buildbucket for the build status
[00:30:32.884] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:30:32.884] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:52:0) after 1m0s
[00:31:32.914] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:52:0)
[00:31:32.914] Timer tick, asking Buildbucket for the build status
[00:31:33.006] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:31:33.006] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:53:0) after 1m0s
[00:32:33.028] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:53:0)
[00:32:33.028] Timer tick, asking Buildbucket for the build status
[00:32:33.112] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:32:33.112] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:54:0) after 1m0s
[00:33:33.135] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:54:0)
[00:33:33.135] Timer tick, asking Buildbucket for the build status
[00:33:33.234] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:33:33.234] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:55:0) after 1m0s
[00:34:33.296] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:55:0)
[00:34:33.297] Timer tick, asking Buildbucket for the build status
[00:34:39.529] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:34:39.529] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:56:0) after 1m0s
[00:35:39.609] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:56:0)
[00:35:39.609] Timer tick, asking Buildbucket for the build status
[00:35:39.788] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:39.788] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:57:0) after 1m0s
[00:36:39.910] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:57:0)
[00:36:39.910] Timer tick, asking Buildbucket for the build status
[00:36:40.130] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:40.130] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:58:0) after 1m0s
[00:37:40.307] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:58:0)
[00:37:40.307] Timer tick, asking Buildbucket for the build status
[00:37:40.434] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:40.434] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:59:0) after 1m0s
[00:38:40.498] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:59:0)
[00:38:40.498] Timer tick, asking Buildbucket for the build status
[00:38:40.748] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:40.748] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:60:0) after 1m0s
[00:39:40.832] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:60:0)
[00:39:40.832] Timer tick, asking Buildbucket for the build status
[00:39:41.002] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:41.002] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:61:0) after 1m0s
[00:40:41.028] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:61:0)
[00:40:41.028] Timer tick, asking Buildbucket for the build status
[00:40:41.223] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:41.223] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:62:0) after 1m0s
[00:41:41.311] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:62:0)
[00:41:41.311] Timer tick, asking Buildbucket for the build status
[00:41:41.514] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:41.514] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:63:0) after 1m0s
[00:42:41.706] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:63:0)
[00:42:41.706] Timer tick, asking Buildbucket for the build status
[00:42:41.781] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:41.781] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:64:0) after 1m0s
[00:43:41.855] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:64:0)
[00:43:41.855] Timer tick, asking Buildbucket for the build status
[00:43:41.955] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:41.955] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:65:0) after 1m0s
[00:44:41.996] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:65:0)
[00:44:41.996] Timer tick, asking Buildbucket for the build status
[00:44:42.215] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:42.215] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:66:0) after 1m0s
[00:45:42.326] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:66:0)
[00:45:42.326] Timer tick, asking Buildbucket for the build status
[00:45:42.407] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:42.407] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:67:0) after 1m0s
[00:46:42.522] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:67:0)
[00:46:42.522] Timer tick, asking Buildbucket for the build status
[00:46:42.747] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:42.747] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:68:0) after 1m0s
[00:47:42.898] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:68:0)
[00:47:42.898] Timer tick, asking Buildbucket for the build status
[00:47:43.003] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:43.003] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:69:0) after 1m0s
[00:48:43.105] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:69:0)
[00:48:43.105] Timer tick, asking Buildbucket for the build status
[00:48:43.307] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:43.307] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:70:0) after 1m0s
[00:49:43.511] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:70:0)
[00:49:43.511] Timer tick, asking Buildbucket for the build status
[00:49:43.610] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:49:43.610] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:71:0) after 1m0s
[00:50:43.806] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:71:0)
[00:50:43.806] Timer tick, asking Buildbucket for the build status
[00:50:43.922] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:50:43.922] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:72:0) after 1m0s
[00:51:44.019] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:72:0)
[00:51:44.019] Timer tick, asking Buildbucket for the build status
[00:51:44.290] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:44.290] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:73:0) after 1m0s
[00:52:44.344] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:73:0)
[00:52:44.344] Timer tick, asking Buildbucket for the build status
[00:52:44.431] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:44.431] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:74:0) after 1m0s
[00:53:44.506] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:74:0)
[00:53:44.506] Timer tick, asking Buildbucket for the build status
[00:53:44.792] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:44.792] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:75:0) after 1m0s
[00:54:44.909] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:75:0)
[00:54:44.909] Timer tick, asking Buildbucket for the build status
[00:54:45.000] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:54:45.000] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:76:0) after 1m0s
[00:55:45.097] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:76:0)
[00:55:45.097] Timer tick, asking Buildbucket for the build status
[00:55:45.471] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:55:45.471] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:77:0) after 1m0s
[00:56:45.494] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:77:0)
[00:56:45.494] Timer tick, asking Buildbucket for the build status
[00:56:45.807] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:56:45.807] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:78:0) after 1m0s
[00:57:45.913] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:78:0)
[00:57:45.913] Timer tick, asking Buildbucket for the build status
[00:57:46.204] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:46.204] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:79:0) after 1m0s
[00:58:46.239] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:79:0)
[00:58:46.239] Timer tick, asking Buildbucket for the build status
[00:58:46.645] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:46.645] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:80:0) after 1m0s
[00:59:46.713] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:80:0)
[00:59:46.713] Timer tick, asking Buildbucket for the build status
[00:59:47.000] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:47.000] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:81:0) after 1m0s
[01:00:47.112] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:81:0)
[01:00:47.112] Timer tick, asking Buildbucket for the build status
[01:00:47.564] Build 8855006007198274128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:47.564] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:82:0) after 1m0s
[01:00:55.100] Received PubSub notification, asking Buildbucket for the build status
[01:00:55.250] Build 8855006007198274128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:47.740] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:82:0)
[01:01:47.740] Timer tick, asking Buildbucket for the build status
[01:01:48.047] Build 8855006007198274128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:48.047] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:84:0) after 1m0s
[01:02:48.113] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:84:0)
[01:02:48.113] Timer tick, asking Buildbucket for the build status
[01:02:48.434] Build 8855006007198274128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:48.434] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:85:0) after 1m0s
[01:03:48.508] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:85:0)
[01:03:48.508] Timer tick, asking Buildbucket for the build status
[01:03:48.795] Build 8855006007198274128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:48.796] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:86:0) after 1m0s
[01:04:48.918] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:86:0)
[01:04:48.918] Timer tick, asking Buildbucket for the build status
[01:04:49.204] Build 8855006007198274128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:49.204] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:87:0) after 1m0s
[01:05:49.228] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:87:0)
[01:05:49.228] Timer tick, asking Buildbucket for the build status
[01:05:49.585] Build 8855006007198274128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:49.585] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:88:0) after 1m0s
[01:06:49.722] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:88:0)
[01:06:49.722] Timer tick, asking Buildbucket for the build status
[01:06:50.185] Build 8855006007198274128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:50.185] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:89:0) after 1m0s
[01:07:50.309] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:89:0)
[01:07:50.309] Timer tick, asking Buildbucket for the build status
[01:07:50.629] Build 8855006007198274128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:50.629] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9020436069258564320:90:0) after 1m0s
[01:08:14.586] Received PubSub notification, asking Buildbucket for the build status
[01:08:14.678] Build 8855006007198274128: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:08:14.678] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1613610491347428",
"created_by": "project:infra",
"created_ts": "1613605221519588",
"id": "8855006007198274128",
"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\": \"100af8333983190e263cc59bb9be365bba2b7ab5\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@100af8333983190e263cc59bb9be365bba2b7ab5\", \"title\": \"100af8333983190e263cc59bb9be365bba2b7ab5\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/100af8333983190e263cc59bb9be365bba2b7ab5\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"a7a9110de5c35a7adba44b6968acd2ed006c2288\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a7a9110de5c35a7adba44b6968acd2ed006c2288\", \"title\": \"a7a9110de5c35a7adba44b6968acd2ed006c2288\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/a7a9110de5c35a7adba44b6968acd2ed006c2288\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"6eeab05a562afbca3cd3e243f032789ad3597fd7\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@6eeab05a562afbca3cd3e243f032789ad3597fd7\", \"title\": \"6eeab05a562afbca3cd3e243f032789ad3597fd7\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/6eeab05a562afbca3cd3e243f032789ad3597fd7\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"b33e26d1c89ef86a0d64a3b60e14ba516b26f740\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b33e26d1c89ef86a0d64a3b60e14ba516b26f740\", \"title\": \"b33e26d1c89ef86a0d64a3b60e14ba516b26f740\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/b33e26d1c89ef86a0d64a3b60e14ba516b26f740\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"97171b61720e78d909e9d7873ca60198d5c21097\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@97171b61720e78d909e9d7873ca60198d5c21097\", \"title\": \"97171b61720e78d909e9d7873ca60198d5c21097\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/97171b61720e78d909e9d7873ca60198d5c21097\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"47b91bc7b688a72e340dc8068b8a383d4c276419\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@47b91bc7b688a72e340dc8068b8a383d4c276419\", \"title\": \"47b91bc7b688a72e340dc8068b8a383d4c276419\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/47b91bc7b688a72e340dc8068b8a383d4c276419\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"8c42d78cbbf25c643b89fc4952fde78dea640395\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@8c42d78cbbf25c643b89fc4952fde78dea640395\", \"title\": \"8c42d78cbbf25c643b89fc4952fde78dea640395\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/8c42d78cbbf25c643b89fc4952fde78dea640395\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0cb688602df0ebf3a1de185202460d9a4a5d96e0\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0cb688602df0ebf3a1de185202460d9a4a5d96e0\", \"title\": \"0cb688602df0ebf3a1de185202460d9a4a5d96e0\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0cb688602df0ebf3a1de185202460d9a4a5d96e0\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0cb688602df0ebf3a1de185202460d9a4a5d96e0\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"0cb688602df0ebf3a1de185202460d9a4a5d96e0\", \"got_revision_cp\": \"refs/heads/master@{#38598}\"}, \"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-p3i2\"], \"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\": [\"5701-45850b2\"], \"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": "1613610047727892",
"status": "COMPLETED",
"status_changed_ts": "1613610053851615",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18638",
"builder:infra-continuous-win10-64",
"buildset:commit/git/0cb688602df0ebf3a1de185202460d9a4a5d96e0",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/0cb688602df0ebf3a1de185202460d9a4a5d96e0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020436069258564320",
"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/8855006007198274128/+/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:51cb2f4002888110",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1613610491394147",
"url": "https://luci-milo-dev.appspot.com/b/8855006007198274128",
"utcnow_ts": "1613610494670817"
}
}
[01:08:14.678] Invocation finished in 1h27m54.50745619s with status SUCCEEDED