[21:27:00.092] New invocation is queued and will start shortly
[21:27:01.170] Starting the invocation (attempt 1)
[21:27:01.219] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[21:27:01.225] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[21:27:01.225] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9021894009431909600",
"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\":\"e961002fbd1c307945946adfe4305f723f1283b1\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@e961002fbd1c307945946adfe4305f723f1283b1\",\"title\":\"e961002fbd1c307945946adfe4305f723f1283b1\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/e961002fbd1c307945946adfe4305f723f1283b1\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"a3ad02c8e43252e3ddebe52df2e6f5a3d53fd2bc\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a3ad02c8e43252e3ddebe52df2e6f5a3d53fd2bc\",\"title\":\"a3ad02c8e43252e3ddebe52df2e6f5a3d53fd2bc\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/a3ad02c8e43252e3ddebe52df2e6f5a3d53fd2bc\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"0553bf160a7a67221600f2574713d1f75bb9461e\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0553bf160a7a67221600f2574713d1f75bb9461e\",\"title\":\"0553bf160a7a67221600f2574713d1f75bb9461e\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/0553bf160a7a67221600f2574713d1f75bb9461e\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"7f9f34ea02ea8ed79edbd38c5ca49f348cb68a1c\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@7f9f34ea02ea8ed79edbd38c5ca49f348cb68a1c\",\"title\":\"7f9f34ea02ea8ed79edbd38c5ca49f348cb68a1c\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/7f9f34ea02ea8ed79edbd38c5ca49f348cb68a1c\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"332b94b98c40532e1085ea1675cdb0c8b3fe1b39\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@332b94b98c40532e1085ea1675cdb0c8b3fe1b39\",\"title\":\"332b94b98c40532e1085ea1675cdb0c8b3fe1b39\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/332b94b98c40532e1085ea1675cdb0c8b3fe1b39\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"567429e4b44045f0f22d236c6fd8fcb08f3a60fb\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@567429e4b44045f0f22d236c6fd8fcb08f3a60fb\",\"title\":\"567429e4b44045f0f22d236c6fd8fcb08f3a60fb\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/567429e4b44045f0f22d236c6fd8fcb08f3a60fb\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"6e07743d0e9a7f8d745f65391296677391b7b212\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@6e07743d0e9a7f8d745f65391296677391b7b212\",\"title\":\"6e07743d0e9a7f8d745f65391296677391b7b212\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/6e07743d0e9a7f8d745f65391296677391b7b212\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"8ff58163c198202be2041d6338cd7e2fc4c1b35a\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@8ff58163c198202be2041d6338cd7e2fc4c1b35a\",\"title\":\"8ff58163c198202be2041d6338cd7e2fc4c1b35a\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/8ff58163c198202be2041d6338cd7e2fc4c1b35a\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"8ff58163c198202be2041d6338cd7e2fc4c1b35a\"}}",
"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:9021894009431909600",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/8ff58163c198202be2041d6338cd7e2fc4c1b35a",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/8ff58163c198202be2041d6338cd7e2fc4c1b35a",
"gitiles_ref:refs/heads/master"
]
}
[21:27:02.047] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1612214821312956",
"id": "8856463947486280576",
"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\": \"e961002fbd1c307945946adfe4305f723f1283b1\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@e961002fbd1c307945946adfe4305f723f1283b1\", \"title\": \"e961002fbd1c307945946adfe4305f723f1283b1\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/e961002fbd1c307945946adfe4305f723f1283b1\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"a3ad02c8e43252e3ddebe52df2e6f5a3d53fd2bc\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a3ad02c8e43252e3ddebe52df2e6f5a3d53fd2bc\", \"title\": \"a3ad02c8e43252e3ddebe52df2e6f5a3d53fd2bc\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/a3ad02c8e43252e3ddebe52df2e6f5a3d53fd2bc\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0553bf160a7a67221600f2574713d1f75bb9461e\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0553bf160a7a67221600f2574713d1f75bb9461e\", \"title\": \"0553bf160a7a67221600f2574713d1f75bb9461e\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0553bf160a7a67221600f2574713d1f75bb9461e\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"7f9f34ea02ea8ed79edbd38c5ca49f348cb68a1c\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@7f9f34ea02ea8ed79edbd38c5ca49f348cb68a1c\", \"title\": \"7f9f34ea02ea8ed79edbd38c5ca49f348cb68a1c\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/7f9f34ea02ea8ed79edbd38c5ca49f348cb68a1c\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"332b94b98c40532e1085ea1675cdb0c8b3fe1b39\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@332b94b98c40532e1085ea1675cdb0c8b3fe1b39\", \"title\": \"332b94b98c40532e1085ea1675cdb0c8b3fe1b39\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/332b94b98c40532e1085ea1675cdb0c8b3fe1b39\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"567429e4b44045f0f22d236c6fd8fcb08f3a60fb\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@567429e4b44045f0f22d236c6fd8fcb08f3a60fb\", \"title\": \"567429e4b44045f0f22d236c6fd8fcb08f3a60fb\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/567429e4b44045f0f22d236c6fd8fcb08f3a60fb\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"6e07743d0e9a7f8d745f65391296677391b7b212\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@6e07743d0e9a7f8d745f65391296677391b7b212\", \"title\": \"6e07743d0e9a7f8d745f65391296677391b7b212\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/6e07743d0e9a7f8d745f65391296677391b7b212\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"8ff58163c198202be2041d6338cd7e2fc4c1b35a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@8ff58163c198202be2041d6338cd7e2fc4c1b35a\", \"title\": \"8ff58163c198202be2041d6338cd7e2fc4c1b35a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/8ff58163c198202be2041d6338cd7e2fc4c1b35a\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"8ff58163c198202be2041d6338cd7e2fc4c1b35a\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612214821864041",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18487",
"builder:infra-continuous-win10-64",
"buildset:commit/git/8ff58163c198202be2041d6338cd7e2fc4c1b35a",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/8ff58163c198202be2041d6338cd7e2fc4c1b35a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021894009431909600",
"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/8856463947486280576/+/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": "1612214821864158",
"url": "https://luci-milo-dev.appspot.com/b/8856463947486280576",
"utcnow_ts": "1612214822026724"
}
}
[21:27:02.048] Task URL: https://luci-milo-dev.appspot.com/b/8856463947486280576
[21:27:02.048] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:2:0) after 1m0s
[21:28:02.142] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:2:0)
[21:28:02.142] Timer tick, asking Buildbucket for the build status
[21:28:02.316] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:28:02.316] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:3:0) after 1m0s
[21:29:02.401] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:3:0)
[21:29:02.401] Timer tick, asking Buildbucket for the build status
[21:29:02.511] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:29:02.511] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:4:0) after 1m0s
[21:30:02.536] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:4:0)
[21:30:02.536] Timer tick, asking Buildbucket for the build status
[21:30:02.682] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:30:02.682] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:5:0) after 1m0s
[21:31:02.723] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:5:0)
[21:31:02.723] Timer tick, asking Buildbucket for the build status
[21:31:02.829] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:31:02.829] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:6:0) after 1m0s
[21:32:02.943] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:6:0)
[21:32:02.943] Timer tick, asking Buildbucket for the build status
[21:32:03.051] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:32:03.051] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:7:0) after 1m0s
[21:33:03.072] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:7:0)
[21:33:03.072] Timer tick, asking Buildbucket for the build status
[21:33:03.473] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:33:03.473] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:8:0) after 1m0s
[21:34:03.493] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:8:0)
[21:34:03.493] Timer tick, asking Buildbucket for the build status
[21:34:03.607] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:34:03.607] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:9:0) after 1m0s
[21:35:03.695] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:9:0)
[21:35:03.695] Timer tick, asking Buildbucket for the build status
[21:35:03.941] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:35:03.941] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:10:0) after 1m0s
[21:36:03.992] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:10:0)
[21:36:03.992] Timer tick, asking Buildbucket for the build status
[21:36:04.106] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:36:04.106] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:11:0) after 1m0s
[21:37:04.148] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:11:0)
[21:37:04.148] Timer tick, asking Buildbucket for the build status
[21:37:04.246] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:37:04.246] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:12:0) after 1m0s
[21:38:04.288] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:12:0)
[21:38:04.288] Timer tick, asking Buildbucket for the build status
[21:38:04.424] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:38:04.424] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:13:0) after 1m0s
[21:39:04.456] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:13:0)
[21:39:04.456] Timer tick, asking Buildbucket for the build status
[21:39:04.778] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:39:04.778] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:14:0) after 1m0s
[21:40:04.809] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:14:0)
[21:40:04.809] Timer tick, asking Buildbucket for the build status
[21:40:05.166] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:40:05.166] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:15:0) after 1m0s
[21:41:05.200] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:15:0)
[21:41:05.200] Timer tick, asking Buildbucket for the build status
[21:41:05.511] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:41:05.511] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:16:0) after 1m0s
[21:42:05.549] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:16:0)
[21:42:05.549] Timer tick, asking Buildbucket for the build status
[21:42:05.823] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:42:05.823] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:17:0) after 1m0s
[21:43:05.851] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:17:0)
[21:43:05.851] Timer tick, asking Buildbucket for the build status
[21:43:06.181] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:43:06.181] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:18:0) after 1m0s
[21:44:06.242] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:18:0)
[21:44:06.242] Timer tick, asking Buildbucket for the build status
[21:44:06.668] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:44:06.668] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:19:0) after 1m0s
[21:45:06.765] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:19:0)
[21:45:06.765] Timer tick, asking Buildbucket for the build status
[21:45:07.036] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:45:07.036] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:20:0) after 1m0s
[21:46:07.145] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:20:0)
[21:46:07.146] Timer tick, asking Buildbucket for the build status
[21:46:07.576] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:46:07.576] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:21:0) after 1m0s
[21:47:07.599] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:21:0)
[21:47:07.599] Timer tick, asking Buildbucket for the build status
[21:47:07.920] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:47:07.920] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:22:0) after 1m0s
[21:48:07.955] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:22:0)
[21:48:07.955] Timer tick, asking Buildbucket for the build status
[21:48:08.281] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:08.281] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:23:0) after 1m0s
[21:49:08.308] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:23:0)
[21:49:08.308] Timer tick, asking Buildbucket for the build status
[21:49:08.625] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:08.625] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:24:0) after 1m0s
[21:50:08.664] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:24:0)
[21:50:08.664] Timer tick, asking Buildbucket for the build status
[21:50:09.006] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:09.006] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:25:0) after 1m0s
[21:51:09.166] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:25:0)
[21:51:09.166] Timer tick, asking Buildbucket for the build status
[21:51:09.458] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:09.458] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:26:0) after 1m0s
[21:52:09.495] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:26:0)
[21:52:09.495] Timer tick, asking Buildbucket for the build status
[21:52:09.840] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:09.840] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:27:0) after 1m0s
[21:53:09.891] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:27:0)
[21:53:09.891] Timer tick, asking Buildbucket for the build status
[21:53:10.145] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:10.145] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:28:0) after 1m0s
[21:54:10.178] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:28:0)
[21:54:10.178] Timer tick, asking Buildbucket for the build status
[21:54:10.404] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:10.404] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:29:0) after 1m0s
[21:55:10.444] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:29:0)
[21:55:10.444] Timer tick, asking Buildbucket for the build status
[21:55:10.673] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:10.673] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:30:0) after 1m0s
[21:56:10.695] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:30:0)
[21:56:10.695] Timer tick, asking Buildbucket for the build status
[21:56:10.985] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:10.985] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:31:0) after 1m0s
[21:57:11.014] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:31:0)
[21:57:11.014] Timer tick, asking Buildbucket for the build status
[21:57:11.363] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:11.363] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:32:0) after 1m0s
[21:58:11.441] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:32:0)
[21:58:11.441] Timer tick, asking Buildbucket for the build status
[21:58:11.824] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:11.824] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:33:0) after 1m0s
[21:59:11.897] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:33:0)
[21:59:11.897] Timer tick, asking Buildbucket for the build status
[21:59:12.245] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:59:12.245] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:34:0) after 1m0s
[22:00:12.296] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:34:0)
[22:00:12.296] Timer tick, asking Buildbucket for the build status
[22:00:12.590] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:00:12.590] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:35:0) after 1m0s
[22:01:12.658] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:35:0)
[22:01:12.658] Timer tick, asking Buildbucket for the build status
[22:01:12.978] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:01:12.979] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:36:0) after 1m0s
[22:02:13.002] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:36:0)
[22:02:13.002] Timer tick, asking Buildbucket for the build status
[22:02:13.282] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:02:13.282] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:37:0) after 1m0s
[22:03:13.355] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:37:0)
[22:03:13.355] Timer tick, asking Buildbucket for the build status
[22:03:13.701] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:13.701] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:38:0) after 1m0s
[22:04:13.755] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:38:0)
[22:04:13.755] Timer tick, asking Buildbucket for the build status
[22:04:14.058] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:14.058] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:39:0) after 1m0s
[22:05:14.144] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:39:0)
[22:05:14.144] Timer tick, asking Buildbucket for the build status
[22:05:14.431] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:14.431] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:40:0) after 1m0s
[22:06:14.541] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:40:0)
[22:06:14.541] Timer tick, asking Buildbucket for the build status
[22:06:14.837] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:14.837] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:41:0) after 1m0s
[22:07:14.972] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:41:0)
[22:07:14.972] Timer tick, asking Buildbucket for the build status
[22:07:15.286] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:15.286] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:42:0) after 1m0s
[22:08:15.398] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:42:0)
[22:08:15.398] Timer tick, asking Buildbucket for the build status
[22:08:15.680] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:15.680] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:43:0) after 1m0s
[22:09:15.714] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:43:0)
[22:09:15.714] Timer tick, asking Buildbucket for the build status
[22:09:16.035] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:16.035] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:44:0) after 1m0s
[22:10:16.141] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:44:0)
[22:10:16.141] Timer tick, asking Buildbucket for the build status
[22:10:16.394] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:16.394] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:45:0) after 1m0s
[22:11:16.491] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:45:0)
[22:11:16.491] Timer tick, asking Buildbucket for the build status
[22:11:16.774] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:16.774] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:46:0) after 1m0s
[22:12:16.797] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:46:0)
[22:12:16.797] Timer tick, asking Buildbucket for the build status
[22:12:18.302] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:18.302] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:47:0) after 1m0s
[22:13:18.342] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:47:0)
[22:13:18.342] Timer tick, asking Buildbucket for the build status
[22:13:18.612] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:18.612] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:48:0) after 1m0s
[22:14:18.699] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:48:0)
[22:14:18.699] Timer tick, asking Buildbucket for the build status
[22:14:19.017] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:19.017] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:49:0) after 1m0s
[22:15:19.044] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:49:0)
[22:15:19.044] Timer tick, asking Buildbucket for the build status
[22:15:19.343] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:19.343] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:50:0) after 1m0s
[22:16:19.439] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:50:0)
[22:16:19.439] Timer tick, asking Buildbucket for the build status
[22:16:19.737] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:19.737] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:51:0) after 1m0s
[22:17:19.773] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:51:0)
[22:17:19.773] Timer tick, asking Buildbucket for the build status
[22:17:20.079] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:20.079] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:52:0) after 1m0s
[22:18:20.147] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:52:0)
[22:18:20.147] Timer tick, asking Buildbucket for the build status
[22:18:20.400] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:20.400] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:53:0) after 1m0s
[22:19:20.521] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:53:0)
[22:19:20.521] Timer tick, asking Buildbucket for the build status
[22:19:20.796] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:20.796] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:54:0) after 1m0s
[22:20:20.901] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:54:0)
[22:20:20.901] Timer tick, asking Buildbucket for the build status
[22:20:21.173] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:21.173] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:55:0) after 1m0s
[22:21:21.329] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:55:0)
[22:21:21.329] Timer tick, asking Buildbucket for the build status
[22:21:21.580] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:21.580] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:56:0) after 1m0s
[22:22:21.641] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:56:0)
[22:22:21.641] Timer tick, asking Buildbucket for the build status
[22:22:21.960] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:21.960] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:57:0) after 1m0s
[22:23:22.010] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:57:0)
[22:23:22.010] Timer tick, asking Buildbucket for the build status
[22:23:22.271] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:22.271] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:58:0) after 1m0s
[22:24:22.798] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:58:0)
[22:24:22.798] Timer tick, asking Buildbucket for the build status
[22:24:23.112] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:23.112] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:59:0) after 1m0s
[22:25:23.199] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:59:0)
[22:25:23.199] Timer tick, asking Buildbucket for the build status
[22:25:23.553] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:23.553] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:60:0) after 1m0s
[22:26:23.697] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:60:0)
[22:26:23.697] Timer tick, asking Buildbucket for the build status
[22:26:23.829] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:23.829] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:61:0) after 1m0s
[22:27:23.891] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:61:0)
[22:27:23.891] Timer tick, asking Buildbucket for the build status
[22:27:24.177] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:24.177] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:62:0) after 1m0s
[22:28:24.265] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:62:0)
[22:28:24.265] Timer tick, asking Buildbucket for the build status
[22:28:24.510] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:24.510] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:63:0) after 1m0s
[22:29:24.604] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:63:0)
[22:29:24.604] Timer tick, asking Buildbucket for the build status
[22:29:24.843] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:24.843] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:64:0) after 1m0s
[22:30:24.915] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:64:0)
[22:30:24.915] Timer tick, asking Buildbucket for the build status
[22:30:25.265] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:25.265] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:65:0) after 1m0s
[22:31:25.422] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:65:0)
[22:31:25.422] Timer tick, asking Buildbucket for the build status
[22:31:25.758] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:25.758] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:66:0) after 1m0s
[22:32:25.805] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:66:0)
[22:32:25.805] Timer tick, asking Buildbucket for the build status
[22:32:26.172] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:26.172] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:67:0) after 1m0s
[22:33:26.236] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:67:0)
[22:33:26.236] Timer tick, asking Buildbucket for the build status
[22:33:26.488] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:26.488] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:68:0) after 1m0s
[22:34:26.531] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:68:0)
[22:34:26.531] Timer tick, asking Buildbucket for the build status
[22:34:26.865] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:26.865] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:69:0) after 1m0s
[22:35:27.013] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:69:0)
[22:35:27.013] Timer tick, asking Buildbucket for the build status
[22:35:27.166] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:27.166] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:70:0) after 1m0s
[22:36:27.235] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:70:0)
[22:36:27.235] Timer tick, asking Buildbucket for the build status
[22:36:27.533] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:27.533] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:71:0) after 1m0s
[22:37:27.712] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:71:0)
[22:37:27.712] Timer tick, asking Buildbucket for the build status
[22:37:27.827] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:27.827] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:72:0) after 1m0s
[22:38:27.912] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:72:0)
[22:38:27.912] Timer tick, asking Buildbucket for the build status
[22:38:28.183] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:28.183] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:73:0) after 1m0s
[22:39:28.317] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:73:0)
[22:39:28.317] Timer tick, asking Buildbucket for the build status
[22:39:28.508] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:28.508] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:74:0) after 1m0s
[22:40:28.617] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:74:0)
[22:40:28.617] Timer tick, asking Buildbucket for the build status
[22:40:28.895] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:28.895] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:75:0) after 1m0s
[22:41:29.035] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:75:0)
[22:41:29.035] Timer tick, asking Buildbucket for the build status
[22:41:29.460] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:29.460] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:76:0) after 1m0s
[22:42:29.515] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:76:0)
[22:42:29.515] Timer tick, asking Buildbucket for the build status
[22:42:29.838] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:29.838] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:77:0) after 1m0s
[22:43:29.921] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:77:0)
[22:43:29.921] Timer tick, asking Buildbucket for the build status
[22:43:30.086] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:30.086] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:78:0) after 1m0s
[22:44:30.203] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:78:0)
[22:44:30.203] Timer tick, asking Buildbucket for the build status
[22:44:30.668] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:30.668] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:79:0) after 1m0s
[22:45:30.796] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:79:0)
[22:45:30.796] Timer tick, asking Buildbucket for the build status
[22:45:30.967] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:30.967] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:80:0) after 1m0s
[22:46:31.202] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:80:0)
[22:46:31.202] Timer tick, asking Buildbucket for the build status
[22:46:31.295] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:31.295] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:81:0) after 1m0s
[22:47:31.465] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:81:0)
[22:47:31.465] Timer tick, asking Buildbucket for the build status
[22:47:31.691] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:31.691] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:82:0) after 1m0s
[22:48:31.820] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:82:0)
[22:48:31.820] Timer tick, asking Buildbucket for the build status
[22:48:31.978] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:31.978] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:83:0) after 1m0s
[22:49:32.032] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:83:0)
[22:49:32.032] Timer tick, asking Buildbucket for the build status
[22:49:32.124] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:32.124] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:84:0) after 1m0s
[22:50:32.198] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:84:0)
[22:50:32.198] Timer tick, asking Buildbucket for the build status
[22:50:32.278] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:32.278] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:85:0) after 1m0s
[22:51:32.318] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:85:0)
[22:51:32.318] Timer tick, asking Buildbucket for the build status
[22:51:32.414] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:32.414] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:86:0) after 1m0s
[22:52:32.451] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:86:0)
[22:52:32.451] Timer tick, asking Buildbucket for the build status
[22:52:32.541] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:32.541] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:87:0) after 1m0s
[22:53:32.634] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:87:0)
[22:53:32.634] Timer tick, asking Buildbucket for the build status
[22:53:32.762] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:32.762] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:88:0) after 1m0s
[22:54:32.818] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:88:0)
[22:54:32.818] Timer tick, asking Buildbucket for the build status
[22:54:32.982] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:32.982] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:89:0) after 1m0s
[22:55:33.022] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:89:0)
[22:55:33.022] Timer tick, asking Buildbucket for the build status
[22:55:33.209] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:33.209] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:90:0) after 1m0s
[22:56:33.307] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:90:0)
[22:56:33.307] Timer tick, asking Buildbucket for the build status
[22:56:33.531] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:33.531] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:91:0) after 1m0s
[22:57:33.731] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:91:0)
[22:57:33.731] Timer tick, asking Buildbucket for the build status
[22:57:33.823] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:33.823] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:92:0) after 1m0s
[22:58:33.957] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:92:0)
[22:58:33.957] Timer tick, asking Buildbucket for the build status
[22:58:34.097] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:34.097] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:93:0) after 1m0s
[22:59:34.297] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:93:0)
[22:59:34.297] Timer tick, asking Buildbucket for the build status
[22:59:34.421] Build 8856463947486280576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:34.421] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:94:0) after 1m0s
[22:59:50.525] Received PubSub notification, asking Buildbucket for the build status
[22:59:50.651] Build 8856463947486280576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:34.456] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:94:0)
[23:00:34.456] Timer tick, asking Buildbucket for the build status
[23:00:34.625] Build 8856463947486280576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:34.625] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:96:0) after 1m0s
[23:01:34.700] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:96:0)
[23:01:34.700] Timer tick, asking Buildbucket for the build status
[23:01:34.884] Build 8856463947486280576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:34.884] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:97:0) after 1m0s
[23:02:34.917] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:97:0)
[23:02:34.917] Timer tick, asking Buildbucket for the build status
[23:02:35.117] Build 8856463947486280576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:35.117] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:98:0) after 1m0s
[23:03:35.313] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:98:0)
[23:03:35.313] Timer tick, asking Buildbucket for the build status
[23:03:35.491] Build 8856463947486280576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:35.491] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:99:0) after 1m0s
[23:04:35.614] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:99:0)
[23:04:35.614] Timer tick, asking Buildbucket for the build status
[23:04:35.750] Build 8856463947486280576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:35.750] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:100:0) after 1m0s
[23:05:35.823] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:100:0)
[23:05:35.823] Timer tick, asking Buildbucket for the build status
[23:05:36.020] Build 8856463947486280576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:36.020] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9021894009431909600:101:0) after 1m0s
[23:05:46.113] Received PubSub notification, asking Buildbucket for the build status
[23:05:46.220] Build 8856463947486280576: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:05:46.220] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1612220742265675",
"created_by": "project:infra",
"created_ts": "1612214821312956",
"id": "8856463947486280576",
"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\": \"e961002fbd1c307945946adfe4305f723f1283b1\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@e961002fbd1c307945946adfe4305f723f1283b1\", \"title\": \"e961002fbd1c307945946adfe4305f723f1283b1\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/e961002fbd1c307945946adfe4305f723f1283b1\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"a3ad02c8e43252e3ddebe52df2e6f5a3d53fd2bc\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a3ad02c8e43252e3ddebe52df2e6f5a3d53fd2bc\", \"title\": \"a3ad02c8e43252e3ddebe52df2e6f5a3d53fd2bc\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/a3ad02c8e43252e3ddebe52df2e6f5a3d53fd2bc\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0553bf160a7a67221600f2574713d1f75bb9461e\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0553bf160a7a67221600f2574713d1f75bb9461e\", \"title\": \"0553bf160a7a67221600f2574713d1f75bb9461e\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0553bf160a7a67221600f2574713d1f75bb9461e\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"7f9f34ea02ea8ed79edbd38c5ca49f348cb68a1c\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@7f9f34ea02ea8ed79edbd38c5ca49f348cb68a1c\", \"title\": \"7f9f34ea02ea8ed79edbd38c5ca49f348cb68a1c\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/7f9f34ea02ea8ed79edbd38c5ca49f348cb68a1c\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"332b94b98c40532e1085ea1675cdb0c8b3fe1b39\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@332b94b98c40532e1085ea1675cdb0c8b3fe1b39\", \"title\": \"332b94b98c40532e1085ea1675cdb0c8b3fe1b39\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/332b94b98c40532e1085ea1675cdb0c8b3fe1b39\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"567429e4b44045f0f22d236c6fd8fcb08f3a60fb\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@567429e4b44045f0f22d236c6fd8fcb08f3a60fb\", \"title\": \"567429e4b44045f0f22d236c6fd8fcb08f3a60fb\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/567429e4b44045f0f22d236c6fd8fcb08f3a60fb\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"6e07743d0e9a7f8d745f65391296677391b7b212\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@6e07743d0e9a7f8d745f65391296677391b7b212\", \"title\": \"6e07743d0e9a7f8d745f65391296677391b7b212\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/6e07743d0e9a7f8d745f65391296677391b7b212\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"8ff58163c198202be2041d6338cd7e2fc4c1b35a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@8ff58163c198202be2041d6338cd7e2fc4c1b35a\", \"title\": \"8ff58163c198202be2041d6338cd7e2fc4c1b35a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/8ff58163c198202be2041d6338cd7e2fc4c1b35a\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"8ff58163c198202be2041d6338cd7e2fc4c1b35a\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"8ff58163c198202be2041d6338cd7e2fc4c1b35a\", \"got_revision_cp\": \"refs/heads/master@{#38112}\"}, \"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-64st\"], \"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\": [\"5675-ebdc400\"], \"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": "1612220386737137",
"status": "COMPLETED",
"status_changed_ts": "1612220743683847",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18487",
"builder:infra-continuous-win10-64",
"buildset:commit/git/8ff58163c198202be2041d6338cd7e2fc4c1b35a",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/8ff58163c198202be2041d6338cd7e2fc4c1b35a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021894009431909600",
"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/8856463947486280576/+/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:51784f6e33f6af10",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1612220743737786",
"url": "https://luci-milo-dev.appspot.com/b/8856463947486280576",
"utcnow_ts": "1612220746206660"
}
}
[23:05:46.220] Invocation finished in 1h38m46.146759163s with status SUCCEEDED