[23:35:04.171] New invocation is queued and will start shortly
[23:35:05.301] Starting the invocation (attempt 1)
[23:35:05.342] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[23:35:05.342] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[23:35:05.342] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9030039679066895088",
"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\":\"da233907ad4195dfa371fac61e64da06130ad8e0\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@da233907ad4195dfa371fac61e64da06130ad8e0\",\"title\":\"da233907ad4195dfa371fac61e64da06130ad8e0\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/da233907ad4195dfa371fac61e64da06130ad8e0\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"53ad0d1e7e274ff87f4c4bce31a0cd432ac34be0\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@53ad0d1e7e274ff87f4c4bce31a0cd432ac34be0\",\"title\":\"53ad0d1e7e274ff87f4c4bce31a0cd432ac34be0\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/53ad0d1e7e274ff87f4c4bce31a0cd432ac34be0\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"9c92b2dc192ffb18f47bba9dbfe5116bcc5d15d5\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@9c92b2dc192ffb18f47bba9dbfe5116bcc5d15d5\",\"title\":\"9c92b2dc192ffb18f47bba9dbfe5116bcc5d15d5\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/9c92b2dc192ffb18f47bba9dbfe5116bcc5d15d5\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"d76b5509dedb88fa59170b285b0187a044233160\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d76b5509dedb88fa59170b285b0187a044233160\",\"title\":\"d76b5509dedb88fa59170b285b0187a044233160\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/d76b5509dedb88fa59170b285b0187a044233160\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"69858f7cbb218fb629663b23d3e6b74f5024cea8\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@69858f7cbb218fb629663b23d3e6b74f5024cea8\",\"title\":\"69858f7cbb218fb629663b23d3e6b74f5024cea8\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/69858f7cbb218fb629663b23d3e6b74f5024cea8\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"d671988b3ebd23d50ff01c1cec6b98a4c662ed73\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d671988b3ebd23d50ff01c1cec6b98a4c662ed73\",\"title\":\"d671988b3ebd23d50ff01c1cec6b98a4c662ed73\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/d671988b3ebd23d50ff01c1cec6b98a4c662ed73\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"c4447be244d2fd8d81583b7c805d568bd041bcb5\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c4447be244d2fd8d81583b7c805d568bd041bcb5\",\"title\":\"c4447be244d2fd8d81583b7c805d568bd041bcb5\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/c4447be244d2fd8d81583b7c805d568bd041bcb5\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"74109b4b7983b1b3883e6262c3ef773decf93113\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@74109b4b7983b1b3883e6262c3ef773decf93113\",\"title\":\"74109b4b7983b1b3883e6262c3ef773decf93113\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/74109b4b7983b1b3883e6262c3ef773decf93113\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"4131347b00cd6d063f17f32b76b2b7a1677c5e1f\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4131347b00cd6d063f17f32b76b2b7a1677c5e1f\",\"title\":\"4131347b00cd6d063f17f32b76b2b7a1677c5e1f\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/4131347b00cd6d063f17f32b76b2b7a1677c5e1f\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"4131347b00cd6d063f17f32b76b2b7a1677c5e1f\"}}",
"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:9030039679066895088",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/4131347b00cd6d063f17f32b76b2b7a1677c5e1f",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/4131347b00cd6d063f17f32b76b2b7a1677c5e1f",
"gitiles_ref:refs/heads/master"
]
}
[23:35:06.126] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1604446505425225",
"id": "8864609617085379120",
"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\": \"da233907ad4195dfa371fac61e64da06130ad8e0\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@da233907ad4195dfa371fac61e64da06130ad8e0\", \"title\": \"da233907ad4195dfa371fac61e64da06130ad8e0\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/da233907ad4195dfa371fac61e64da06130ad8e0\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"53ad0d1e7e274ff87f4c4bce31a0cd432ac34be0\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@53ad0d1e7e274ff87f4c4bce31a0cd432ac34be0\", \"title\": \"53ad0d1e7e274ff87f4c4bce31a0cd432ac34be0\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/53ad0d1e7e274ff87f4c4bce31a0cd432ac34be0\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"9c92b2dc192ffb18f47bba9dbfe5116bcc5d15d5\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@9c92b2dc192ffb18f47bba9dbfe5116bcc5d15d5\", \"title\": \"9c92b2dc192ffb18f47bba9dbfe5116bcc5d15d5\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/9c92b2dc192ffb18f47bba9dbfe5116bcc5d15d5\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"d76b5509dedb88fa59170b285b0187a044233160\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d76b5509dedb88fa59170b285b0187a044233160\", \"title\": \"d76b5509dedb88fa59170b285b0187a044233160\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/d76b5509dedb88fa59170b285b0187a044233160\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"69858f7cbb218fb629663b23d3e6b74f5024cea8\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@69858f7cbb218fb629663b23d3e6b74f5024cea8\", \"title\": \"69858f7cbb218fb629663b23d3e6b74f5024cea8\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/69858f7cbb218fb629663b23d3e6b74f5024cea8\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"d671988b3ebd23d50ff01c1cec6b98a4c662ed73\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d671988b3ebd23d50ff01c1cec6b98a4c662ed73\", \"title\": \"d671988b3ebd23d50ff01c1cec6b98a4c662ed73\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/d671988b3ebd23d50ff01c1cec6b98a4c662ed73\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"c4447be244d2fd8d81583b7c805d568bd041bcb5\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c4447be244d2fd8d81583b7c805d568bd041bcb5\", \"title\": \"c4447be244d2fd8d81583b7c805d568bd041bcb5\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/c4447be244d2fd8d81583b7c805d568bd041bcb5\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"74109b4b7983b1b3883e6262c3ef773decf93113\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@74109b4b7983b1b3883e6262c3ef773decf93113\", \"title\": \"74109b4b7983b1b3883e6262c3ef773decf93113\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/74109b4b7983b1b3883e6262c3ef773decf93113\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"4131347b00cd6d063f17f32b76b2b7a1677c5e1f\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4131347b00cd6d063f17f32b76b2b7a1677c5e1f\", \"title\": \"4131347b00cd6d063f17f32b76b2b7a1677c5e1f\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/4131347b00cd6d063f17f32b76b2b7a1677c5e1f\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"4131347b00cd6d063f17f32b76b2b7a1677c5e1f\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604446505994262",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17969",
"builder:infra-continuous-win10-64",
"buildset:commit/git/4131347b00cd6d063f17f32b76b2b7a1677c5e1f",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/4131347b00cd6d063f17f32b76b2b7a1677c5e1f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030039679066895088",
"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/8864609617085379120/+/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": "1604446505994384",
"url": "https://luci-milo-dev.appspot.com/b/8864609617085379120",
"utcnow_ts": "1604446506114239"
}
}
[23:35:06.126] Task URL: https://luci-milo-dev.appspot.com/b/8864609617085379120
[23:35:06.126] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:2:0) after 1m0s
[23:36:06.219] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:2:0)
[23:36:06.219] Timer tick, asking Buildbucket for the build status
[23:36:06.313] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:06.313] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:3:0) after 1m0s
[23:37:06.376] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:3:0)
[23:37:06.376] Timer tick, asking Buildbucket for the build status
[23:37:06.516] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:06.516] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:4:0) after 1m0s
[23:38:06.544] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:4:0)
[23:38:06.544] Timer tick, asking Buildbucket for the build status
[23:38:06.702] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:06.702] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:5:0) after 1m0s
[23:39:06.766] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:5:0)
[23:39:06.766] Timer tick, asking Buildbucket for the build status
[23:39:06.921] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:06.921] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:6:0) after 1m0s
[23:40:06.955] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:6:0)
[23:40:06.955] Timer tick, asking Buildbucket for the build status
[23:40:07.082] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:07.082] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:7:0) after 1m0s
[23:41:07.187] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:7:0)
[23:41:07.187] Timer tick, asking Buildbucket for the build status
[23:41:07.286] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:07.286] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:8:0) after 1m0s
[23:42:07.308] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:8:0)
[23:42:07.308] Timer tick, asking Buildbucket for the build status
[23:42:07.462] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:07.462] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:9:0) after 1m0s
[23:43:07.516] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:9:0)
[23:43:07.516] Timer tick, asking Buildbucket for the build status
[23:43:07.625] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:07.625] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:10:0) after 1m0s
[23:44:07.693] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:10:0)
[23:44:07.693] Timer tick, asking Buildbucket for the build status
[23:44:07.791] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:07.791] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:11:0) after 1m0s
[23:45:07.890] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:11:0)
[23:45:07.890] Timer tick, asking Buildbucket for the build status
[23:45:07.991] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:07.991] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:12:0) after 1m0s
[23:46:08.078] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:12:0)
[23:46:08.078] Timer tick, asking Buildbucket for the build status
[23:46:08.196] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:08.196] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:13:0) after 1m0s
[23:47:08.221] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:13:0)
[23:47:08.221] Timer tick, asking Buildbucket for the build status
[23:47:08.322] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:08.322] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:14:0) after 1m0s
[23:48:08.347] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:14:0)
[23:48:08.347] Timer tick, asking Buildbucket for the build status
[23:48:08.449] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:08.449] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:15:0) after 1m0s
[23:49:08.517] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:15:0)
[23:49:08.517] Timer tick, asking Buildbucket for the build status
[23:49:08.642] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:08.642] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:16:0) after 1m0s
[23:50:08.698] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:16:0)
[23:50:08.698] Timer tick, asking Buildbucket for the build status
[23:50:08.827] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:08.827] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:17:0) after 1m0s
[23:51:08.877] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:17:0)
[23:51:08.877] Timer tick, asking Buildbucket for the build status
[23:51:08.989] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:08.989] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:18:0) after 1m0s
[23:52:09.094] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:18:0)
[23:52:09.094] Timer tick, asking Buildbucket for the build status
[23:52:09.230] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:09.230] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:19:0) after 1m0s
[23:53:09.315] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:19:0)
[23:53:09.315] Timer tick, asking Buildbucket for the build status
[23:53:09.418] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:53:09.418] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:20:0) after 1m0s
[23:54:09.440] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:20:0)
[23:54:09.440] Timer tick, asking Buildbucket for the build status
[23:54:09.544] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:54:09.544] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:21:0) after 1m0s
[23:55:09.614] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:21:0)
[23:55:09.614] Timer tick, asking Buildbucket for the build status
[23:55:09.744] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:55:09.744] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:22:0) after 1m0s
[23:56:09.799] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:22:0)
[23:56:09.799] Timer tick, asking Buildbucket for the build status
[23:56:09.979] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:56:09.979] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:23:0) after 1m0s
[23:57:10.097] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:23:0)
[23:57:10.097] Timer tick, asking Buildbucket for the build status
[23:57:10.295] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:10.295] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:24:0) after 1m0s
[23:58:10.412] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:24:0)
[23:58:10.412] Timer tick, asking Buildbucket for the build status
[23:58:10.528] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:58:10.528] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:25:0) after 1m0s
[23:59:10.609] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:25:0)
[23:59:10.609] Timer tick, asking Buildbucket for the build status
[23:59:10.711] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:59:10.711] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:26:0) after 1m0s
[00:00:10.825] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:26:0)
[00:00:10.825] Timer tick, asking Buildbucket for the build status
[00:00:11.082] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:00:11.082] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:27:0) after 1m0s
[00:01:11.189] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:27:0)
[00:01:11.190] Timer tick, asking Buildbucket for the build status
[00:01:11.320] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:01:11.320] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:28:0) after 1m0s
[00:02:11.355] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:28:0)
[00:02:11.355] Timer tick, asking Buildbucket for the build status
[00:02:11.476] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:02:11.476] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:29:0) after 1m0s
[00:03:11.508] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:29:0)
[00:03:11.508] Timer tick, asking Buildbucket for the build status
[00:03:11.626] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:03:11.626] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:30:0) after 1m0s
[00:04:11.704] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:30:0)
[00:04:11.704] Timer tick, asking Buildbucket for the build status
[00:04:11.899] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:04:11.899] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:31:0) after 1m0s
[00:05:11.925] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:31:0)
[00:05:11.925] Timer tick, asking Buildbucket for the build status
[00:05:12.078] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:05:12.078] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:32:0) after 1m0s
[00:06:12.250] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:32:0)
[00:06:12.250] Timer tick, asking Buildbucket for the build status
[00:06:12.340] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:06:12.340] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:33:0) after 1m0s
[00:07:12.364] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:33:0)
[00:07:12.364] Timer tick, asking Buildbucket for the build status
[00:07:12.462] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:07:12.462] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:34:0) after 1m0s
[00:08:12.497] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:34:0)
[00:08:12.497] Timer tick, asking Buildbucket for the build status
[00:08:12.599] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:08:12.599] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:35:0) after 1m0s
[00:09:12.692] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:35:0)
[00:09:12.692] Timer tick, asking Buildbucket for the build status
[00:09:12.804] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:09:12.805] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:36:0) after 1m0s
[00:10:12.931] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:36:0)
[00:10:12.931] Timer tick, asking Buildbucket for the build status
[00:10:13.035] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:10:13.035] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:37:0) after 1m0s
[00:11:13.133] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:37:0)
[00:11:13.133] Timer tick, asking Buildbucket for the build status
[00:11:13.245] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:11:13.245] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:38:0) after 1m0s
[00:12:13.279] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:38:0)
[00:12:13.279] Timer tick, asking Buildbucket for the build status
[00:12:13.444] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:12:13.444] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:39:0) after 1m0s
[00:13:13.506] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:39:0)
[00:13:13.506] Timer tick, asking Buildbucket for the build status
[00:13:13.618] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:13:13.618] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:40:0) after 1m0s
[00:14:13.687] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:40:0)
[00:14:13.687] Timer tick, asking Buildbucket for the build status
[00:14:13.808] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:14:13.809] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:41:0) after 1m0s
[00:15:13.880] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:41:0)
[00:15:13.880] Timer tick, asking Buildbucket for the build status
[00:15:13.993] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:15:13.993] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:42:0) after 1m0s
[00:16:14.024] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:42:0)
[00:16:14.024] Timer tick, asking Buildbucket for the build status
[00:16:14.143] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:16:14.143] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:43:0) after 1m0s
[00:17:14.174] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:43:0)
[00:17:14.174] Timer tick, asking Buildbucket for the build status
[00:17:14.273] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:17:14.273] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:44:0) after 1m0s
[00:18:14.358] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:44:0)
[00:18:14.358] Timer tick, asking Buildbucket for the build status
[00:18:14.494] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:18:14.494] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:45:0) after 1m0s
[00:19:14.521] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:45:0)
[00:19:14.521] Timer tick, asking Buildbucket for the build status
[00:19:14.621] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:19:14.621] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:46:0) after 1m0s
[00:20:14.736] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:46:0)
[00:20:14.736] Timer tick, asking Buildbucket for the build status
[00:20:14.874] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:20:14.874] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:47:0) after 1m0s
[00:21:14.908] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:47:0)
[00:21:14.908] Timer tick, asking Buildbucket for the build status
[00:21:15.034] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:15.034] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:48:0) after 1m0s
[00:22:15.065] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:48:0)
[00:22:15.065] Timer tick, asking Buildbucket for the build status
[00:22:15.271] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:15.271] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:49:0) after 1m0s
[00:23:15.321] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:49:0)
[00:23:15.321] Timer tick, asking Buildbucket for the build status
[00:23:15.437] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:23:15.437] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:50:0) after 1m0s
[00:24:15.475] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:50:0)
[00:24:15.475] Timer tick, asking Buildbucket for the build status
[00:24:15.608] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:24:15.608] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:51:0) after 1m0s
[00:25:15.675] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:51:0)
[00:25:15.675] Timer tick, asking Buildbucket for the build status
[00:25:15.781] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:25:15.781] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:52:0) after 1m0s
[00:26:15.808] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:52:0)
[00:26:15.808] Timer tick, asking Buildbucket for the build status
[00:26:15.954] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:26:15.954] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:53:0) after 1m0s
[00:27:15.987] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:53:0)
[00:27:15.987] Timer tick, asking Buildbucket for the build status
[00:27:16.178] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:27:16.178] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:54:0) after 1m0s
[00:28:16.299] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:54:0)
[00:28:16.299] Timer tick, asking Buildbucket for the build status
[00:28:16.400] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:28:16.400] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:55:0) after 1m0s
[00:29:16.430] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:55:0)
[00:29:16.430] Timer tick, asking Buildbucket for the build status
[00:29:16.555] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:29:16.555] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:56:0) after 1m0s
[00:30:16.589] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:56:0)
[00:30:16.589] Timer tick, asking Buildbucket for the build status
[00:30:16.691] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:30:16.691] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:57:0) after 1m0s
[00:31:16.722] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:57:0)
[00:31:16.722] Timer tick, asking Buildbucket for the build status
[00:31:16.832] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:31:16.832] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:58:0) after 1m0s
[00:32:16.888] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:58:0)
[00:32:16.888] Timer tick, asking Buildbucket for the build status
[00:32:17.005] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:32:17.005] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:59:0) after 1m0s
[00:33:17.107] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:59:0)
[00:33:17.107] Timer tick, asking Buildbucket for the build status
[00:33:17.223] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:33:17.223] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:60:0) after 1m0s
[00:34:17.292] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:60:0)
[00:34:17.292] Timer tick, asking Buildbucket for the build status
[00:34:17.398] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:34:17.398] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:61:0) after 1m0s
[00:35:17.424] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:61:0)
[00:35:17.424] Timer tick, asking Buildbucket for the build status
[00:35:17.572] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:17.572] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:62:0) after 1m0s
[00:36:17.624] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:62:0)
[00:36:17.624] Timer tick, asking Buildbucket for the build status
[00:36:17.729] Build 8864609617085379120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:17.729] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:63:0) after 1m0s
[00:37:11.133] Received PubSub notification, asking Buildbucket for the build status
[00:37:11.268] Build 8864609617085379120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:17.819] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:63:0)
[00:37:17.819] Timer tick, asking Buildbucket for the build status
[00:37:17.921] Build 8864609617085379120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:17.921] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:65:0) after 1m0s
[00:38:18.035] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:65:0)
[00:38:18.035] Timer tick, asking Buildbucket for the build status
[00:38:18.137] Build 8864609617085379120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:18.137] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:66:0) after 1m0s
[00:39:18.168] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:66:0)
[00:39:18.168] Timer tick, asking Buildbucket for the build status
[00:39:18.278] Build 8864609617085379120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:18.278] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:67:0) after 1m0s
[00:40:18.306] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:67:0)
[00:40:18.306] Timer tick, asking Buildbucket for the build status
[00:40:18.406] Build 8864609617085379120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:18.406] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:68:0) after 1m0s
[00:41:18.505] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:68:0)
[00:41:18.505] Timer tick, asking Buildbucket for the build status
[00:41:18.612] Build 8864609617085379120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:18.612] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:69:0) after 1m0s
[00:42:18.638] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:69:0)
[00:42:18.638] Timer tick, asking Buildbucket for the build status
[00:42:18.759] Build 8864609617085379120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:18.759] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:70:0) after 1m0s
[00:43:18.791] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:70:0)
[00:43:18.791] Timer tick, asking Buildbucket for the build status
[00:43:18.897] Build 8864609617085379120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:18.897] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:71:0) after 1m0s
[00:44:18.963] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:71:0)
[00:44:18.963] Timer tick, asking Buildbucket for the build status
[00:44:19.161] Build 8864609617085379120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:19.161] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:72:0) after 1m0s
[00:45:19.219] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:72:0)
[00:45:19.219] Timer tick, asking Buildbucket for the build status
[00:45:19.411] Build 8864609617085379120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:19.411] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:73:0) after 1m0s
[00:46:19.447] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:73:0)
[00:46:19.447] Timer tick, asking Buildbucket for the build status
[00:46:19.572] Build 8864609617085379120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:19.572] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:74:0) after 1m0s
[00:47:19.600] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:74:0)
[00:47:19.600] Timer tick, asking Buildbucket for the build status
[00:47:19.698] Build 8864609617085379120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:19.698] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:75:0) after 1m0s
[00:48:19.815] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:75:0)
[00:48:19.815] Timer tick, asking Buildbucket for the build status
[00:48:19.914] Build 8864609617085379120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:19.914] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:76:0) after 1m0s
[00:49:19.988] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:76:0)
[00:49:19.988] Timer tick, asking Buildbucket for the build status
[00:49:20.087] Build 8864609617085379120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:20.087] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:77:0) after 1m0s
[00:50:20.197] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:77:0)
[00:50:20.197] Timer tick, asking Buildbucket for the build status
[00:50:20.352] Build 8864609617085379120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:20.352] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:78:0) after 1m0s
[00:51:20.380] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:78:0)
[00:51:20.380] Timer tick, asking Buildbucket for the build status
[00:51:20.517] Build 8864609617085379120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:20.517] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:79:0) after 1m0s
[00:52:20.597] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:79:0)
[00:52:20.597] Timer tick, asking Buildbucket for the build status
[00:52:20.716] Build 8864609617085379120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:20.716] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:80:0) after 1m0s
[00:53:20.822] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:80:0)
[00:53:20.822] Timer tick, asking Buildbucket for the build status
[00:53:20.921] Build 8864609617085379120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:20.921] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9030039679066895088:81:0) after 1m0s
[00:53:46.593] Received PubSub notification, asking Buildbucket for the build status
[00:53:46.704] Build 8864609617085379120: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:53:46.704] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1604451222706863",
"created_by": "project:infra",
"created_ts": "1604446505425225",
"id": "8864609617085379120",
"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\": \"da233907ad4195dfa371fac61e64da06130ad8e0\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@da233907ad4195dfa371fac61e64da06130ad8e0\", \"title\": \"da233907ad4195dfa371fac61e64da06130ad8e0\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/da233907ad4195dfa371fac61e64da06130ad8e0\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"53ad0d1e7e274ff87f4c4bce31a0cd432ac34be0\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@53ad0d1e7e274ff87f4c4bce31a0cd432ac34be0\", \"title\": \"53ad0d1e7e274ff87f4c4bce31a0cd432ac34be0\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/53ad0d1e7e274ff87f4c4bce31a0cd432ac34be0\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"9c92b2dc192ffb18f47bba9dbfe5116bcc5d15d5\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@9c92b2dc192ffb18f47bba9dbfe5116bcc5d15d5\", \"title\": \"9c92b2dc192ffb18f47bba9dbfe5116bcc5d15d5\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/9c92b2dc192ffb18f47bba9dbfe5116bcc5d15d5\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"d76b5509dedb88fa59170b285b0187a044233160\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d76b5509dedb88fa59170b285b0187a044233160\", \"title\": \"d76b5509dedb88fa59170b285b0187a044233160\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/d76b5509dedb88fa59170b285b0187a044233160\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"69858f7cbb218fb629663b23d3e6b74f5024cea8\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@69858f7cbb218fb629663b23d3e6b74f5024cea8\", \"title\": \"69858f7cbb218fb629663b23d3e6b74f5024cea8\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/69858f7cbb218fb629663b23d3e6b74f5024cea8\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"d671988b3ebd23d50ff01c1cec6b98a4c662ed73\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d671988b3ebd23d50ff01c1cec6b98a4c662ed73\", \"title\": \"d671988b3ebd23d50ff01c1cec6b98a4c662ed73\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/d671988b3ebd23d50ff01c1cec6b98a4c662ed73\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"c4447be244d2fd8d81583b7c805d568bd041bcb5\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c4447be244d2fd8d81583b7c805d568bd041bcb5\", \"title\": \"c4447be244d2fd8d81583b7c805d568bd041bcb5\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/c4447be244d2fd8d81583b7c805d568bd041bcb5\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"74109b4b7983b1b3883e6262c3ef773decf93113\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@74109b4b7983b1b3883e6262c3ef773decf93113\", \"title\": \"74109b4b7983b1b3883e6262c3ef773decf93113\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/74109b4b7983b1b3883e6262c3ef773decf93113\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"4131347b00cd6d063f17f32b76b2b7a1677c5e1f\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4131347b00cd6d063f17f32b76b2b7a1677c5e1f\", \"title\": \"4131347b00cd6d063f17f32b76b2b7a1677c5e1f\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/4131347b00cd6d063f17f32b76b2b7a1677c5e1f\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"4131347b00cd6d063f17f32b76b2b7a1677c5e1f\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"4131347b00cd6d063f17f32b76b2b7a1677c5e1f\", \"got_revision_cp\": \"refs/heads/master@{#36175}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_v2\", \"git\", \"goma\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"dev-luci-chromium-ci-win10-0-yt87\"], \"image\": [\"chrome-win10-1703-20101600-6f576df2987\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5622-42c9106\"], \"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": "1604450220945007",
"status": "COMPLETED",
"status_changed_ts": "1604451223228657",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17969",
"builder:infra-continuous-win10-64",
"buildset:commit/git/4131347b00cd6d063f17f32b76b2b7a1677c5e1f",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/4131347b00cd6d063f17f32b76b2b7a1677c5e1f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030039679066895088",
"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/8864609617085379120/+/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:4fa94855f9d93c10",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1604451223291845",
"url": "https://luci-milo-dev.appspot.com/b/8864609617085379120",
"utcnow_ts": "1604451226695087"
}
}
[00:53:46.704] Invocation finished in 1h18m42.552097095s with status SUCCEEDED