[19:47:58.113] New invocation is queued and will start shortly
[19:47:59.251] Starting the invocation (attempt 1)
[19:47:59.312] Popped gitiles commit info from properties and tags
[19:47:59.312] Popped a tag as redundant: buildset:commit/git/adc1f7c941da7335ee1340cfcaab0d15dfbff39b
[19:47:59.312] Popped a tag as redundant: buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/adc1f7c941da7335ee1340cfcaab0d15dfbff39b
[19:47:59.312] Popped a tag as redundant: gitiles_ref:refs/heads/master
[19:47:59.312] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[19:47:59.314] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[19:47:59.314] Buildbucket request:
{
"requestId": "9018457555331127504",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "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": "137a05bc5c53f72c42ab67fa5e586c5d0e9f2878"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@137a05bc5c53f72c42ab67fa5e586c5d0e9f2878",
"title": "137a05bc5c53f72c42ab67fa5e586c5d0e9f2878",
"url": "https://chromium.googlesource.com/infra/infra/+/137a05bc5c53f72c42ab67fa5e586c5d0e9f2878"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "ab63d69668e9c3f42068c4da27c74d3e09dc18a7"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ab63d69668e9c3f42068c4da27c74d3e09dc18a7",
"title": "ab63d69668e9c3f42068c4da27c74d3e09dc18a7",
"url": "https://chromium.googlesource.com/infra/infra/+/ab63d69668e9c3f42068c4da27c74d3e09dc18a7"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "c559626ff7803541c37a8e4d15e91cdb3a8d51d8"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c559626ff7803541c37a8e4d15e91cdb3a8d51d8",
"title": "c559626ff7803541c37a8e4d15e91cdb3a8d51d8",
"url": "https://chromium.googlesource.com/infra/infra/+/c559626ff7803541c37a8e4d15e91cdb3a8d51d8"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "0300afe1eacf665138407ee2f701067c8ce5dca7"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0300afe1eacf665138407ee2f701067c8ce5dca7",
"title": "0300afe1eacf665138407ee2f701067c8ce5dca7",
"url": "https://chromium.googlesource.com/infra/infra/+/0300afe1eacf665138407ee2f701067c8ce5dca7"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "adc1f7c941da7335ee1340cfcaab0d15dfbff39b"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@adc1f7c941da7335ee1340cfcaab0d15dfbff39b",
"title": "adc1f7c941da7335ee1340cfcaab0d15dfbff39b",
"url": "https://chromium.googlesource.com/infra/infra/+/adc1f7c941da7335ee1340cfcaab0d15dfbff39b"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "adc1f7c941da7335ee1340cfcaab0d15dfbff39b",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9018457555331127504"
},
{
"key": "scheduler_job_id",
"value": "infra/infra-continuous-win10-64"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[19:47:59.908] Scheduled build:
{
"id": "8853027493318920080",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 18847,
"createdBy": "project:infra",
"createTime": "2021-03-11T19:47:59.391477Z",
"updateTime": "2021-03-11T19:47:59.833778Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "adc1f7c941da7335ee1340cfcaab0d15dfbff39b",
"ref": "refs/heads/master"
}
}
}
[19:47:59.908] Task URL: https://cr-buildbucket-dev.appspot.com/build/8853027493318920080
[19:47:59.908] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:2:0) after 1m0s
[19:48:59.971] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:2:0)
[19:48:59.971] Timer tick, asking Buildbucket for the build status
[19:49:00.082] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:49:00.082] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:3:0) after 1m0s
[19:50:00.102] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:3:0)
[19:50:00.102] Timer tick, asking Buildbucket for the build status
[19:50:00.237] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:50:00.237] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:4:0) after 1m0s
[19:51:00.320] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:4:0)
[19:51:00.320] Timer tick, asking Buildbucket for the build status
[19:51:00.410] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:51:00.410] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:5:0) after 1m0s
[19:52:00.430] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:5:0)
[19:52:00.430] Timer tick, asking Buildbucket for the build status
[19:52:00.542] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:52:00.542] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:6:0) after 1m0s
[19:53:00.557] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:6:0)
[19:53:00.557] Timer tick, asking Buildbucket for the build status
[19:53:00.641] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:53:00.641] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:7:0) after 1m0s
[19:54:00.660] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:7:0)
[19:54:00.660] Timer tick, asking Buildbucket for the build status
[19:54:00.905] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:54:00.905] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:8:0) after 1m0s
[19:55:00.965] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:8:0)
[19:55:00.965] Timer tick, asking Buildbucket for the build status
[19:55:01.078] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:55:01.078] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:9:0) after 1m0s
[19:56:01.096] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:9:0)
[19:56:01.096] Timer tick, asking Buildbucket for the build status
[19:56:01.180] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:56:01.180] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:10:0) after 1m0s
[19:57:01.199] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:10:0)
[19:57:01.199] Timer tick, asking Buildbucket for the build status
[19:57:01.331] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:57:01.331] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:11:0) after 1m0s
[19:58:01.368] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:11:0)
[19:58:01.368] Timer tick, asking Buildbucket for the build status
[19:58:01.464] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:58:01.464] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:12:0) after 1m0s
[19:59:01.496] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:12:0)
[19:59:01.496] Timer tick, asking Buildbucket for the build status
[19:59:01.661] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:59:01.661] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:13:0) after 1m0s
[20:00:01.764] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:13:0)
[20:00:01.764] Timer tick, asking Buildbucket for the build status
[20:00:01.857] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:00:01.857] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:14:0) after 1m0s
[20:01:01.964] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:14:0)
[20:01:01.964] Timer tick, asking Buildbucket for the build status
[20:01:02.054] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:01:02.054] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:15:0) after 1m0s
[20:02:02.078] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:15:0)
[20:02:02.078] Timer tick, asking Buildbucket for the build status
[20:02:02.167] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:02:02.167] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:16:0) after 1m0s
[20:03:02.189] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:16:0)
[20:03:02.189] Timer tick, asking Buildbucket for the build status
[20:03:02.325] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:03:02.325] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:17:0) after 1m0s
[20:04:02.349] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:17:0)
[20:04:02.349] Timer tick, asking Buildbucket for the build status
[20:04:02.548] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:04:02.548] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:18:0) after 1m0s
[20:05:02.699] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:18:0)
[20:05:02.699] Timer tick, asking Buildbucket for the build status
[20:05:02.838] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:05:02.838] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:19:0) after 1m0s
[20:06:02.862] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:19:0)
[20:06:02.862] Timer tick, asking Buildbucket for the build status
[20:06:02.951] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:06:02.951] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:20:0) after 1m0s
[20:07:03.060] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:20:0)
[20:07:03.060] Timer tick, asking Buildbucket for the build status
[20:07:03.157] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:07:03.157] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:21:0) after 1m0s
[20:08:03.251] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:21:0)
[20:08:03.251] Timer tick, asking Buildbucket for the build status
[20:08:03.334] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:08:03.334] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:22:0) after 1m0s
[20:09:03.358] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:22:0)
[20:09:03.358] Timer tick, asking Buildbucket for the build status
[20:09:03.438] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:09:03.438] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:23:0) after 1m0s
[20:10:03.462] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:23:0)
[20:10:03.462] Timer tick, asking Buildbucket for the build status
[20:10:03.568] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:10:03.568] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:24:0) after 1m0s
[20:11:03.660] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:24:0)
[20:11:03.660] Timer tick, asking Buildbucket for the build status
[20:11:03.728] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:11:03.728] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:25:0) after 1m0s
[20:12:03.833] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:25:0)
[20:12:03.833] Timer tick, asking Buildbucket for the build status
[20:12:03.944] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:12:03.944] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:26:0) after 1m0s
[20:13:03.972] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:26:0)
[20:13:03.972] Timer tick, asking Buildbucket for the build status
[20:13:04.052] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:13:04.052] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:27:0) after 1m0s
[20:14:04.154] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:27:0)
[20:14:04.154] Timer tick, asking Buildbucket for the build status
[20:14:04.250] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:14:04.250] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:28:0) after 1m0s
[20:15:04.270] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:28:0)
[20:15:04.270] Timer tick, asking Buildbucket for the build status
[20:15:04.345] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:15:04.345] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:29:0) after 1m0s
[20:16:04.363] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:29:0)
[20:16:04.363] Timer tick, asking Buildbucket for the build status
[20:16:04.450] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:16:04.450] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:30:0) after 1m0s
[20:17:04.577] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:30:0)
[20:17:04.577] Timer tick, asking Buildbucket for the build status
[20:17:04.742] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:17:04.742] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:31:0) after 1m0s
[20:18:04.759] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:31:0)
[20:18:04.759] Timer tick, asking Buildbucket for the build status
[20:18:04.833] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:18:04.833] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:32:0) after 1m0s
[20:19:04.883] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:32:0)
[20:19:04.883] Timer tick, asking Buildbucket for the build status
[20:19:04.958] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:19:04.958] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:33:0) after 1m0s
[20:20:05.058] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:33:0)
[20:20:05.058] Timer tick, asking Buildbucket for the build status
[20:20:05.141] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:20:05.141] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:34:0) after 1m0s
[20:21:05.161] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:34:0)
[20:21:05.161] Timer tick, asking Buildbucket for the build status
[20:21:05.252] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:21:05.252] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:35:0) after 1m0s
[20:22:05.358] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:35:0)
[20:22:05.358] Timer tick, asking Buildbucket for the build status
[20:22:05.458] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:22:05.458] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:36:0) after 1m0s
[20:23:05.559] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:36:0)
[20:23:05.559] Timer tick, asking Buildbucket for the build status
[20:23:05.658] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:23:05.658] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:37:0) after 1m0s
[20:24:05.758] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:37:0)
[20:24:05.758] Timer tick, asking Buildbucket for the build status
[20:24:05.833] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:24:05.833] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:38:0) after 1m0s
[20:25:05.853] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:38:0)
[20:25:05.853] Timer tick, asking Buildbucket for the build status
[20:25:05.940] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:25:05.940] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:39:0) after 1m0s
[20:26:05.968] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:39:0)
[20:26:05.968] Timer tick, asking Buildbucket for the build status
[20:26:06.051] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:26:06.051] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:40:0) after 1m0s
[20:27:06.159] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:40:0)
[20:27:06.159] Timer tick, asking Buildbucket for the build status
[20:27:06.238] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:27:06.238] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:41:0) after 1m0s
[20:28:06.269] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:41:0)
[20:28:06.269] Timer tick, asking Buildbucket for the build status
[20:28:06.428] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:28:06.428] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:42:0) after 1m0s
[20:29:06.458] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:42:0)
[20:29:06.458] Timer tick, asking Buildbucket for the build status
[20:29:06.536] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:29:06.536] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:43:0) after 1m0s
[20:30:06.575] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:43:0)
[20:30:06.575] Timer tick, asking Buildbucket for the build status
[20:30:06.652] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:30:06.652] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:44:0) after 1m0s
[20:31:06.685] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:44:0)
[20:31:06.685] Timer tick, asking Buildbucket for the build status
[20:31:06.760] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:31:06.760] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:45:0) after 1m0s
[20:32:06.868] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:45:0)
[20:32:06.868] Timer tick, asking Buildbucket for the build status
[20:32:06.990] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:32:06.990] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:46:0) after 1m0s
[20:33:07.076] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:46:0)
[20:33:07.076] Timer tick, asking Buildbucket for the build status
[20:33:07.247] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:33:07.247] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:47:0) after 1m0s
[20:34:07.270] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:47:0)
[20:34:07.270] Timer tick, asking Buildbucket for the build status
[20:34:07.351] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:34:07.351] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:48:0) after 1m0s
[20:35:07.384] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:48:0)
[20:35:07.384] Timer tick, asking Buildbucket for the build status
[20:35:07.464] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:35:07.464] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:49:0) after 1m0s
[20:36:07.489] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:49:0)
[20:36:07.489] Timer tick, asking Buildbucket for the build status
[20:36:07.586] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:36:07.586] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:50:0) after 1m0s
[20:37:07.657] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:50:0)
[20:37:07.657] Timer tick, asking Buildbucket for the build status
[20:37:07.737] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:37:07.737] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:51:0) after 1m0s
[20:38:07.765] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:51:0)
[20:38:07.765] Timer tick, asking Buildbucket for the build status
[20:38:07.859] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:38:07.859] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:52:0) after 1m0s
[20:39:07.971] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:52:0)
[20:39:07.971] Timer tick, asking Buildbucket for the build status
[20:39:08.071] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:39:08.071] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:53:0) after 1m0s
[20:40:08.170] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:53:0)
[20:40:08.170] Timer tick, asking Buildbucket for the build status
[20:40:08.273] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:40:08.273] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:54:0) after 1m0s
[20:41:08.301] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:54:0)
[20:41:08.301] Timer tick, asking Buildbucket for the build status
[20:41:08.411] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:41:08.411] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:55:0) after 1m0s
[20:42:08.492] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:55:0)
[20:42:08.492] Timer tick, asking Buildbucket for the build status
[20:42:08.591] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:42:08.591] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:56:0) after 1m0s
[20:43:08.662] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:56:0)
[20:43:08.662] Timer tick, asking Buildbucket for the build status
[20:43:08.739] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:43:08.739] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:57:0) after 1m0s
[20:44:08.763] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:57:0)
[20:44:08.763] Timer tick, asking Buildbucket for the build status
[20:44:08.856] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:44:08.856] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:58:0) after 1m0s
[20:45:08.957] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:58:0)
[20:45:08.957] Timer tick, asking Buildbucket for the build status
[20:45:09.048] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:45:09.048] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:59:0) after 1m0s
[20:46:09.071] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:59:0)
[20:46:09.071] Timer tick, asking Buildbucket for the build status
[20:46:09.138] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:46:09.138] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:60:0) after 1m0s
[20:47:09.176] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:60:0)
[20:47:09.176] Timer tick, asking Buildbucket for the build status
[20:47:09.363] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:47:09.363] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:61:0) after 1m0s
[20:48:09.385] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:61:0)
[20:48:09.385] Timer tick, asking Buildbucket for the build status
[20:48:09.477] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:48:09.477] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:62:0) after 1m0s
[20:49:09.587] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:62:0)
[20:49:09.587] Timer tick, asking Buildbucket for the build status
[20:49:09.687] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:49:09.687] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:63:0) after 1m0s
[20:50:09.706] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:63:0)
[20:50:09.707] Timer tick, asking Buildbucket for the build status
[20:50:09.786] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:50:09.786] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:64:0) after 1m0s
[20:51:09.891] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:64:0)
[20:51:09.891] Timer tick, asking Buildbucket for the build status
[20:51:10.037] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:51:10.037] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:65:0) after 1m0s
[20:52:10.077] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:65:0)
[20:52:10.077] Timer tick, asking Buildbucket for the build status
[20:52:10.234] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:52:10.234] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:66:0) after 1m0s
[20:53:10.277] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:66:0)
[20:53:10.277] Timer tick, asking Buildbucket for the build status
[20:53:10.369] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:53:10.369] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:67:0) after 1m0s
[20:54:10.476] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:67:0)
[20:54:10.476] Timer tick, asking Buildbucket for the build status
[20:54:10.561] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:54:10.561] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:68:0) after 1m0s
[20:55:10.675] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:68:0)
[20:55:10.675] Timer tick, asking Buildbucket for the build status
[20:55:10.852] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:55:10.852] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:69:0) after 1m0s
[20:56:10.972] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:69:0)
[20:56:10.972] Timer tick, asking Buildbucket for the build status
[20:56:11.134] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:56:11.134] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:70:0) after 1m0s
[20:57:11.162] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:70:0)
[20:57:11.162] Timer tick, asking Buildbucket for the build status
[20:57:11.248] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:57:11.248] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:71:0) after 1m0s
[20:58:11.316] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:71:0)
[20:58:11.316] Timer tick, asking Buildbucket for the build status
[20:58:11.409] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:58:11.409] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:72:0) after 1m0s
[20:59:11.480] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:72:0)
[20:59:11.480] Timer tick, asking Buildbucket for the build status
[20:59:11.565] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:59:11.565] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:73:0) after 1m0s
[21:00:11.595] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:73:0)
[21:00:11.595] Timer tick, asking Buildbucket for the build status
[21:00:11.677] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:00:11.677] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:74:0) after 1m0s
[21:01:11.769] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:74:0)
[21:01:11.769] Timer tick, asking Buildbucket for the build status
[21:01:11.845] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:01:11.845] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:75:0) after 1m0s
[21:02:11.877] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:75:0)
[21:02:11.877] Timer tick, asking Buildbucket for the build status
[21:02:11.979] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:02:11.979] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:76:0) after 1m0s
[21:03:12.079] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:76:0)
[21:03:12.079] Timer tick, asking Buildbucket for the build status
[21:03:12.160] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:03:12.160] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:77:0) after 1m0s
[21:04:12.186] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:77:0)
[21:04:12.186] Timer tick, asking Buildbucket for the build status
[21:04:12.280] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:04:12.280] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:78:0) after 1m0s
[21:05:12.303] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:78:0)
[21:05:12.303] Timer tick, asking Buildbucket for the build status
[21:05:12.428] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:05:12.428] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:79:0) after 1m0s
[21:06:12.458] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:79:0)
[21:06:12.458] Timer tick, asking Buildbucket for the build status
[21:06:12.543] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:06:12.543] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:80:0) after 1m0s
[21:07:12.571] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:80:0)
[21:07:12.571] Timer tick, asking Buildbucket for the build status
[21:07:12.664] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:07:12.664] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:81:0) after 1m0s
[21:08:12.787] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:81:0)
[21:08:12.787] Timer tick, asking Buildbucket for the build status
[21:08:12.871] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:08:12.871] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:82:0) after 1m0s
[21:09:12.980] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:82:0)
[21:09:12.980] Timer tick, asking Buildbucket for the build status
[21:09:13.123] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:09:13.123] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:83:0) after 1m0s
[21:10:13.196] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:83:0)
[21:10:13.196] Timer tick, asking Buildbucket for the build status
[21:10:13.276] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:10:13.276] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:84:0) after 1m0s
[21:11:13.303] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:84:0)
[21:11:13.303] Timer tick, asking Buildbucket for the build status
[21:11:13.371] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:11:13.371] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:85:0) after 1m0s
[21:12:13.398] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:85:0)
[21:12:13.398] Timer tick, asking Buildbucket for the build status
[21:12:13.478] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:12:13.478] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:86:0) after 1m0s
[21:13:13.588] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:86:0)
[21:13:13.588] Timer tick, asking Buildbucket for the build status
[21:13:13.673] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:13:13.673] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:87:0) after 1m0s
[21:14:13.759] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:87:0)
[21:14:13.759] Timer tick, asking Buildbucket for the build status
[21:14:13.839] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:14:13.839] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:88:0) after 1m0s
[21:15:13.893] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:88:0)
[21:15:13.893] Timer tick, asking Buildbucket for the build status
[21:15:13.963] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:15:13.963] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:89:0) after 1m0s
[21:16:13.985] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:89:0)
[21:16:13.985] Timer tick, asking Buildbucket for the build status
[21:16:14.132] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:16:14.132] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:90:0) after 1m0s
[21:17:14.178] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:90:0)
[21:17:14.178] Timer tick, asking Buildbucket for the build status
[21:17:14.259] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:17:14.259] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:91:0) after 1m0s
[21:18:14.362] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:91:0)
[21:18:14.362] Timer tick, asking Buildbucket for the build status
[21:18:14.440] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:18:14.440] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:92:0) after 1m0s
[21:19:14.520] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:92:0)
[21:19:14.520] Timer tick, asking Buildbucket for the build status
[21:19:14.621] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:19:14.621] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:93:0) after 1m0s
[21:20:14.747] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:93:0)
[21:20:14.747] Timer tick, asking Buildbucket for the build status
[21:20:14.846] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:20:14.846] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:94:0) after 1m0s
[21:21:14.888] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:94:0)
[21:21:14.889] Timer tick, asking Buildbucket for the build status
[21:21:15.046] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:21:15.046] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:95:0) after 1m0s
[21:22:15.082] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:95:0)
[21:22:15.082] Timer tick, asking Buildbucket for the build status
[21:22:15.149] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:22:15.149] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:96:0) after 1m0s
[21:23:15.235] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:96:0)
[21:23:15.235] Timer tick, asking Buildbucket for the build status
[21:23:15.323] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:23:15.323] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:97:0) after 1m0s
[21:24:15.384] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:97:0)
[21:24:15.384] Timer tick, asking Buildbucket for the build status
[21:24:15.457] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:24:15.457] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:98:0) after 1m0s
[21:25:15.560] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:98:0)
[21:25:15.560] Timer tick, asking Buildbucket for the build status
[21:25:15.640] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:25:15.640] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:99:0) after 1m0s
[21:26:15.667] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:99:0)
[21:26:15.667] Timer tick, asking Buildbucket for the build status
[21:26:15.756] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:26:15.756] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:100:0) after 1m0s
[21:27:15.779] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:100:0)
[21:27:15.779] Timer tick, asking Buildbucket for the build status
[21:27:15.933] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:27:15.934] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:101:0) after 1m0s
[21:28:15.961] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:101:0)
[21:28:15.961] Timer tick, asking Buildbucket for the build status
[21:28:16.043] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:28:16.044] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:102:0) after 1m0s
[21:29:16.082] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:102:0)
[21:29:16.082] Timer tick, asking Buildbucket for the build status
[21:29:16.166] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:29:16.166] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:103:0) after 1m0s
[21:30:16.189] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:103:0)
[21:30:16.189] Timer tick, asking Buildbucket for the build status
[21:30:16.265] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:30:16.265] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:104:0) after 1m0s
[21:31:16.332] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:104:0)
[21:31:16.332] Timer tick, asking Buildbucket for the build status
[21:31:16.452] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:31:16.452] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:105:0) after 1m0s
[21:32:16.569] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:105:0)
[21:32:16.569] Timer tick, asking Buildbucket for the build status
[21:32:16.655] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:32:16.655] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:106:0) after 1m0s
[21:33:16.760] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:106:0)
[21:33:16.760] Timer tick, asking Buildbucket for the build status
[21:33:16.843] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:33:16.843] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:107:0) after 1m0s
[21:34:17.009] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:107:0)
[21:34:17.009] Timer tick, asking Buildbucket for the build status
[21:34:17.161] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:34:17.161] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:108:0) after 1m0s
[21:35:17.268] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:108:0)
[21:35:17.268] Timer tick, asking Buildbucket for the build status
[21:35:17.370] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:35:17.370] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:109:0) after 1m0s
[21:36:17.478] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:109:0)
[21:36:17.479] Timer tick, asking Buildbucket for the build status
[21:36:17.920] Build 8853027493318920080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:36:17.920] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:110:0) after 1m0s
[21:36:38.756] Received PubSub notification, asking Buildbucket for the build status
[21:36:39.060] Build 8853027493318920080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:17.982] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:110:0)
[21:37:17.982] Timer tick, asking Buildbucket for the build status
[21:37:18.191] Build 8853027493318920080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:18.191] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:112:0) after 1m0s
[21:38:18.489] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:112:0)
[21:38:18.489] Timer tick, asking Buildbucket for the build status
[21:38:18.765] Build 8853027493318920080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:18.765] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:113:0) after 1m0s
[21:39:18.860] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:113:0)
[21:39:18.860] Timer tick, asking Buildbucket for the build status
[21:39:18.943] Build 8853027493318920080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:18.943] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:114:0) after 1m0s
[21:40:19.011] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:114:0)
[21:40:19.011] Timer tick, asking Buildbucket for the build status
[21:40:19.147] Build 8853027493318920080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:19.147] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:115:0) after 1m0s
[21:41:19.272] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:115:0)
[21:41:19.272] Timer tick, asking Buildbucket for the build status
[21:41:19.604] Build 8853027493318920080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:19.604] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:116:0) after 1m0s
[21:42:19.656] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:116:0)
[21:42:19.656] Timer tick, asking Buildbucket for the build status
[21:42:19.886] Build 8853027493318920080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:19.886] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:117:0) after 1m0s
[21:43:19.911] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:117:0)
[21:43:19.911] Timer tick, asking Buildbucket for the build status
[21:43:20.162] Build 8853027493318920080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:20.162] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:118:0) after 1m0s
[21:44:20.259] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:118:0)
[21:44:20.259] Timer tick, asking Buildbucket for the build status
[21:44:20.529] Build 8853027493318920080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:20.529] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:119:0) after 1m0s
[21:45:20.552] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:119:0)
[21:45:20.552] Timer tick, asking Buildbucket for the build status
[21:45:20.796] Build 8853027493318920080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:20.796] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:120:0) after 1m0s
[21:46:20.858] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:120:0)
[21:46:20.858] Timer tick, asking Buildbucket for the build status
[21:46:21.123] Build 8853027493318920080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:21.123] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018457555331127504:121:0) after 1m0s
[21:46:27.271] Received PubSub notification, asking Buildbucket for the build status
[21:46:27.345] Build 8853027493318920080: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:46:27.345] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1615499186784395",
"created_by": "project:infra",
"created_ts": "1615492079391477",
"id": "8853027493318920080",
"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\": \"137a05bc5c53f72c42ab67fa5e586c5d0e9f2878\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@137a05bc5c53f72c42ab67fa5e586c5d0e9f2878\", \"title\": \"137a05bc5c53f72c42ab67fa5e586c5d0e9f2878\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/137a05bc5c53f72c42ab67fa5e586c5d0e9f2878\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"ab63d69668e9c3f42068c4da27c74d3e09dc18a7\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ab63d69668e9c3f42068c4da27c74d3e09dc18a7\", \"title\": \"ab63d69668e9c3f42068c4da27c74d3e09dc18a7\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/ab63d69668e9c3f42068c4da27c74d3e09dc18a7\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"c559626ff7803541c37a8e4d15e91cdb3a8d51d8\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c559626ff7803541c37a8e4d15e91cdb3a8d51d8\", \"title\": \"c559626ff7803541c37a8e4d15e91cdb3a8d51d8\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/c559626ff7803541c37a8e4d15e91cdb3a8d51d8\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0300afe1eacf665138407ee2f701067c8ce5dca7\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0300afe1eacf665138407ee2f701067c8ce5dca7\", \"title\": \"0300afe1eacf665138407ee2f701067c8ce5dca7\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0300afe1eacf665138407ee2f701067c8ce5dca7\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"adc1f7c941da7335ee1340cfcaab0d15dfbff39b\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@adc1f7c941da7335ee1340cfcaab0d15dfbff39b\", \"title\": \"adc1f7c941da7335ee1340cfcaab0d15dfbff39b\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/adc1f7c941da7335ee1340cfcaab0d15dfbff39b\"}]}}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"adc1f7c941da7335ee1340cfcaab0d15dfbff39b\", \"got_revision_cp\": \"refs/heads/master@{#39264}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_044445a3bc7a4bfe795493d7956aa02cd526172de1fa7300f69fe152e82ea631_v2\", \"builder_5de1032f0e9ef497433aad28840ee258c037976023b410f653133cee0ad04e2c_v2\", \"builder_82ed111fed7ea8169e482f00b5f3e5338a65daa41208585de67cb0a736b1d538_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_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-6rd2\"], \"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\": [\"5755-f07b01c\"], \"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": "1615498596709072",
"status": "COMPLETED",
"status_changed_ts": "1615498597276030",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18847",
"builder:infra-continuous-win10-64",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/adc1f7c941da7335ee1340cfcaab0d15dfbff39b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018457555331127504",
"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/8853027493318920080/+/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:523ba66a746e1710",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1615499186784788",
"url": "https://luci-milo-dev.appspot.com/b/8853027493318920080",
"utcnow_ts": "1615499187337465"
}
}
[21:46:27.345] Invocation finished in 1h58m29.256758249s with status SUCCEEDED