[13:30:14.106] New invocation is queued and will start shortly
[13:30:15.259] Starting the invocation (attempt 1)
[13:30:15.291] Popped gitiles commit info from properties and tags
[13:30:15.291] Popped a tag as redundant: buildset:commit/git/63aa229254fa18b338090f11e6a6da493e4f80c4
[13:30:15.291] Popped a tag as redundant: buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/63aa229254fa18b338090f11e6a6da493e4f80c4
[13:30:15.291] Popped a tag as redundant: gitiles_ref:refs/heads/master
[13:30:15.291] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[13:30:15.291] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[13:30:15.291] Buildbucket request:
{
"requestId": "9018481320255611856",
"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": "63aa229254fa18b338090f11e6a6da493e4f80c4"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@63aa229254fa18b338090f11e6a6da493e4f80c4",
"title": "63aa229254fa18b338090f11e6a6da493e4f80c4",
"url": "https://chromium.googlesource.com/infra/infra/+/63aa229254fa18b338090f11e6a6da493e4f80c4"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "63aa229254fa18b338090f11e6a6da493e4f80c4",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9018481320255611856"
},
{
"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"
}
}
[13:30:15.829] Scheduled build:
{
"id": "8853051258249887664",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 18843,
"createdBy": "project:infra",
"createTime": "2021-03-11T13:30:15.387040Z",
"updateTime": "2021-03-11T13:30:15.765154Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "63aa229254fa18b338090f11e6a6da493e4f80c4",
"ref": "refs/heads/master"
}
},
"canary": true
}
[13:30:15.829] Task URL: https://cr-buildbucket-dev.appspot.com/build/8853051258249887664
[13:30:15.829] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:2:0) after 1m0s
[13:31:15.861] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:2:0)
[13:31:15.861] Timer tick, asking Buildbucket for the build status
[13:31:15.945] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:31:15.945] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:3:0) after 1m0s
[13:32:15.963] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:3:0)
[13:32:15.963] Timer tick, asking Buildbucket for the build status
[13:32:16.051] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:32:16.051] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:4:0) after 1m0s
[13:33:16.153] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:4:0)
[13:33:16.153] Timer tick, asking Buildbucket for the build status
[13:33:16.228] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:33:16.228] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:5:0) after 1m0s
[13:34:16.245] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:5:0)
[13:34:16.245] Timer tick, asking Buildbucket for the build status
[13:34:16.337] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:34:16.337] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:6:0) after 1m0s
[13:35:16.352] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:6:0)
[13:35:16.352] Timer tick, asking Buildbucket for the build status
[13:35:16.430] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:35:16.430] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:7:0) after 1m0s
[13:36:16.446] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:7:0)
[13:36:16.446] Timer tick, asking Buildbucket for the build status
[13:36:16.509] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:36:16.509] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:8:0) after 1m0s
[13:37:16.537] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:8:0)
[13:37:16.537] Timer tick, asking Buildbucket for the build status
[13:37:16.601] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:37:16.601] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:9:0) after 1m0s
[13:38:16.624] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:9:0)
[13:38:16.624] Timer tick, asking Buildbucket for the build status
[13:38:16.724] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:38:16.724] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:10:0) after 1m0s
[13:39:16.876] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:10:0)
[13:39:16.876] Timer tick, asking Buildbucket for the build status
[13:39:16.942] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:39:16.942] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:11:0) after 1m0s
[13:40:16.966] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:11:0)
[13:40:16.966] Timer tick, asking Buildbucket for the build status
[13:40:17.034] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:40:17.034] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:12:0) after 1m0s
[13:41:17.051] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:12:0)
[13:41:17.051] Timer tick, asking Buildbucket for the build status
[13:41:17.153] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:41:17.153] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:13:0) after 1m0s
[13:42:17.252] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:13:0)
[13:42:17.252] Timer tick, asking Buildbucket for the build status
[13:42:17.337] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:42:17.337] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:14:0) after 1m0s
[13:43:17.354] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:14:0)
[13:43:17.354] Timer tick, asking Buildbucket for the build status
[13:43:17.419] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:43:17.419] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:15:0) after 1m0s
[13:44:17.438] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:15:0)
[13:44:17.438] Timer tick, asking Buildbucket for the build status
[13:44:17.546] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:44:17.546] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:16:0) after 1m0s
[13:45:17.660] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:16:0)
[13:45:17.660] Timer tick, asking Buildbucket for the build status
[13:45:17.725] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:45:17.725] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:17:0) after 1m0s
[13:46:17.753] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:17:0)
[13:46:17.753] Timer tick, asking Buildbucket for the build status
[13:46:17.832] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:46:17.832] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:18:0) after 1m0s
[13:47:17.862] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:18:0)
[13:47:17.862] Timer tick, asking Buildbucket for the build status
[13:47:17.956] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:47:17.956] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:19:0) after 1m0s
[13:48:18.067] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:19:0)
[13:48:18.067] Timer tick, asking Buildbucket for the build status
[13:48:18.136] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:48:18.136] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:20:0) after 1m0s
[13:49:18.164] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:20:0)
[13:49:18.164] Timer tick, asking Buildbucket for the build status
[13:49:18.266] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:49:18.266] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:21:0) after 1m0s
[13:50:18.364] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:21:0)
[13:50:18.364] Timer tick, asking Buildbucket for the build status
[13:50:18.430] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:50:18.430] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:22:0) after 1m0s
[13:51:18.448] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:22:0)
[13:51:18.448] Timer tick, asking Buildbucket for the build status
[13:51:18.529] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:51:18.529] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:23:0) after 1m0s
[13:52:18.564] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:23:0)
[13:52:18.564] Timer tick, asking Buildbucket for the build status
[13:52:18.637] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:52:18.637] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:24:0) after 1m0s
[13:53:18.660] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:24:0)
[13:53:18.660] Timer tick, asking Buildbucket for the build status
[13:53:18.733] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:53:18.733] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:25:0) after 1m0s
[13:54:18.763] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:25:0)
[13:54:18.763] Timer tick, asking Buildbucket for the build status
[13:54:18.833] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:54:18.833] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:26:0) after 1m0s
[13:55:18.856] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:26:0)
[13:55:18.856] Timer tick, asking Buildbucket for the build status
[13:55:18.939] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:55:18.939] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:27:0) after 1m0s
[13:56:18.969] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:27:0)
[13:56:18.969] Timer tick, asking Buildbucket for the build status
[13:56:19.048] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:56:19.048] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:28:0) after 1m0s
[13:57:19.154] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:28:0)
[13:57:19.154] Timer tick, asking Buildbucket for the build status
[13:57:19.236] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:57:19.236] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:29:0) after 1m0s
[13:58:19.255] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:29:0)
[13:58:19.255] Timer tick, asking Buildbucket for the build status
[13:58:19.339] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:58:19.339] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:30:0) after 1m0s
[13:59:19.379] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:30:0)
[13:59:19.379] Timer tick, asking Buildbucket for the build status
[13:59:19.603] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:59:19.603] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:31:0) after 1m0s
[14:00:19.629] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:31:0)
[14:00:19.629] Timer tick, asking Buildbucket for the build status
[14:00:19.724] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:00:19.724] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:32:0) after 1m0s
[14:01:19.756] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:32:0)
[14:01:19.756] Timer tick, asking Buildbucket for the build status
[14:01:19.842] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:01:19.842] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:33:0) after 1m0s
[14:02:19.874] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:33:0)
[14:02:19.874] Timer tick, asking Buildbucket for the build status
[14:02:19.948] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:02:19.948] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:34:0) after 1m0s
[14:03:20.063] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:34:0)
[14:03:20.063] Timer tick, asking Buildbucket for the build status
[14:03:20.142] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:03:20.142] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:35:0) after 1m0s
[14:04:20.168] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:35:0)
[14:04:20.168] Timer tick, asking Buildbucket for the build status
[14:04:20.250] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:04:20.250] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:36:0) after 1m0s
[14:05:20.278] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:36:0)
[14:05:20.278] Timer tick, asking Buildbucket for the build status
[14:05:20.357] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:05:20.357] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:37:0) after 1m0s
[14:06:20.458] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:37:0)
[14:06:20.458] Timer tick, asking Buildbucket for the build status
[14:06:20.553] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:06:20.553] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:38:0) after 1m0s
[14:07:20.653] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:38:0)
[14:07:20.653] Timer tick, asking Buildbucket for the build status
[14:07:20.743] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:07:20.743] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:39:0) after 1m0s
[14:08:20.771] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:39:0)
[14:08:20.771] Timer tick, asking Buildbucket for the build status
[14:08:20.849] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:08:20.849] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:40:0) after 1m0s
[14:09:20.967] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:40:0)
[14:09:20.967] Timer tick, asking Buildbucket for the build status
[14:09:21.037] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:09:21.037] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:41:0) after 1m0s
[14:10:21.056] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:41:0)
[14:10:21.056] Timer tick, asking Buildbucket for the build status
[14:10:21.125] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:10:21.125] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:42:0) after 1m0s
[14:11:21.181] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:42:0)
[14:11:21.181] Timer tick, asking Buildbucket for the build status
[14:11:22.332] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:11:22.332] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:43:0) after 1m0s
[14:12:22.354] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:43:0)
[14:12:22.354] Timer tick, asking Buildbucket for the build status
[14:12:22.456] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:12:22.456] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:44:0) after 1m0s
[14:13:22.563] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:44:0)
[14:13:22.563] Timer tick, asking Buildbucket for the build status
[14:13:22.639] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:13:22.639] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:45:0) after 1m0s
[14:14:22.667] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:45:0)
[14:14:22.667] Timer tick, asking Buildbucket for the build status
[14:14:22.758] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:14:22.758] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:46:0) after 1m0s
[14:15:22.855] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:46:0)
[14:15:22.855] Timer tick, asking Buildbucket for the build status
[14:15:22.925] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:15:22.925] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:47:0) after 1m0s
[14:16:22.966] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:47:0)
[14:16:22.966] Timer tick, asking Buildbucket for the build status
[14:16:23.044] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:16:23.045] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:48:0) after 1m0s
[14:17:23.072] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:48:0)
[14:17:23.072] Timer tick, asking Buildbucket for the build status
[14:17:23.168] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:17:23.168] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:49:0) after 1m0s
[14:18:23.265] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:49:0)
[14:18:23.265] Timer tick, asking Buildbucket for the build status
[14:18:23.337] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:18:23.337] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:50:0) after 1m0s
[14:19:23.367] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:50:0)
[14:19:23.367] Timer tick, asking Buildbucket for the build status
[14:19:23.547] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:19:23.547] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:51:0) after 1m0s
[14:20:23.666] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:51:0)
[14:20:23.666] Timer tick, asking Buildbucket for the build status
[14:20:23.836] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:20:23.836] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:52:0) after 1m0s
[14:21:23.865] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:52:0)
[14:21:23.865] Timer tick, asking Buildbucket for the build status
[14:21:23.943] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:21:23.943] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:53:0) after 1m0s
[14:22:23.975] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:53:0)
[14:22:23.975] Timer tick, asking Buildbucket for the build status
[14:22:24.076] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:22:24.076] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:54:0) after 1m0s
[14:23:24.157] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:54:0)
[14:23:24.157] Timer tick, asking Buildbucket for the build status
[14:23:24.231] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:23:24.231] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:55:0) after 1m0s
[14:24:24.268] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:55:0)
[14:24:24.268] Timer tick, asking Buildbucket for the build status
[14:24:24.338] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:24:24.338] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:56:0) after 1m0s
[14:25:24.367] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:56:0)
[14:25:24.367] Timer tick, asking Buildbucket for the build status
[14:25:24.434] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:25:24.434] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:57:0) after 1m0s
[14:26:24.461] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:57:0)
[14:26:24.461] Timer tick, asking Buildbucket for the build status
[14:26:24.544] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:26:24.544] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:58:0) after 1m0s
[14:27:24.563] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:58:0)
[14:27:24.563] Timer tick, asking Buildbucket for the build status
[14:27:24.642] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:27:24.642] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:59:0) after 1m0s
[14:28:24.662] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:59:0)
[14:28:24.662] Timer tick, asking Buildbucket for the build status
[14:28:24.738] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:28:24.738] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:60:0) after 1m0s
[14:29:24.762] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:60:0)
[14:29:24.762] Timer tick, asking Buildbucket for the build status
[14:29:24.854] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:29:24.854] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:61:0) after 1m0s
[14:30:24.883] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:61:0)
[14:30:24.883] Timer tick, asking Buildbucket for the build status
[14:30:25.032] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:30:25.032] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:62:0) after 1m0s
[14:31:25.056] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:62:0)
[14:31:25.056] Timer tick, asking Buildbucket for the build status
[14:31:25.127] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:31:25.127] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:63:0) after 1m0s
[14:32:25.159] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:63:0)
[14:32:25.159] Timer tick, asking Buildbucket for the build status
[14:32:25.254] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:32:25.254] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:64:0) after 1m0s
[14:33:25.354] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:64:0)
[14:33:25.354] Timer tick, asking Buildbucket for the build status
[14:33:25.439] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:33:25.439] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:65:0) after 1m0s
[14:34:25.462] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:65:0)
[14:34:25.462] Timer tick, asking Buildbucket for the build status
[14:34:25.540] Build 8853051258249887664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:34:25.540] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:66:0) after 1m0s
[14:35:23.165] Received PubSub notification, asking Buildbucket for the build status
[14:35:23.330] Build 8853051258249887664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:25.576] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:66:0)
[14:35:25.576] Timer tick, asking Buildbucket for the build status
[14:35:25.643] Build 8853051258249887664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:25.643] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:68:0) after 1m0s
[14:36:25.661] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:68:0)
[14:36:25.661] Timer tick, asking Buildbucket for the build status
[14:36:25.734] Build 8853051258249887664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:25.734] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:69:0) after 1m0s
[14:37:25.756] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:69:0)
[14:37:25.756] Timer tick, asking Buildbucket for the build status
[14:37:25.825] Build 8853051258249887664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:25.825] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:70:0) after 1m0s
[14:38:25.862] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:70:0)
[14:38:25.862] Timer tick, asking Buildbucket for the build status
[14:38:25.932] Build 8853051258249887664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:25.932] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:71:0) after 1m0s
[14:39:25.955] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:71:0)
[14:39:25.955] Timer tick, asking Buildbucket for the build status
[14:39:26.022] Build 8853051258249887664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:26.022] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:72:0) after 1m0s
[14:40:26.065] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:72:0)
[14:40:26.065] Timer tick, asking Buildbucket for the build status
[14:40:26.139] Build 8853051258249887664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:26.139] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:73:0) after 1m0s
[14:41:26.162] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:73:0)
[14:41:26.162] Timer tick, asking Buildbucket for the build status
[14:41:26.226] Build 8853051258249887664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:26.226] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018481320255611856:74:0) after 1m0s
[14:41:41.769] Received PubSub notification, asking Buildbucket for the build status
[14:41:41.844] Build 8853051258249887664: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:41:41.844] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1615473701321984",
"created_by": "project:infra",
"created_ts": "1615469415387040",
"id": "8853051258249887664",
"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\": \"63aa229254fa18b338090f11e6a6da493e4f80c4\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@63aa229254fa18b338090f11e6a6da493e4f80c4\", \"title\": \"63aa229254fa18b338090f11e6a6da493e4f80c4\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/63aa229254fa18b338090f11e6a6da493e4f80c4\"}]}}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"63aa229254fa18b338090f11e6a6da493e4f80c4\", \"got_revision_cp\": \"refs/heads/master@{#39253}\"}, \"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-6mti\"], \"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": "1615473321330770",
"status": "COMPLETED",
"status_changed_ts": "1615473321964690",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18843",
"builder:infra-continuous-win10-64",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/63aa229254fa18b338090f11e6a6da493e4f80c4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018481320255611856",
"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/8853051258249887664/+/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:523a4c967c0f0810",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1615473701322562",
"url": "https://luci-milo-dev.appspot.com/b/8853051258249887664",
"utcnow_ts": "1615473701838024"
}
}
[14:41:41.845] Invocation finished in 1h11m27.754994237s with status SUCCEEDED