[07:24:06.139] New invocation is queued and will start shortly
[07:24:07.295] Starting the invocation (attempt 1)
[07:24:07.325] Popped gitiles commit info from properties and tags
[07:24:07.325] Popped a tag as redundant: buildset:commit/git/187df22a10f7507920841c65b28ef8d8237fc5ce
[07:24:07.325] Popped a tag as redundant: buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/187df22a10f7507920841c65b28ef8d8237fc5ce
[07:24:07.325] Popped a tag as redundant: gitiles_ref:refs/heads/master
[07:24:07.325] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[07:24:07.326] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[07:24:07.326] Buildbucket request:
{
"requestId": "9018413758372643200",
"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": "0817883c4f24ae48f3494dff0bb78d433dac8e28"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0817883c4f24ae48f3494dff0bb78d433dac8e28",
"title": "0817883c4f24ae48f3494dff0bb78d433dac8e28",
"url": "https://chromium.googlesource.com/infra/infra/+/0817883c4f24ae48f3494dff0bb78d433dac8e28"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "d5852eeb18a50e783452742fa35c8c7898111fad"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d5852eeb18a50e783452742fa35c8c7898111fad",
"title": "d5852eeb18a50e783452742fa35c8c7898111fad",
"url": "https://chromium.googlesource.com/infra/infra/+/d5852eeb18a50e783452742fa35c8c7898111fad"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "187df22a10f7507920841c65b28ef8d8237fc5ce"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@187df22a10f7507920841c65b28ef8d8237fc5ce",
"title": "187df22a10f7507920841c65b28ef8d8237fc5ce",
"url": "https://chromium.googlesource.com/infra/infra/+/187df22a10f7507920841c65b28ef8d8237fc5ce"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "187df22a10f7507920841c65b28ef8d8237fc5ce",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9018413758372643200"
},
{
"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"
}
}
[07:24:07.916] Scheduled build:
{
"id": "8852983696366217632",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 18854,
"createdBy": "project:infra",
"createTime": "2021-03-12T07:24:07.420575Z",
"updateTime": "2021-03-12T07:24:07.848542Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "187df22a10f7507920841c65b28ef8d8237fc5ce",
"ref": "refs/heads/master"
}
},
"canary": true
}
[07:24:07.916] Task URL: https://cr-buildbucket-dev.appspot.com/build/8852983696366217632
[07:24:07.916] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:2:0) after 1m0s
[07:25:07.988] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:2:0)
[07:25:07.988] Timer tick, asking Buildbucket for the build status
[07:25:08.054] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:25:08.054] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:3:0) after 1m0s
[07:26:08.111] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:3:0)
[07:26:08.111] Timer tick, asking Buildbucket for the build status
[07:26:08.206] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:26:08.206] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:4:0) after 1m0s
[07:27:08.398] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:4:0)
[07:27:08.398] Timer tick, asking Buildbucket for the build status
[07:27:08.471] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:27:08.471] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:5:0) after 1m0s
[07:28:08.604] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:5:0)
[07:28:08.604] Timer tick, asking Buildbucket for the build status
[07:28:08.670] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:28:08.670] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:6:0) after 1m0s
[07:29:08.684] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:6:0)
[07:29:08.684] Timer tick, asking Buildbucket for the build status
[07:29:08.795] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:29:08.795] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:7:0) after 1m0s
[07:30:08.814] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:7:0)
[07:30:08.814] Timer tick, asking Buildbucket for the build status
[07:30:08.895] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:30:08.895] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:8:0) after 1m0s
[07:31:09.011] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:8:0)
[07:31:09.011] Timer tick, asking Buildbucket for the build status
[07:31:09.080] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:31:09.080] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:9:0) after 1m0s
[07:32:09.099] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:9:0)
[07:32:09.099] Timer tick, asking Buildbucket for the build status
[07:32:09.181] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:32:09.182] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:10:0) after 1m0s
[07:33:09.275] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:10:0)
[07:33:09.275] Timer tick, asking Buildbucket for the build status
[07:33:09.358] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:33:09.358] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:11:0) after 1m0s
[07:34:09.431] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:11:0)
[07:34:09.431] Timer tick, asking Buildbucket for the build status
[07:34:09.515] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:34:09.515] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:12:0) after 1m0s
[07:35:09.542] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:12:0)
[07:35:09.542] Timer tick, asking Buildbucket for the build status
[07:35:09.641] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:35:09.641] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:13:0) after 1m0s
[07:36:09.698] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:13:0)
[07:36:09.698] Timer tick, asking Buildbucket for the build status
[07:36:09.786] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:36:09.786] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:14:0) after 1m0s
[07:37:09.800] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:14:0)
[07:37:09.800] Timer tick, asking Buildbucket for the build status
[07:37:09.881] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:37:09.881] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:15:0) after 1m0s
[07:38:09.905] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:15:0)
[07:38:09.905] Timer tick, asking Buildbucket for the build status
[07:38:10.002] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:38:10.002] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:16:0) after 1m0s
[07:39:10.121] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:16:0)
[07:39:10.121] Timer tick, asking Buildbucket for the build status
[07:39:10.192] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:39:10.193] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:17:0) after 1m0s
[07:40:10.297] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:17:0)
[07:40:10.297] Timer tick, asking Buildbucket for the build status
[07:40:10.408] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:40:10.408] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:18:0) after 1m0s
[07:41:10.492] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:18:0)
[07:41:10.492] Timer tick, asking Buildbucket for the build status
[07:41:10.571] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:41:10.571] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:19:0) after 1m0s
[07:42:10.660] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:19:0)
[07:42:10.660] Timer tick, asking Buildbucket for the build status
[07:42:10.735] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:42:10.735] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:20:0) after 1m0s
[07:43:10.784] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:20:0)
[07:43:10.784] Timer tick, asking Buildbucket for the build status
[07:43:10.854] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:43:10.854] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:21:0) after 1m0s
[07:44:10.894] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:21:0)
[07:44:10.894] Timer tick, asking Buildbucket for the build status
[07:44:10.986] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:44:10.986] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:22:0) after 1m0s
[07:45:11.107] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:22:0)
[07:45:11.107] Timer tick, asking Buildbucket for the build status
[07:45:11.189] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:45:11.189] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:23:0) after 1m0s
[07:46:11.266] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:23:0)
[07:46:11.266] Timer tick, asking Buildbucket for the build status
[07:46:11.332] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:46:11.332] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:24:0) after 1m0s
[07:47:11.398] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:24:0)
[07:47:11.398] Timer tick, asking Buildbucket for the build status
[07:47:11.487] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:47:11.487] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:25:0) after 1m0s
[07:48:11.519] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:25:0)
[07:48:11.519] Timer tick, asking Buildbucket for the build status
[07:48:11.607] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:48:11.607] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:26:0) after 1m0s
[07:49:11.659] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:26:0)
[07:49:11.659] Timer tick, asking Buildbucket for the build status
[07:49:11.775] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:49:11.775] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:27:0) after 1m0s
[07:50:11.834] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:27:0)
[07:50:11.834] Timer tick, asking Buildbucket for the build status
[07:50:11.916] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:50:11.916] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:28:0) after 1m0s
[07:51:12.009] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:28:0)
[07:51:12.009] Timer tick, asking Buildbucket for the build status
[07:51:12.142] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:51:12.142] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:29:0) after 1m0s
[07:52:12.211] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:29:0)
[07:52:12.211] Timer tick, asking Buildbucket for the build status
[07:52:12.272] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:52:12.272] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:30:0) after 1m0s
[07:53:12.306] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:30:0)
[07:53:12.306] Timer tick, asking Buildbucket for the build status
[07:53:12.412] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:53:12.412] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:31:0) after 1m0s
[07:54:12.591] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:31:0)
[07:54:12.591] Timer tick, asking Buildbucket for the build status
[07:54:12.674] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:54:12.674] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:32:0) after 1m0s
[07:55:12.794] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:32:0)
[07:55:12.794] Timer tick, asking Buildbucket for the build status
[07:55:12.928] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:55:12.928] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:33:0) after 1m0s
[07:56:12.991] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:33:0)
[07:56:12.991] Timer tick, asking Buildbucket for the build status
[07:56:13.073] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:56:13.073] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:34:0) after 1m0s
[07:57:13.099] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:34:0)
[07:57:13.099] Timer tick, asking Buildbucket for the build status
[07:57:13.162] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:57:13.162] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:35:0) after 1m0s
[07:58:13.186] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:35:0)
[07:58:13.186] Timer tick, asking Buildbucket for the build status
[07:58:13.254] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:58:13.254] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:36:0) after 1m0s
[07:59:13.294] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:36:0)
[07:59:13.294] Timer tick, asking Buildbucket for the build status
[07:59:13.370] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:59:13.370] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:37:0) after 1m0s
[08:00:13.392] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:37:0)
[08:00:13.392] Timer tick, asking Buildbucket for the build status
[08:00:13.454] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:00:13.454] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:38:0) after 1m0s
[08:01:13.482] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:38:0)
[08:01:13.482] Timer tick, asking Buildbucket for the build status
[08:01:13.552] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:01:13.552] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:39:0) after 1m0s
[08:02:13.590] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:39:0)
[08:02:13.590] Timer tick, asking Buildbucket for the build status
[08:02:13.664] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:02:13.664] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:40:0) after 1m0s
[08:03:13.713] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:40:0)
[08:03:13.713] Timer tick, asking Buildbucket for the build status
[08:03:13.842] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:03:13.842] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:41:0) after 1m0s
[08:04:13.914] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:41:0)
[08:04:13.914] Timer tick, asking Buildbucket for the build status
[08:04:14.061] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:04:14.061] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:42:0) after 1m0s
[08:05:14.177] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:42:0)
[08:05:14.177] Timer tick, asking Buildbucket for the build status
[08:05:14.258] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:05:14.258] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:43:0) after 1m0s
[08:06:14.319] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:43:0)
[08:06:14.319] Timer tick, asking Buildbucket for the build status
[08:06:14.445] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:06:14.445] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:44:0) after 1m0s
[08:07:14.516] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:44:0)
[08:07:14.516] Timer tick, asking Buildbucket for the build status
[08:07:14.597] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:07:14.597] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:45:0) after 1m0s
[08:08:14.707] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:45:0)
[08:08:14.707] Timer tick, asking Buildbucket for the build status
[08:08:14.787] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:08:14.787] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:46:0) after 1m0s
[08:09:14.805] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:46:0)
[08:09:14.805] Timer tick, asking Buildbucket for the build status
[08:09:14.900] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:09:14.900] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:47:0) after 1m0s
[08:10:14.991] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:47:0)
[08:10:14.991] Timer tick, asking Buildbucket for the build status
[08:10:15.077] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:10:15.077] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:48:0) after 1m0s
[08:11:15.098] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:48:0)
[08:11:15.098] Timer tick, asking Buildbucket for the build status
[08:11:15.171] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:11:15.171] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:49:0) after 1m0s
[08:12:15.199] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:49:0)
[08:12:15.199] Timer tick, asking Buildbucket for the build status
[08:12:15.272] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:12:15.272] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:50:0) after 1m0s
[08:13:15.307] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:50:0)
[08:13:15.307] Timer tick, asking Buildbucket for the build status
[08:13:15.398] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:13:15.398] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:51:0) after 1m0s
[08:14:15.421] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:51:0)
[08:14:15.421] Timer tick, asking Buildbucket for the build status
[08:14:15.528] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:14:15.528] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:52:0) after 1m0s
[08:15:15.593] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:52:0)
[08:15:15.593] Timer tick, asking Buildbucket for the build status
[08:15:15.683] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:15:15.683] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:53:0) after 1m0s
[08:16:15.713] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:53:0)
[08:16:15.713] Timer tick, asking Buildbucket for the build status
[08:16:15.785] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:16:15.785] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:54:0) after 1m0s
[08:17:15.807] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:54:0)
[08:17:15.808] Timer tick, asking Buildbucket for the build status
[08:17:15.875] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:17:15.875] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:55:0) after 1m0s
[08:18:15.910] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:55:0)
[08:18:15.910] Timer tick, asking Buildbucket for the build status
[08:18:15.992] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:18:15.992] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:56:0) after 1m0s
[08:19:16.103] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:56:0)
[08:19:16.103] Timer tick, asking Buildbucket for the build status
[08:19:16.173] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:19:16.173] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:57:0) after 1m0s
[08:20:16.191] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:57:0)
[08:20:16.191] Timer tick, asking Buildbucket for the build status
[08:20:16.330] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:20:16.330] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:58:0) after 1m0s
[08:21:16.398] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:58:0)
[08:21:16.398] Timer tick, asking Buildbucket for the build status
[08:21:16.479] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:21:16.479] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:59:0) after 1m0s
[08:22:16.508] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:59:0)
[08:22:16.508] Timer tick, asking Buildbucket for the build status
[08:22:16.603] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:22:16.603] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:60:0) after 1m0s
[08:23:16.694] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:60:0)
[08:23:16.694] Timer tick, asking Buildbucket for the build status
[08:23:16.788] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:23:16.788] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:61:0) after 1m0s
[08:24:16.808] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:61:0)
[08:24:16.808] Timer tick, asking Buildbucket for the build status
[08:24:16.879] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:24:16.879] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:62:0) after 1m0s
[08:25:16.901] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:62:0)
[08:25:16.901] Timer tick, asking Buildbucket for the build status
[08:25:16.997] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:25:16.997] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:63:0) after 1m0s
[08:26:17.030] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:63:0)
[08:26:17.030] Timer tick, asking Buildbucket for the build status
[08:26:17.095] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:26:17.095] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:64:0) after 1m0s
[08:27:17.206] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:64:0)
[08:27:17.206] Timer tick, asking Buildbucket for the build status
[08:27:17.288] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:27:17.288] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:65:0) after 1m0s
[08:28:17.317] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:65:0)
[08:28:17.317] Timer tick, asking Buildbucket for the build status
[08:28:17.449] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:28:17.449] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:66:0) after 1m0s
[08:29:17.499] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:66:0)
[08:29:17.499] Timer tick, asking Buildbucket for the build status
[08:29:17.626] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:29:17.626] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:67:0) after 1m0s
[08:30:17.711] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:67:0)
[08:30:17.711] Timer tick, asking Buildbucket for the build status
[08:30:17.792] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:30:17.792] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:68:0) after 1m0s
[08:31:17.816] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:68:0)
[08:31:17.816] Timer tick, asking Buildbucket for the build status
[08:31:17.884] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:31:17.884] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:69:0) after 1m0s
[08:32:17.906] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:69:0)
[08:32:17.906] Timer tick, asking Buildbucket for the build status
[08:32:18.001] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:32:18.001] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:70:0) after 1m0s
[08:33:18.101] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:70:0)
[08:33:18.101] Timer tick, asking Buildbucket for the build status
[08:33:18.166] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:33:18.166] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:71:0) after 1m0s
[08:34:18.194] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:71:0)
[08:34:18.194] Timer tick, asking Buildbucket for the build status
[08:34:18.266] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:34:18.266] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:72:0) after 1m0s
[08:35:18.294] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:72:0)
[08:35:18.294] Timer tick, asking Buildbucket for the build status
[08:35:18.370] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:35:18.371] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:73:0) after 1m0s
[08:36:18.407] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:73:0)
[08:36:18.407] Timer tick, asking Buildbucket for the build status
[08:36:18.558] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:36:18.558] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:74:0) after 1m0s
[08:37:18.599] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:74:0)
[08:37:18.599] Timer tick, asking Buildbucket for the build status
[08:37:18.683] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:37:18.683] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:75:0) after 1m0s
[08:38:18.722] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:75:0)
[08:38:18.722] Timer tick, asking Buildbucket for the build status
[08:38:18.791] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:38:18.791] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:76:0) after 1m0s
[08:39:18.891] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:76:0)
[08:39:18.891] Timer tick, asking Buildbucket for the build status
[08:39:19.034] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:39:19.034] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:77:0) after 1m0s
[08:40:19.094] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:77:0)
[08:40:19.094] Timer tick, asking Buildbucket for the build status
[08:40:19.214] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:40:19.214] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:78:0) after 1m0s
[08:41:19.240] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:78:0)
[08:41:19.240] Timer tick, asking Buildbucket for the build status
[08:41:19.337] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:41:19.337] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:79:0) after 1m0s
[08:42:19.392] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:79:0)
[08:42:19.392] Timer tick, asking Buildbucket for the build status
[08:42:19.478] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:42:19.478] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:80:0) after 1m0s
[08:43:19.497] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:80:0)
[08:43:19.497] Timer tick, asking Buildbucket for the build status
[08:43:19.583] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:43:19.583] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:81:0) after 1m0s
[08:44:19.618] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:81:0)
[08:44:19.618] Timer tick, asking Buildbucket for the build status
[08:44:19.711] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:44:19.711] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:82:0) after 1m0s
[08:45:19.800] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:82:0)
[08:45:19.800] Timer tick, asking Buildbucket for the build status
[08:45:19.937] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:45:19.937] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:83:0) after 1m0s
[08:46:20.012] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:83:0)
[08:46:20.012] Timer tick, asking Buildbucket for the build status
[08:46:20.100] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:46:20.100] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:84:0) after 1m0s
[08:47:20.200] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:84:0)
[08:47:20.200] Timer tick, asking Buildbucket for the build status
[08:47:20.324] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:47:20.324] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:85:0) after 1m0s
[08:48:20.346] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:85:0)
[08:48:20.346] Timer tick, asking Buildbucket for the build status
[08:48:20.420] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:48:20.420] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:86:0) after 1m0s
[08:49:20.513] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:86:0)
[08:49:20.513] Timer tick, asking Buildbucket for the build status
[08:49:20.581] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:49:20.581] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:87:0) after 1m0s
[08:50:20.607] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:87:0)
[08:50:20.607] Timer tick, asking Buildbucket for the build status
[08:50:20.682] Build 8852983696366217632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:50:20.682] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:88:0) after 1m0s
[08:51:20.000] Received PubSub notification, asking Buildbucket for the build status
[08:51:20.140] Build 8852983696366217632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:20.713] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:88:0)
[08:51:20.713] Timer tick, asking Buildbucket for the build status
[08:51:20.780] Build 8852983696366217632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:20.780] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:90:0) after 1m0s
[08:52:20.807] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:90:0)
[08:52:20.808] Timer tick, asking Buildbucket for the build status
[08:52:20.877] Build 8852983696366217632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:20.877] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:91:0) after 1m0s
[08:53:20.905] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:91:0)
[08:53:20.905] Timer tick, asking Buildbucket for the build status
[08:53:20.987] Build 8852983696366217632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:20.987] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:92:0) after 1m0s
[08:54:21.010] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:92:0)
[08:54:21.010] Timer tick, asking Buildbucket for the build status
[08:54:21.141] Build 8852983696366217632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:21.141] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:93:0) after 1m0s
[08:55:21.210] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:93:0)
[08:55:21.211] Timer tick, asking Buildbucket for the build status
[08:55:21.338] Build 8852983696366217632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:21.338] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:94:0) after 1m0s
[08:56:21.407] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:94:0)
[08:56:21.407] Timer tick, asking Buildbucket for the build status
[08:56:21.530] Build 8852983696366217632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:21.530] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:95:0) after 1m0s
[08:57:21.603] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:95:0)
[08:57:21.603] Timer tick, asking Buildbucket for the build status
[08:57:21.675] Build 8852983696366217632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:21.675] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:96:0) after 1m0s
[08:58:21.698] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:96:0)
[08:58:21.698] Timer tick, asking Buildbucket for the build status
[08:58:21.793] Build 8852983696366217632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:21.793] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:97:0) after 1m0s
[08:59:21.893] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:97:0)
[08:59:21.893] Timer tick, asking Buildbucket for the build status
[08:59:21.971] Build 8852983696366217632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:21.971] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:98:0) after 1m0s
[09:00:22.000] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:98:0)
[09:00:22.000] Timer tick, asking Buildbucket for the build status
[09:00:22.084] Build 8852983696366217632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:22.084] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:99:0) after 1m0s
[09:01:22.105] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:99:0)
[09:01:22.105] Timer tick, asking Buildbucket for the build status
[09:01:22.186] Build 8852983696366217632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:22.186] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018413758372643200:100:0) after 1m0s
[09:02:13.700] Received PubSub notification, asking Buildbucket for the build status
[09:02:13.787] Build 8852983696366217632: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:02:13.787] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1615539733318919",
"created_by": "project:infra",
"created_ts": "1615533847420575",
"id": "8852983696366217632",
"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\": \"0817883c4f24ae48f3494dff0bb78d433dac8e28\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0817883c4f24ae48f3494dff0bb78d433dac8e28\", \"title\": \"0817883c4f24ae48f3494dff0bb78d433dac8e28\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/0817883c4f24ae48f3494dff0bb78d433dac8e28\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"d5852eeb18a50e783452742fa35c8c7898111fad\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@d5852eeb18a50e783452742fa35c8c7898111fad\", \"title\": \"d5852eeb18a50e783452742fa35c8c7898111fad\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/d5852eeb18a50e783452742fa35c8c7898111fad\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"187df22a10f7507920841c65b28ef8d8237fc5ce\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@187df22a10f7507920841c65b28ef8d8237fc5ce\", \"title\": \"187df22a10f7507920841c65b28ef8d8237fc5ce\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/187df22a10f7507920841c65b28ef8d8237fc5ce\"}]}}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"187df22a10f7507920841c65b28ef8d8237fc5ce\", \"got_revision_cp\": \"refs/heads/master@{#39313}\"}, \"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-1qwn\"], \"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\": [\"5757-fab9691\"], \"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": "1615539075372678",
"status": "COMPLETED",
"status_changed_ts": "1615539075709381",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18854",
"builder:infra-continuous-win10-64",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/187df22a10f7507920841c65b28ef8d8237fc5ce",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018413758372643200",
"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/8852983696366217632/+/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:523e23be2c4e8c10",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1615539733319518",
"url": "https://luci-milo-dev.appspot.com/b/8852983696366217632",
"utcnow_ts": "1615539733776467"
}
}
[09:02:13.787] Invocation finished in 1h38m7.664374876s with status SUCCEEDED