[23:27:54.105] New invocation is queued and will start shortly
[23:27:55.256] Starting the invocation (attempt 1)
[23:27:55.289] Popped gitiles commit info from properties and tags
[23:27:55.289] Popped a tag as redundant: buildset:commit/git/a4fee7ebbf332aa080f000318842fe99d671c65c
[23:27:55.289] Popped a tag as redundant: buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/a4fee7ebbf332aa080f000318842fe99d671c65c
[23:27:55.289] Popped a tag as redundant: gitiles_ref:refs/heads/master
[23:27:55.289] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[23:27:55.289] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[23:27:55.290] Buildbucket request:
{
"requestId": "9018443718321434304",
"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": "8fcb20ac11f9d9adbc9c5f5d58ec5af1a6286554"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@8fcb20ac11f9d9adbc9c5f5d58ec5af1a6286554",
"title": "8fcb20ac11f9d9adbc9c5f5d58ec5af1a6286554",
"url": "https://chromium.googlesource.com/infra/infra/+/8fcb20ac11f9d9adbc9c5f5d58ec5af1a6286554"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "3c99b8407491bc7f9e3a6dad68b20fc573f33f81"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@3c99b8407491bc7f9e3a6dad68b20fc573f33f81",
"title": "3c99b8407491bc7f9e3a6dad68b20fc573f33f81",
"url": "https://chromium.googlesource.com/infra/infra/+/3c99b8407491bc7f9e3a6dad68b20fc573f33f81"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "b669dbd708ea86401e6ee4042432a6c23d67db01"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b669dbd708ea86401e6ee4042432a6c23d67db01",
"title": "b669dbd708ea86401e6ee4042432a6c23d67db01",
"url": "https://chromium.googlesource.com/infra/infra/+/b669dbd708ea86401e6ee4042432a6c23d67db01"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "28428859061a3d44df359cfda2950b9e168ed75a"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@28428859061a3d44df359cfda2950b9e168ed75a",
"title": "28428859061a3d44df359cfda2950b9e168ed75a",
"url": "https://chromium.googlesource.com/infra/infra/+/28428859061a3d44df359cfda2950b9e168ed75a"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "0c335b78f4fd3988580d5ef12543c274e9ed1480"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0c335b78f4fd3988580d5ef12543c274e9ed1480",
"title": "0c335b78f4fd3988580d5ef12543c274e9ed1480",
"url": "https://chromium.googlesource.com/infra/infra/+/0c335b78f4fd3988580d5ef12543c274e9ed1480"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "baccad6319bf33f0f250ebd8d03793d8e168d736"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@baccad6319bf33f0f250ebd8d03793d8e168d736",
"title": "baccad6319bf33f0f250ebd8d03793d8e168d736",
"url": "https://chromium.googlesource.com/infra/infra/+/baccad6319bf33f0f250ebd8d03793d8e168d736"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "c64ab13503beb05e0998a58d402aa07c0f2c6eba"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c64ab13503beb05e0998a58d402aa07c0f2c6eba",
"title": "c64ab13503beb05e0998a58d402aa07c0f2c6eba",
"url": "https://chromium.googlesource.com/infra/infra/+/c64ab13503beb05e0998a58d402aa07c0f2c6eba"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "ab16c48e09a6a2201d2f692cba05429d34130a30"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ab16c48e09a6a2201d2f692cba05429d34130a30",
"title": "ab16c48e09a6a2201d2f692cba05429d34130a30",
"url": "https://chromium.googlesource.com/infra/infra/+/ab16c48e09a6a2201d2f692cba05429d34130a30"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "209b050e74e5e6bec0e4080bdca06c26d0a02c70"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@209b050e74e5e6bec0e4080bdca06c26d0a02c70",
"title": "209b050e74e5e6bec0e4080bdca06c26d0a02c70",
"url": "https://chromium.googlesource.com/infra/infra/+/209b050e74e5e6bec0e4080bdca06c26d0a02c70"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "a4fee7ebbf332aa080f000318842fe99d671c65c"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a4fee7ebbf332aa080f000318842fe99d671c65c",
"title": "a4fee7ebbf332aa080f000318842fe99d671c65c",
"url": "https://chromium.googlesource.com/infra/infra/+/a4fee7ebbf332aa080f000318842fe99d671c65c"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "a4fee7ebbf332aa080f000318842fe99d671c65c",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9018443718321434304"
},
{
"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"
}
}
[23:27:55.856] Scheduled build:
{
"id": "8853013656325930992",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 18849,
"createdBy": "project:infra",
"createTime": "2021-03-11T23:27:55.376585Z",
"updateTime": "2021-03-11T23:27:55.774209Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "a4fee7ebbf332aa080f000318842fe99d671c65c",
"ref": "refs/heads/master"
}
}
}
[23:27:55.856] Task URL: https://cr-buildbucket-dev.appspot.com/build/8853013656325930992
[23:27:55.856] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:2:0) after 1m0s
[23:28:55.952] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:2:0)
[23:28:55.952] Timer tick, asking Buildbucket for the build status
[23:28:56.048] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:56.048] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:3:0) after 1m0s
[23:29:56.076] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:3:0)
[23:29:56.076] Timer tick, asking Buildbucket for the build status
[23:29:56.157] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:56.157] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:4:0) after 1m0s
[23:30:56.223] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:4:0)
[23:30:56.223] Timer tick, asking Buildbucket for the build status
[23:30:56.317] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:56.317] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:5:0) after 1m0s
[23:31:56.403] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:5:0)
[23:31:56.403] Timer tick, asking Buildbucket for the build status
[23:31:56.481] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:56.481] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:6:0) after 1m0s
[23:32:56.509] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:6:0)
[23:32:56.509] Timer tick, asking Buildbucket for the build status
[23:32:56.592] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:56.592] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:7:0) after 1m0s
[23:33:56.691] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:7:0)
[23:33:56.691] Timer tick, asking Buildbucket for the build status
[23:33:56.787] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:56.787] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:8:0) after 1m0s
[23:34:56.898] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:8:0)
[23:34:56.898] Timer tick, asking Buildbucket for the build status
[23:34:57.010] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:57.010] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:9:0) after 1m0s
[23:35:57.069] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:9:0)
[23:35:57.069] Timer tick, asking Buildbucket for the build status
[23:35:57.161] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:57.161] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:10:0) after 1m0s
[23:36:57.193] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:10:0)
[23:36:57.193] Timer tick, asking Buildbucket for the build status
[23:36:57.280] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:57.280] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:11:0) after 1m0s
[23:37:57.360] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:11:0)
[23:37:57.360] Timer tick, asking Buildbucket for the build status
[23:37:57.457] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:57.457] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:12:0) after 1m0s
[23:38:57.574] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:12:0)
[23:38:57.574] Timer tick, asking Buildbucket for the build status
[23:38:57.697] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:57.697] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:13:0) after 1m0s
[23:39:57.786] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:13:0)
[23:39:57.787] Timer tick, asking Buildbucket for the build status
[23:39:57.862] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:57.862] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:14:0) after 1m0s
[23:40:57.880] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:14:0)
[23:40:57.880] Timer tick, asking Buildbucket for the build status
[23:40:57.954] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:57.954] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:15:0) after 1m0s
[23:41:58.059] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:15:0)
[23:41:58.059] Timer tick, asking Buildbucket for the build status
[23:41:58.143] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:58.143] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:16:0) after 1m0s
[23:42:58.190] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:16:0)
[23:42:58.190] Timer tick, asking Buildbucket for the build status
[23:42:58.372] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:58.372] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:17:0) after 1m0s
[23:43:58.389] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:17:0)
[23:43:58.389] Timer tick, asking Buildbucket for the build status
[23:43:58.477] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:58.477] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:18:0) after 1m0s
[23:44:58.565] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:18:0)
[23:44:58.565] Timer tick, asking Buildbucket for the build status
[23:44:58.656] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:58.656] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:19:0) after 1m0s
[23:45:58.698] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:19:0)
[23:45:58.698] Timer tick, asking Buildbucket for the build status
[23:45:58.775] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:58.776] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:20:0) after 1m0s
[23:46:58.809] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:20:0)
[23:46:58.809] Timer tick, asking Buildbucket for the build status
[23:46:58.889] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:58.889] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:21:0) after 1m0s
[23:47:58.966] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:21:0)
[23:47:58.966] Timer tick, asking Buildbucket for the build status
[23:47:59.046] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:59.046] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:22:0) after 1m0s
[23:48:59.093] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:22:0)
[23:48:59.093] Timer tick, asking Buildbucket for the build status
[23:48:59.186] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:59.186] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:23:0) after 1m0s
[23:49:59.254] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:23:0)
[23:49:59.254] Timer tick, asking Buildbucket for the build status
[23:49:59.334] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:59.334] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:24:0) after 1m0s
[23:50:59.356] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:24:0)
[23:50:59.356] Timer tick, asking Buildbucket for the build status
[23:50:59.439] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:59.439] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:25:0) after 1m0s
[23:51:59.462] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:25:0)
[23:51:59.462] Timer tick, asking Buildbucket for the build status
[23:51:59.563] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:59.563] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:26:0) after 1m0s
[23:52:59.583] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:26:0)
[23:52:59.583] Timer tick, asking Buildbucket for the build status
[23:52:59.656] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:59.656] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:27:0) after 1m0s
[23:53:59.768] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:27:0)
[23:53:59.768] Timer tick, asking Buildbucket for the build status
[23:53:59.848] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:53:59.848] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:28:0) after 1m0s
[23:54:59.866] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:28:0)
[23:54:59.866] Timer tick, asking Buildbucket for the build status
[23:54:59.966] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:54:59.966] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:29:0) after 1m0s
[23:55:59.993] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:29:0)
[23:55:59.993] Timer tick, asking Buildbucket for the build status
[23:56:00.083] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:56:00.083] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:30:0) after 1m0s
[23:57:00.192] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:30:0)
[23:57:00.192] Timer tick, asking Buildbucket for the build status
[23:57:00.318] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:00.318] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:31:0) after 1m0s
[23:58:00.403] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:31:0)
[23:58:00.403] Timer tick, asking Buildbucket for the build status
[23:58:00.510] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:58:00.510] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:32:0) after 1m0s
[23:59:00.553] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:32:0)
[23:59:00.553] Timer tick, asking Buildbucket for the build status
[23:59:00.644] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:59:00.644] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:33:0) after 1m0s
[00:00:00.662] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:33:0)
[00:00:00.662] Timer tick, asking Buildbucket for the build status
[00:00:00.737] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:00:00.737] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:34:0) after 1m0s
[00:01:00.810] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:34:0)
[00:01:00.810] Timer tick, asking Buildbucket for the build status
[00:01:00.900] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:01:00.900] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:35:0) after 1m0s
[00:02:00.966] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:35:0)
[00:02:00.966] Timer tick, asking Buildbucket for the build status
[00:02:01.059] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:02:01.059] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:36:0) after 1m0s
[00:03:01.154] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:36:0)
[00:03:01.154] Timer tick, asking Buildbucket for the build status
[00:03:01.247] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:03:01.247] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:37:0) after 1m0s
[00:04:01.277] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:37:0)
[00:04:01.277] Timer tick, asking Buildbucket for the build status
[00:04:01.403] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:04:01.403] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:38:0) after 1m0s
[00:05:01.453] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:38:0)
[00:05:01.453] Timer tick, asking Buildbucket for the build status
[00:05:01.543] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:05:01.543] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:39:0) after 1m0s
[00:06:01.566] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:39:0)
[00:06:01.566] Timer tick, asking Buildbucket for the build status
[00:06:01.683] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:06:01.683] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:40:0) after 1m0s
[00:07:01.766] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:40:0)
[00:07:01.766] Timer tick, asking Buildbucket for the build status
[00:07:01.960] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:07:01.960] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:41:0) after 1m0s
[00:08:01.996] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:41:0)
[00:08:01.996] Timer tick, asking Buildbucket for the build status
[00:08:02.101] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:08:02.101] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:42:0) after 1m0s
[00:09:02.201] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:42:0)
[00:09:02.201] Timer tick, asking Buildbucket for the build status
[00:09:02.383] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:09:02.384] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:43:0) after 1m0s
[00:10:02.491] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:43:0)
[00:10:02.491] Timer tick, asking Buildbucket for the build status
[00:10:02.679] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:10:02.679] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:44:0) after 1m0s
[00:11:02.711] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:44:0)
[00:11:02.711] Timer tick, asking Buildbucket for the build status
[00:11:02.876] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:11:02.876] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:45:0) after 1m0s
[00:12:02.993] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:45:0)
[00:12:02.993] Timer tick, asking Buildbucket for the build status
[00:12:03.086] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:12:03.086] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:46:0) after 1m0s
[00:13:03.165] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:46:0)
[00:13:03.165] Timer tick, asking Buildbucket for the build status
[00:13:03.254] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:13:03.254] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:47:0) after 1m0s
[00:14:03.357] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:47:0)
[00:14:03.357] Timer tick, asking Buildbucket for the build status
[00:14:03.458] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:14:03.458] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:48:0) after 1m0s
[00:15:03.507] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:48:0)
[00:15:03.507] Timer tick, asking Buildbucket for the build status
[00:15:03.589] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:15:03.589] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:49:0) after 1m0s
[00:16:03.693] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:49:0)
[00:16:03.693] Timer tick, asking Buildbucket for the build status
[00:16:03.785] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:16:03.785] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:50:0) after 1m0s
[00:17:03.862] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:50:0)
[00:17:03.862] Timer tick, asking Buildbucket for the build status
[00:17:03.952] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:17:03.952] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:51:0) after 1m0s
[00:18:03.975] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:51:0)
[00:18:03.975] Timer tick, asking Buildbucket for the build status
[00:18:04.062] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:18:04.062] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:52:0) after 1m0s
[00:19:04.156] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:52:0)
[00:19:04.156] Timer tick, asking Buildbucket for the build status
[00:19:04.236] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:19:04.237] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:53:0) after 1m0s
[00:20:04.266] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:53:0)
[00:20:04.266] Timer tick, asking Buildbucket for the build status
[00:20:04.354] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:20:04.354] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:54:0) after 1m0s
[00:21:04.403] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:54:0)
[00:21:04.403] Timer tick, asking Buildbucket for the build status
[00:21:04.480] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:04.480] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:55:0) after 1m0s
[00:22:04.502] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:55:0)
[00:22:04.502] Timer tick, asking Buildbucket for the build status
[00:22:04.653] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:04.653] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:56:0) after 1m0s
[00:23:04.750] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:56:0)
[00:23:04.750] Timer tick, asking Buildbucket for the build status
[00:23:04.842] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:23:04.842] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:57:0) after 1m0s
[00:24:04.865] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:57:0)
[00:24:04.865] Timer tick, asking Buildbucket for the build status
[00:24:04.956] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:24:04.956] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:58:0) after 1m0s
[00:25:05.017] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:58:0)
[00:25:05.017] Timer tick, asking Buildbucket for the build status
[00:25:05.092] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:25:05.092] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:59:0) after 1m0s
[00:26:05.163] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:59:0)
[00:26:05.163] Timer tick, asking Buildbucket for the build status
[00:26:05.265] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:26:05.265] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:60:0) after 1m0s
[00:27:05.291] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:60:0)
[00:27:05.291] Timer tick, asking Buildbucket for the build status
[00:27:05.380] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:27:05.380] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:61:0) after 1m0s
[00:28:05.400] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:61:0)
[00:28:05.400] Timer tick, asking Buildbucket for the build status
[00:28:05.478] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:28:05.478] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:62:0) after 1m0s
[00:29:05.570] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:62:0)
[00:29:05.570] Timer tick, asking Buildbucket for the build status
[00:29:05.748] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:29:05.748] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:63:0) after 1m0s
[00:30:05.771] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:63:0)
[00:30:05.771] Timer tick, asking Buildbucket for the build status
[00:30:05.862] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:30:05.862] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:64:0) after 1m0s
[00:31:05.884] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:64:0)
[00:31:05.884] Timer tick, asking Buildbucket for the build status
[00:31:05.971] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:31:05.971] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:65:0) after 1m0s
[00:32:06.056] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:65:0)
[00:32:06.056] Timer tick, asking Buildbucket for the build status
[00:32:06.197] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:32:06.197] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:66:0) after 1m0s
[00:33:06.264] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:66:0)
[00:33:06.264] Timer tick, asking Buildbucket for the build status
[00:33:06.365] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:33:06.365] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:67:0) after 1m0s
[00:34:06.468] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:67:0)
[00:34:06.468] Timer tick, asking Buildbucket for the build status
[00:34:06.576] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:34:06.576] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:68:0) after 1m0s
[00:35:06.616] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:68:0)
[00:35:06.616] Timer tick, asking Buildbucket for the build status
[00:35:06.711] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:06.711] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:69:0) after 1m0s
[00:36:06.743] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:69:0)
[00:36:06.743] Timer tick, asking Buildbucket for the build status
[00:36:06.831] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:06.831] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:70:0) after 1m0s
[00:37:06.853] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:70:0)
[00:37:06.853] Timer tick, asking Buildbucket for the build status
[00:37:06.958] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:06.958] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:71:0) after 1m0s
[00:38:07.074] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:71:0)
[00:38:07.074] Timer tick, asking Buildbucket for the build status
[00:38:07.155] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:07.155] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:72:0) after 1m0s
[00:39:07.226] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:72:0)
[00:39:07.226] Timer tick, asking Buildbucket for the build status
[00:39:07.316] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:07.316] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:73:0) after 1m0s
[00:40:07.422] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:73:0)
[00:40:07.422] Timer tick, asking Buildbucket for the build status
[00:40:07.538] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:07.538] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:74:0) after 1m0s
[00:41:07.602] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:74:0)
[00:41:07.602] Timer tick, asking Buildbucket for the build status
[00:41:07.740] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:07.740] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:75:0) after 1m0s
[00:42:07.800] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:75:0)
[00:42:07.800] Timer tick, asking Buildbucket for the build status
[00:42:07.879] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:07.879] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:76:0) after 1m0s
[00:43:07.898] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:76:0)
[00:43:07.898] Timer tick, asking Buildbucket for the build status
[00:43:07.984] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:07.984] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:77:0) after 1m0s
[00:44:08.015] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:77:0)
[00:44:08.015] Timer tick, asking Buildbucket for the build status
[00:44:08.155] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:08.155] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:78:0) after 1m0s
[00:45:08.197] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:78:0)
[00:45:08.197] Timer tick, asking Buildbucket for the build status
[00:45:08.277] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:08.277] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:79:0) after 1m0s
[00:46:08.312] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:79:0)
[00:46:08.312] Timer tick, asking Buildbucket for the build status
[00:46:08.455] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:08.455] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:80:0) after 1m0s
[00:47:08.480] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:80:0)
[00:47:08.480] Timer tick, asking Buildbucket for the build status
[00:47:08.570] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:08.570] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:81:0) after 1m0s
[00:48:08.595] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:81:0)
[00:48:08.595] Timer tick, asking Buildbucket for the build status
[00:48:08.673] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:08.673] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:82:0) after 1m0s
[00:49:08.718] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:82:0)
[00:49:08.718] Timer tick, asking Buildbucket for the build status
[00:49:08.815] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:49:08.815] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:83:0) after 1m0s
[00:50:08.916] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:83:0)
[00:50:08.916] Timer tick, asking Buildbucket for the build status
[00:50:09.009] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:50:09.009] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:84:0) after 1m0s
[00:51:09.042] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:84:0)
[00:51:09.043] Timer tick, asking Buildbucket for the build status
[00:51:09.156] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:09.156] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:85:0) after 1m0s
[00:52:09.213] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:85:0)
[00:52:09.213] Timer tick, asking Buildbucket for the build status
[00:52:09.346] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:09.346] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:86:0) after 1m0s
[00:53:10.911] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:86:0)
[00:53:10.911] Timer tick, asking Buildbucket for the build status
[00:53:11.259] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:11.259] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:87:0) after 1m0s
[00:54:11.306] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:87:0)
[00:54:11.306] Timer tick, asking Buildbucket for the build status
[00:54:11.398] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:54:11.398] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:88:0) after 1m0s
[00:55:11.528] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:88:0)
[00:55:11.528] Timer tick, asking Buildbucket for the build status
[00:55:11.637] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:55:11.637] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:89:0) after 1m0s
[00:56:11.667] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:89:0)
[00:56:11.667] Timer tick, asking Buildbucket for the build status
[00:56:11.744] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:56:11.744] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:90:0) after 1m0s
[00:57:11.823] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:90:0)
[00:57:11.823] Timer tick, asking Buildbucket for the build status
[00:57:11.904] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:11.904] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:91:0) after 1m0s
[00:58:12.006] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:91:0)
[00:58:12.006] Timer tick, asking Buildbucket for the build status
[00:58:12.085] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:12.085] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:92:0) after 1m0s
[00:59:12.109] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:92:0)
[00:59:12.109] Timer tick, asking Buildbucket for the build status
[00:59:12.185] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:12.185] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:93:0) after 1m0s
[01:00:12.221] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:93:0)
[01:00:12.221] Timer tick, asking Buildbucket for the build status
[01:00:12.317] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:12.317] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:94:0) after 1m0s
[01:01:12.349] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:94:0)
[01:01:12.350] Timer tick, asking Buildbucket for the build status
[01:01:12.430] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:12.430] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:95:0) after 1m0s
[01:02:12.452] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:95:0)
[01:02:12.452] Timer tick, asking Buildbucket for the build status
[01:02:12.554] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:12.554] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:96:0) after 1m0s
[01:03:12.597] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:96:0)
[01:03:12.597] Timer tick, asking Buildbucket for the build status
[01:03:12.847] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:12.847] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:97:0) after 1m0s
[01:04:12.867] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:97:0)
[01:04:12.867] Timer tick, asking Buildbucket for the build status
[01:04:12.942] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:12.942] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:98:0) after 1m0s
[01:05:13.002] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:98:0)
[01:05:13.002] Timer tick, asking Buildbucket for the build status
[01:05:13.088] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:13.088] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:99:0) after 1m0s
[01:06:13.209] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:99:0)
[01:06:13.209] Timer tick, asking Buildbucket for the build status
[01:06:13.394] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:13.394] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:100:0) after 1m0s
[01:07:13.513] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:100:0)
[01:07:13.513] Timer tick, asking Buildbucket for the build status
[01:07:13.598] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:13.598] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:101:0) after 1m0s
[01:08:13.738] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:101:0)
[01:08:13.738] Timer tick, asking Buildbucket for the build status
[01:08:13.838] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:13.838] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:102:0) after 1m0s
[01:09:13.901] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:102:0)
[01:09:13.901] Timer tick, asking Buildbucket for the build status
[01:09:13.979] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:13.979] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:103:0) after 1m0s
[01:10:13.998] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:103:0)
[01:10:13.998] Timer tick, asking Buildbucket for the build status
[01:10:14.098] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:14.098] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:104:0) after 1m0s
[01:11:14.205] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:104:0)
[01:11:14.205] Timer tick, asking Buildbucket for the build status
[01:11:14.291] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:14.291] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:105:0) after 1m0s
[01:12:14.394] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:105:0)
[01:12:14.394] Timer tick, asking Buildbucket for the build status
[01:12:14.547] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:14.547] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:106:0) after 1m0s
[01:13:14.617] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:106:0)
[01:13:14.617] Timer tick, asking Buildbucket for the build status
[01:13:14.704] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:14.704] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:107:0) after 1m0s
[01:14:14.803] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:107:0)
[01:14:14.803] Timer tick, asking Buildbucket for the build status
[01:14:14.927] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:14.927] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:108:0) after 1m0s
[01:15:15.008] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:108:0)
[01:15:15.008] Timer tick, asking Buildbucket for the build status
[01:15:15.090] Build 8853013656325930992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:15.090] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:109:0) after 1m0s
[01:15:48.997] Received PubSub notification, asking Buildbucket for the build status
[01:15:49.085] Build 8853013656325930992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:15.120] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:109:0)
[01:16:15.120] Timer tick, asking Buildbucket for the build status
[01:16:15.227] Build 8853013656325930992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:15.227] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:111:0) after 1m0s
[01:17:15.247] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:111:0)
[01:17:15.247] Timer tick, asking Buildbucket for the build status
[01:17:15.332] Build 8853013656325930992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:15.332] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:112:0) after 1m0s
[01:18:15.357] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:112:0)
[01:18:15.357] Timer tick, asking Buildbucket for the build status
[01:18:15.560] Build 8853013656325930992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:15.560] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:113:0) after 1m0s
[01:19:15.579] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:113:0)
[01:19:15.579] Timer tick, asking Buildbucket for the build status
[01:19:15.811] Build 8853013656325930992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:15.811] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:114:0) after 1m0s
[01:20:15.904] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:114:0)
[01:20:15.904] Timer tick, asking Buildbucket for the build status
[01:20:16.121] Build 8853013656325930992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:16.121] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:115:0) after 1m0s
[01:21:16.208] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:115:0)
[01:21:16.208] Timer tick, asking Buildbucket for the build status
[01:21:16.651] Build 8853013656325930992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:16.651] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:116:0) after 1m0s
[01:22:16.703] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:116:0)
[01:22:16.703] Timer tick, asking Buildbucket for the build status
[01:22:16.938] Build 8853013656325930992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:16.938] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018443718321434304:117:0) after 1m0s
[01:23:11.422] Received PubSub notification, asking Buildbucket for the build status
[01:23:11.511] Build 8853013656325930992: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:23:11.511] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1615512190957562",
"created_by": "project:infra",
"created_ts": "1615505275376585",
"id": "8853013656325930992",
"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\": \"8fcb20ac11f9d9adbc9c5f5d58ec5af1a6286554\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@8fcb20ac11f9d9adbc9c5f5d58ec5af1a6286554\", \"title\": \"8fcb20ac11f9d9adbc9c5f5d58ec5af1a6286554\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/8fcb20ac11f9d9adbc9c5f5d58ec5af1a6286554\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"3c99b8407491bc7f9e3a6dad68b20fc573f33f81\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@3c99b8407491bc7f9e3a6dad68b20fc573f33f81\", \"title\": \"3c99b8407491bc7f9e3a6dad68b20fc573f33f81\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/3c99b8407491bc7f9e3a6dad68b20fc573f33f81\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"b669dbd708ea86401e6ee4042432a6c23d67db01\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b669dbd708ea86401e6ee4042432a6c23d67db01\", \"title\": \"b669dbd708ea86401e6ee4042432a6c23d67db01\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/b669dbd708ea86401e6ee4042432a6c23d67db01\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"28428859061a3d44df359cfda2950b9e168ed75a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@28428859061a3d44df359cfda2950b9e168ed75a\", \"title\": \"28428859061a3d44df359cfda2950b9e168ed75a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/28428859061a3d44df359cfda2950b9e168ed75a\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"0c335b78f4fd3988580d5ef12543c274e9ed1480\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0c335b78f4fd3988580d5ef12543c274e9ed1480\", \"title\": \"0c335b78f4fd3988580d5ef12543c274e9ed1480\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0c335b78f4fd3988580d5ef12543c274e9ed1480\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"baccad6319bf33f0f250ebd8d03793d8e168d736\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@baccad6319bf33f0f250ebd8d03793d8e168d736\", \"title\": \"baccad6319bf33f0f250ebd8d03793d8e168d736\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/baccad6319bf33f0f250ebd8d03793d8e168d736\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"c64ab13503beb05e0998a58d402aa07c0f2c6eba\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c64ab13503beb05e0998a58d402aa07c0f2c6eba\", \"title\": \"c64ab13503beb05e0998a58d402aa07c0f2c6eba\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/c64ab13503beb05e0998a58d402aa07c0f2c6eba\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"ab16c48e09a6a2201d2f692cba05429d34130a30\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ab16c48e09a6a2201d2f692cba05429d34130a30\", \"title\": \"ab16c48e09a6a2201d2f692cba05429d34130a30\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/ab16c48e09a6a2201d2f692cba05429d34130a30\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"209b050e74e5e6bec0e4080bdca06c26d0a02c70\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@209b050e74e5e6bec0e4080bdca06c26d0a02c70\", \"title\": \"209b050e74e5e6bec0e4080bdca06c26d0a02c70\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/209b050e74e5e6bec0e4080bdca06c26d0a02c70\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"a4fee7ebbf332aa080f000318842fe99d671c65c\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a4fee7ebbf332aa080f000318842fe99d671c65c\", \"title\": \"a4fee7ebbf332aa080f000318842fe99d671c65c\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/a4fee7ebbf332aa080f000318842fe99d671c65c\"}]}}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"a4fee7ebbf332aa080f000318842fe99d671c65c\", \"got_revision_cp\": \"refs/heads/master@{#39283}\"}, \"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-4h1s\"], \"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": "1615511745928583",
"status": "COMPLETED",
"status_changed_ts": "1615511747654929",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18849",
"builder:infra-continuous-win10-64",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/a4fee7ebbf332aa080f000318842fe99d671c65c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018443718321434304",
"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/8853013656325930992/+/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:523c6fc4de335c10",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1615512190957944",
"url": "https://luci-milo-dev.appspot.com/b/8853013656325930992",
"utcnow_ts": "1615512191499765"
}
}
[01:23:11.511] Invocation finished in 1h55m17.42225769s with status SUCCEEDED