[05:41:12.071] New invocation is queued and will start shortly
[05:41:13.206] Starting the invocation (attempt 1)
[05:41:13.247] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[05:41:13.247] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[05:41:13.247] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9024127841234957168",
"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\":\"720a184cbeca12e601b684c99fdcd25edfe81c10\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@720a184cbeca12e601b684c99fdcd25edfe81c10\",\"title\":\"720a184cbeca12e601b684c99fdcd25edfe81c10\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/720a184cbeca12e601b684c99fdcd25edfe81c10\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"ca4e5ef5d05265d2b89661e0b6d6cf74f3d7d5a8\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ca4e5ef5d05265d2b89661e0b6d6cf74f3d7d5a8\",\"title\":\"ca4e5ef5d05265d2b89661e0b6d6cf74f3d7d5a8\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/ca4e5ef5d05265d2b89661e0b6d6cf74f3d7d5a8\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"f7c7d8243b0824c7fa8d7404893ed3983384c523\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f7c7d8243b0824c7fa8d7404893ed3983384c523\",\"title\":\"f7c7d8243b0824c7fa8d7404893ed3983384c523\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/f7c7d8243b0824c7fa8d7404893ed3983384c523\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"a3d32d4ebd045e0042b655e9ec40d13371e348f6\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a3d32d4ebd045e0042b655e9ec40d13371e348f6\",\"title\":\"a3d32d4ebd045e0042b655e9ec40d13371e348f6\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/a3d32d4ebd045e0042b655e9ec40d13371e348f6\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"1e161a42efe5a21abcae9ea23c3ae55a9e150e5b\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@1e161a42efe5a21abcae9ea23c3ae55a9e150e5b\",\"title\":\"1e161a42efe5a21abcae9ea23c3ae55a9e150e5b\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/1e161a42efe5a21abcae9ea23c3ae55a9e150e5b\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"9450eafb53fce13ce280030d62f237d1a835df20\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@9450eafb53fce13ce280030d62f237d1a835df20\",\"title\":\"9450eafb53fce13ce280030d62f237d1a835df20\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/9450eafb53fce13ce280030d62f237d1a835df20\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"9450eafb53fce13ce280030d62f237d1a835df20\"}}",
"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:9024127841234957168",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/9450eafb53fce13ce280030d62f237d1a835df20",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/9450eafb53fce13ce280030d62f237d1a835df20",
"gitiles_ref:refs/heads/master"
]
}
[05:41:13.903] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:infra",
"created_ts": "1610084473331729",
"id": "8858697779251189360",
"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\": \"720a184cbeca12e601b684c99fdcd25edfe81c10\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@720a184cbeca12e601b684c99fdcd25edfe81c10\", \"title\": \"720a184cbeca12e601b684c99fdcd25edfe81c10\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/720a184cbeca12e601b684c99fdcd25edfe81c10\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"ca4e5ef5d05265d2b89661e0b6d6cf74f3d7d5a8\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ca4e5ef5d05265d2b89661e0b6d6cf74f3d7d5a8\", \"title\": \"ca4e5ef5d05265d2b89661e0b6d6cf74f3d7d5a8\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/ca4e5ef5d05265d2b89661e0b6d6cf74f3d7d5a8\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"f7c7d8243b0824c7fa8d7404893ed3983384c523\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f7c7d8243b0824c7fa8d7404893ed3983384c523\", \"title\": \"f7c7d8243b0824c7fa8d7404893ed3983384c523\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/f7c7d8243b0824c7fa8d7404893ed3983384c523\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"a3d32d4ebd045e0042b655e9ec40d13371e348f6\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a3d32d4ebd045e0042b655e9ec40d13371e348f6\", \"title\": \"a3d32d4ebd045e0042b655e9ec40d13371e348f6\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/a3d32d4ebd045e0042b655e9ec40d13371e348f6\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"1e161a42efe5a21abcae9ea23c3ae55a9e150e5b\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@1e161a42efe5a21abcae9ea23c3ae55a9e150e5b\", \"title\": \"1e161a42efe5a21abcae9ea23c3ae55a9e150e5b\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/1e161a42efe5a21abcae9ea23c3ae55a9e150e5b\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"9450eafb53fce13ce280030d62f237d1a835df20\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@9450eafb53fce13ce280030d62f237d1a835df20\", \"title\": \"9450eafb53fce13ce280030d62f237d1a835df20\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/9450eafb53fce13ce280030d62f237d1a835df20\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"9450eafb53fce13ce280030d62f237d1a835df20\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610084473771116",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18292",
"builder:infra-continuous-win10-64",
"buildset:commit/git/9450eafb53fce13ce280030d62f237d1a835df20",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/9450eafb53fce13ce280030d62f237d1a835df20",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024127841234957168",
"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/8858697779251189360/+/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": "1610084473771235",
"url": "https://luci-milo-dev.appspot.com/b/8858697779251189360",
"utcnow_ts": "1610084473893279"
}
}
[05:41:13.903] Task URL: https://luci-milo-dev.appspot.com/b/8858697779251189360
[05:41:13.903] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:2:0) after 1m0s
[05:42:14.003] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:2:0)
[05:42:14.003] Timer tick, asking Buildbucket for the build status
[05:42:14.092] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:42:14.092] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:3:0) after 1m0s
[05:43:14.193] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:3:0)
[05:43:14.193] Timer tick, asking Buildbucket for the build status
[05:43:14.274] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:43:14.274] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:4:0) after 1m0s
[05:44:14.300] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:4:0)
[05:44:14.300] Timer tick, asking Buildbucket for the build status
[05:44:14.383] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:44:14.383] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:5:0) after 1m0s
[05:45:14.492] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:5:0)
[05:45:14.492] Timer tick, asking Buildbucket for the build status
[05:45:14.598] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:45:14.598] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:6:0) after 1m0s
[05:46:14.710] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:6:0)
[05:46:14.710] Timer tick, asking Buildbucket for the build status
[05:46:14.851] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:46:14.851] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:7:0) after 1m0s
[05:47:14.874] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:7:0)
[05:47:14.874] Timer tick, asking Buildbucket for the build status
[05:47:14.970] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:47:14.970] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:8:0) after 1m0s
[05:48:14.992] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:8:0)
[05:48:14.992] Timer tick, asking Buildbucket for the build status
[05:48:15.161] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:48:15.161] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:9:0) after 1m0s
[05:49:15.191] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:9:0)
[05:49:15.191] Timer tick, asking Buildbucket for the build status
[05:49:15.284] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:49:15.284] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:10:0) after 1m0s
[05:50:15.391] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:10:0)
[05:50:15.391] Timer tick, asking Buildbucket for the build status
[05:50:15.546] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:50:15.546] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:11:0) after 1m0s
[05:51:15.602] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:11:0)
[05:51:15.602] Timer tick, asking Buildbucket for the build status
[05:51:15.870] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:51:15.870] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:12:0) after 1m0s
[05:52:15.901] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:12:0)
[05:52:15.901] Timer tick, asking Buildbucket for the build status
[05:52:16.032] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:52:16.032] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:13:0) after 1m0s
[05:53:16.114] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:13:0)
[05:53:16.114] Timer tick, asking Buildbucket for the build status
[05:53:16.245] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:53:16.245] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:14:0) after 1m0s
[05:54:16.278] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:14:0)
[05:54:16.278] Timer tick, asking Buildbucket for the build status
[05:54:16.373] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:54:16.373] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:15:0) after 1m0s
[05:55:16.399] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:15:0)
[05:55:16.399] Timer tick, asking Buildbucket for the build status
[05:55:16.500] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:55:16.500] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:16:0) after 1m0s
[05:56:16.611] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:16:0)
[05:56:16.611] Timer tick, asking Buildbucket for the build status
[05:56:16.762] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:56:16.762] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:17:0) after 1m0s
[05:57:16.808] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:17:0)
[05:57:16.808] Timer tick, asking Buildbucket for the build status
[05:57:16.922] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:57:16.922] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:18:0) after 1m0s
[05:58:17.008] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:18:0)
[05:58:17.008] Timer tick, asking Buildbucket for the build status
[05:58:17.103] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:58:17.103] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:19:0) after 1m0s
[05:59:17.128] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:19:0)
[05:59:17.128] Timer tick, asking Buildbucket for the build status
[05:59:17.228] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:59:17.228] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:20:0) after 1m0s
[06:00:17.258] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:20:0)
[06:00:17.258] Timer tick, asking Buildbucket for the build status
[06:00:17.361] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:00:17.361] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:21:0) after 1m0s
[06:01:17.398] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:21:0)
[06:01:17.398] Timer tick, asking Buildbucket for the build status
[06:01:17.520] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:01:17.520] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:22:0) after 1m0s
[06:02:17.590] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:22:0)
[06:02:17.590] Timer tick, asking Buildbucket for the build status
[06:02:17.984] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:02:17.984] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:23:0) after 1m0s
[06:03:18.093] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:23:0)
[06:03:18.093] Timer tick, asking Buildbucket for the build status
[06:03:18.398] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:03:18.398] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:24:0) after 1m0s
[06:04:18.492] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:24:0)
[06:04:18.492] Timer tick, asking Buildbucket for the build status
[06:04:18.740] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:04:18.740] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:25:0) after 1m0s
[06:05:18.794] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:25:0)
[06:05:18.794] Timer tick, asking Buildbucket for the build status
[06:05:19.077] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:05:19.077] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:26:0) after 1m0s
[06:06:19.178] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:26:0)
[06:06:19.178] Timer tick, asking Buildbucket for the build status
[06:06:20.206] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:06:20.206] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:27:0) after 1m0s
[06:07:20.311] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:27:0)
[06:07:20.311] Timer tick, asking Buildbucket for the build status
[06:07:20.607] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:07:20.607] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:28:0) after 1m0s
[06:08:20.703] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:28:0)
[06:08:20.703] Timer tick, asking Buildbucket for the build status
[06:08:21.045] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:08:21.045] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:29:0) after 1m0s
[06:09:21.105] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:29:0)
[06:09:21.105] Timer tick, asking Buildbucket for the build status
[06:09:21.370] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:09:21.370] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:30:0) after 1m0s
[06:10:21.488] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:30:0)
[06:10:21.488] Timer tick, asking Buildbucket for the build status
[06:10:21.857] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:10:21.857] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:31:0) after 1m0s
[06:11:21.910] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:31:0)
[06:11:21.910] Timer tick, asking Buildbucket for the build status
[06:11:22.363] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:11:22.363] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:32:0) after 1m0s
[06:12:22.391] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:32:0)
[06:12:22.391] Timer tick, asking Buildbucket for the build status
[06:12:23.045] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:12:23.045] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:33:0) after 1m0s
[06:13:23.099] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:33:0)
[06:13:23.099] Timer tick, asking Buildbucket for the build status
[06:13:23.353] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:13:23.353] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:34:0) after 1m0s
[06:14:23.393] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:34:0)
[06:14:23.393] Timer tick, asking Buildbucket for the build status
[06:14:23.598] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:14:23.598] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:35:0) after 1m0s
[06:15:23.694] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:35:0)
[06:15:23.694] Timer tick, asking Buildbucket for the build status
[06:15:24.688] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:15:24.688] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:36:0) after 1m0s
[06:16:24.782] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:36:0)
[06:16:24.782] Timer tick, asking Buildbucket for the build status
[06:16:24.974] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:16:24.974] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:37:0) after 1m0s
[06:17:25.009] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:37:0)
[06:17:25.009] Timer tick, asking Buildbucket for the build status
[06:17:25.300] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:17:25.300] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:38:0) after 1m0s
[06:18:25.376] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:38:0)
[06:18:25.376] Timer tick, asking Buildbucket for the build status
[06:18:25.645] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:18:25.645] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:39:0) after 1m0s
[06:19:25.691] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:39:0)
[06:19:25.691] Timer tick, asking Buildbucket for the build status
[06:19:26.078] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:19:26.078] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:40:0) after 1m0s
[06:20:26.106] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:40:0)
[06:20:26.106] Timer tick, asking Buildbucket for the build status
[06:20:26.435] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:20:26.435] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:41:0) after 1m0s
[06:21:26.505] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:41:0)
[06:21:26.505] Timer tick, asking Buildbucket for the build status
[06:21:26.753] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:21:26.753] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:42:0) after 1m0s
[06:22:26.891] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:42:0)
[06:22:26.891] Timer tick, asking Buildbucket for the build status
[06:22:26.992] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:22:26.992] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:43:0) after 1m0s
[06:23:27.109] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:43:0)
[06:23:27.109] Timer tick, asking Buildbucket for the build status
[06:23:27.409] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:23:27.409] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:44:0) after 1m0s
[06:24:27.489] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:44:0)
[06:24:27.489] Timer tick, asking Buildbucket for the build status
[06:24:27.595] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:24:27.595] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:45:0) after 1m0s
[06:25:27.777] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:45:0)
[06:25:27.777] Timer tick, asking Buildbucket for the build status
[06:25:27.884] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:25:27.884] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:46:0) after 1m0s
[06:26:27.986] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:46:0)
[06:26:27.986] Timer tick, asking Buildbucket for the build status
[06:26:28.341] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:26:28.341] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:47:0) after 1m0s
[06:27:28.417] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:47:0)
[06:27:28.417] Timer tick, asking Buildbucket for the build status
[06:27:28.537] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:27:28.537] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:48:0) after 1m0s
[06:28:28.606] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:48:0)
[06:28:28.606] Timer tick, asking Buildbucket for the build status
[06:28:28.855] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:28:28.855] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:49:0) after 1m0s
[06:29:28.990] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:49:0)
[06:29:28.990] Timer tick, asking Buildbucket for the build status
[06:29:29.103] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:29:29.103] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:50:0) after 1m0s
[06:30:29.293] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:50:0)
[06:30:29.293] Timer tick, asking Buildbucket for the build status
[06:30:29.561] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:30:29.561] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:51:0) after 1m0s
[06:31:29.592] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:51:0)
[06:31:29.592] Timer tick, asking Buildbucket for the build status
[06:31:29.744] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:31:29.744] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:52:0) after 1m0s
[06:32:29.777] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:52:0)
[06:32:29.777] Timer tick, asking Buildbucket for the build status
[06:32:29.894] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:32:29.894] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:53:0) after 1m0s
[06:33:29.975] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:53:0)
[06:33:29.975] Timer tick, asking Buildbucket for the build status
[06:33:30.086] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:33:30.086] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:54:0) after 1m0s
[06:34:30.123] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:54:0)
[06:34:30.123] Timer tick, asking Buildbucket for the build status
[06:34:30.246] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:34:30.246] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:55:0) after 1m0s
[06:35:30.270] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:55:0)
[06:35:30.270] Timer tick, asking Buildbucket for the build status
[06:35:30.418] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:35:30.418] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:56:0) after 1m0s
[06:36:30.447] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:56:0)
[06:36:30.447] Timer tick, asking Buildbucket for the build status
[06:36:30.575] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:36:30.575] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:57:0) after 1m0s
[06:37:30.694] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:57:0)
[06:37:30.694] Timer tick, asking Buildbucket for the build status
[06:37:30.847] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:37:30.847] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:58:0) after 1m0s
[06:38:30.874] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:58:0)
[06:38:30.874] Timer tick, asking Buildbucket for the build status
[06:38:30.994] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:38:30.994] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:59:0) after 1m0s
[06:39:31.107] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:59:0)
[06:39:31.107] Timer tick, asking Buildbucket for the build status
[06:39:31.266] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:39:31.266] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:60:0) after 1m0s
[06:40:31.291] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:60:0)
[06:40:31.291] Timer tick, asking Buildbucket for the build status
[06:40:31.408] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:40:31.408] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:61:0) after 1m0s
[06:41:31.433] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:61:0)
[06:41:31.433] Timer tick, asking Buildbucket for the build status
[06:41:31.702] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:41:31.702] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:62:0) after 1m0s
[06:42:31.788] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:62:0)
[06:42:31.788] Timer tick, asking Buildbucket for the build status
[06:42:31.909] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:42:31.909] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:63:0) after 1m0s
[06:43:31.994] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:63:0)
[06:43:31.994] Timer tick, asking Buildbucket for the build status
[06:43:32.143] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:43:32.143] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:64:0) after 1m0s
[06:44:32.176] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:64:0)
[06:44:32.176] Timer tick, asking Buildbucket for the build status
[06:44:32.304] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:44:32.304] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:65:0) after 1m0s
[06:45:32.329] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:65:0)
[06:45:32.329] Timer tick, asking Buildbucket for the build status
[06:45:32.445] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:45:32.445] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:66:0) after 1m0s
[06:46:32.501] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:66:0)
[06:46:32.501] Timer tick, asking Buildbucket for the build status
[06:46:32.614] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:46:32.614] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:67:0) after 1m0s
[06:47:32.679] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:67:0)
[06:47:32.679] Timer tick, asking Buildbucket for the build status
[06:47:32.801] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:47:32.801] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:68:0) after 1m0s
[06:48:32.896] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:68:0)
[06:48:32.896] Timer tick, asking Buildbucket for the build status
[06:48:33.008] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:48:33.008] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:69:0) after 1m0s
[06:49:33.036] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:69:0)
[06:49:33.036] Timer tick, asking Buildbucket for the build status
[06:49:33.155] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:49:33.155] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:70:0) after 1m0s
[06:50:33.191] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:70:0)
[06:50:33.191] Timer tick, asking Buildbucket for the build status
[06:50:33.311] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:50:33.311] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:71:0) after 1m0s
[06:51:33.405] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:71:0)
[06:51:33.405] Timer tick, asking Buildbucket for the build status
[06:51:33.527] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:51:33.527] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:72:0) after 1m0s
[06:52:33.597] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:72:0)
[06:52:33.597] Timer tick, asking Buildbucket for the build status
[06:52:33.797] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:52:33.797] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:73:0) after 1m0s
[06:53:33.821] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:73:0)
[06:53:33.821] Timer tick, asking Buildbucket for the build status
[06:53:33.935] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:53:33.935] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:74:0) after 1m0s
[06:54:33.980] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:74:0)
[06:54:33.980] Timer tick, asking Buildbucket for the build status
[06:54:34.076] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:54:34.076] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:75:0) after 1m0s
[06:55:34.115] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:75:0)
[06:55:34.115] Timer tick, asking Buildbucket for the build status
[06:55:34.275] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:55:34.275] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:76:0) after 1m0s
[06:56:34.312] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:76:0)
[06:56:34.312] Timer tick, asking Buildbucket for the build status
[06:56:34.459] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:56:34.459] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:77:0) after 1m0s
[06:57:34.506] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:77:0)
[06:57:34.506] Timer tick, asking Buildbucket for the build status
[06:57:34.619] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:57:34.619] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:78:0) after 1m0s
[06:58:34.695] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:78:0)
[06:58:34.695] Timer tick, asking Buildbucket for the build status
[06:58:34.839] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:58:34.839] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:79:0) after 1m0s
[06:59:34.938] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:79:0)
[06:59:34.939] Timer tick, asking Buildbucket for the build status
[06:59:35.050] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:59:35.050] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:80:0) after 1m0s
[07:00:35.112] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:80:0)
[07:00:35.112] Timer tick, asking Buildbucket for the build status
[07:00:35.271] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:00:35.271] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:81:0) after 1m0s
[07:01:35.304] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:81:0)
[07:01:35.304] Timer tick, asking Buildbucket for the build status
[07:01:35.428] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:01:35.428] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:82:0) after 1m0s
[07:02:35.518] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:82:0)
[07:02:35.518] Timer tick, asking Buildbucket for the build status
[07:02:35.654] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:02:35.654] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:83:0) after 1m0s
[07:03:35.710] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:83:0)
[07:03:35.710] Timer tick, asking Buildbucket for the build status
[07:03:35.914] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:03:35.914] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:84:0) after 1m0s
[07:04:36.014] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:84:0)
[07:04:36.014] Timer tick, asking Buildbucket for the build status
[07:04:36.228] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:04:36.228] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:85:0) after 1m0s
[07:05:36.294] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:85:0)
[07:05:36.294] Timer tick, asking Buildbucket for the build status
[07:05:36.417] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:05:36.417] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:86:0) after 1m0s
[07:06:36.496] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:86:0)
[07:06:36.496] Timer tick, asking Buildbucket for the build status
[07:06:36.622] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:06:36.622] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:87:0) after 1m0s
[07:07:36.706] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:87:0)
[07:07:36.706] Timer tick, asking Buildbucket for the build status
[07:07:36.928] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:07:36.928] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:88:0) after 1m0s
[07:08:36.977] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:88:0)
[07:08:36.977] Timer tick, asking Buildbucket for the build status
[07:08:37.098] Build 8858697779251189360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:08:37.098] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:89:0) after 1m0s
[07:09:34.599] Received PubSub notification, asking Buildbucket for the build status
[07:09:34.691] Build 8858697779251189360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:37.121] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:89:0)
[07:09:37.121] Timer tick, asking Buildbucket for the build status
[07:09:37.252] Build 8858697779251189360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:37.252] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:91:0) after 1m0s
[07:10:37.283] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:91:0)
[07:10:37.283] Timer tick, asking Buildbucket for the build status
[07:10:37.395] Build 8858697779251189360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:37.395] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:92:0) after 1m0s
[07:11:37.417] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:92:0)
[07:11:37.417] Timer tick, asking Buildbucket for the build status
[07:11:37.573] Build 8858697779251189360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:37.573] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:93:0) after 1m0s
[07:12:37.604] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:93:0)
[07:12:37.604] Timer tick, asking Buildbucket for the build status
[07:12:37.711] Build 8858697779251189360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:37.711] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:94:0) after 1m0s
[07:13:37.780] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:94:0)
[07:13:37.780] Timer tick, asking Buildbucket for the build status
[07:13:37.898] Build 8858697779251189360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:37.898] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:95:0) after 1m0s
[07:14:37.998] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:95:0)
[07:14:37.998] Timer tick, asking Buildbucket for the build status
[07:14:38.126] Build 8858697779251189360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:38.126] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:96:0) after 1m0s
[07:15:38.200] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:96:0)
[07:15:38.200] Timer tick, asking Buildbucket for the build status
[07:15:38.320] Build 8858697779251189360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:38.320] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9024127841234957168:97:0) after 1m0s
[07:15:51.309] Received PubSub notification, asking Buildbucket for the build status
[07:15:51.403] Build 8858697779251189360: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:15:51.403] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1610090149929587",
"created_by": "project:infra",
"created_ts": "1610084473331729",
"id": "8858697779251189360",
"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\": \"720a184cbeca12e601b684c99fdcd25edfe81c10\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@720a184cbeca12e601b684c99fdcd25edfe81c10\", \"title\": \"720a184cbeca12e601b684c99fdcd25edfe81c10\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/720a184cbeca12e601b684c99fdcd25edfe81c10\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"ca4e5ef5d05265d2b89661e0b6d6cf74f3d7d5a8\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ca4e5ef5d05265d2b89661e0b6d6cf74f3d7d5a8\", \"title\": \"ca4e5ef5d05265d2b89661e0b6d6cf74f3d7d5a8\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/ca4e5ef5d05265d2b89661e0b6d6cf74f3d7d5a8\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"f7c7d8243b0824c7fa8d7404893ed3983384c523\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f7c7d8243b0824c7fa8d7404893ed3983384c523\", \"title\": \"f7c7d8243b0824c7fa8d7404893ed3983384c523\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/f7c7d8243b0824c7fa8d7404893ed3983384c523\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"a3d32d4ebd045e0042b655e9ec40d13371e348f6\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a3d32d4ebd045e0042b655e9ec40d13371e348f6\", \"title\": \"a3d32d4ebd045e0042b655e9ec40d13371e348f6\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/a3d32d4ebd045e0042b655e9ec40d13371e348f6\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"1e161a42efe5a21abcae9ea23c3ae55a9e150e5b\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@1e161a42efe5a21abcae9ea23c3ae55a9e150e5b\", \"title\": \"1e161a42efe5a21abcae9ea23c3ae55a9e150e5b\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/1e161a42efe5a21abcae9ea23c3ae55a9e150e5b\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"9450eafb53fce13ce280030d62f237d1a835df20\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@9450eafb53fce13ce280030d62f237d1a835df20\", \"title\": \"9450eafb53fce13ce280030d62f237d1a835df20\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/9450eafb53fce13ce280030d62f237d1a835df20\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"9450eafb53fce13ce280030d62f237d1a835df20\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"9450eafb53fce13ce280030d62f237d1a835df20\", \"got_revision_cp\": \"refs/heads/master@{#37417}\"}, \"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-ltzh\"], \"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\": [\"5659-fbaaf55\"], \"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": "1610089773040400",
"status": "COMPLETED",
"status_changed_ts": "1610090150236211",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18292",
"builder:infra-continuous-win10-64",
"buildset:commit/git/9450eafb53fce13ce280030d62f237d1a835df20",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/9450eafb53fce13ce280030d62f237d1a835df20",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024127841234957168",
"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/8858697779251189360/+/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:50f954e629e71d10",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1610090150283924",
"url": "https://luci-milo-dev.appspot.com/b/8858697779251189360",
"utcnow_ts": "1610090151394539"
}
}
[07:15:51.403] Invocation finished in 1h34m39.347586291s with status SUCCEEDED