[02:00:08.077] New invocation is queued and will start shortly
[02:00:09.261] Starting the invocation (attempt 1)
[02:00:09.300] Popped gitiles commit info from properties and tags
[02:00:09.300] Popped a tag as redundant: buildset:commit/git/22a9c3b41a28b4a5ca1b061e950d5b307c49721c
[02:00:09.300] Popped a tag as redundant: buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/22a9c3b41a28b4a5ca1b061e950d5b307c49721c
[02:00:09.300] Popped a tag as redundant: gitiles_ref:refs/heads/master
[02:00:09.300] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[02:00:09.300] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[02:00:09.301] Buildbucket request:
{
"requestId": "9018524737623242512",
"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": "133f9b9fadb24a588f41ce7a1c190d956e840b76"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@133f9b9fadb24a588f41ce7a1c190d956e840b76",
"title": "133f9b9fadb24a588f41ce7a1c190d956e840b76",
"url": "https://chromium.googlesource.com/infra/infra/+/133f9b9fadb24a588f41ce7a1c190d956e840b76"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "f1d40dae9035aa9961f5a09976fe6eb8117f4e4a"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f1d40dae9035aa9961f5a09976fe6eb8117f4e4a",
"title": "f1d40dae9035aa9961f5a09976fe6eb8117f4e4a",
"url": "https://chromium.googlesource.com/infra/infra/+/f1d40dae9035aa9961f5a09976fe6eb8117f4e4a"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "02b742a3de6094523b1114ebf151f1b3fde9aa95"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@02b742a3de6094523b1114ebf151f1b3fde9aa95",
"title": "02b742a3de6094523b1114ebf151f1b3fde9aa95",
"url": "https://chromium.googlesource.com/infra/infra/+/02b742a3de6094523b1114ebf151f1b3fde9aa95"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "e1b51e805cdc8e2373f80c6b1302207a35c9b30c"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@e1b51e805cdc8e2373f80c6b1302207a35c9b30c",
"title": "e1b51e805cdc8e2373f80c6b1302207a35c9b30c",
"url": "https://chromium.googlesource.com/infra/infra/+/e1b51e805cdc8e2373f80c6b1302207a35c9b30c"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "22a9c3b41a28b4a5ca1b061e950d5b307c49721c"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@22a9c3b41a28b4a5ca1b061e950d5b307c49721c",
"title": "22a9c3b41a28b4a5ca1b061e950d5b307c49721c",
"url": "https://chromium.googlesource.com/infra/infra/+/22a9c3b41a28b4a5ca1b061e950d5b307c49721c"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "22a9c3b41a28b4a5ca1b061e950d5b307c49721c",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9018524737623242512"
},
{
"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"
}
}
[02:00:09.900] Scheduled build:
{
"id": "8853094675544950720",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 18837,
"createdBy": "project:infra",
"createTime": "2021-03-11T02:00:09.427607Z",
"updateTime": "2021-03-11T02:00:09.830195Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "22a9c3b41a28b4a5ca1b061e950d5b307c49721c",
"ref": "refs/heads/master"
}
},
"canary": true
}
[02:00:09.900] Task URL: https://cr-buildbucket-dev.appspot.com/build/8853094675544950720
[02:00:09.900] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:2:0) after 1m0s
[02:01:09.988] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:2:0)
[02:01:09.988] Timer tick, asking Buildbucket for the build status
[02:01:10.102] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:01:10.102] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:3:0) after 1m0s
[02:02:10.122] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:3:0)
[02:02:10.122] Timer tick, asking Buildbucket for the build status
[02:02:10.200] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:02:10.200] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:4:0) after 1m0s
[02:03:10.293] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:4:0)
[02:03:10.293] Timer tick, asking Buildbucket for the build status
[02:03:10.378] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:03:10.378] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:5:0) after 1m0s
[02:04:10.395] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:5:0)
[02:04:10.395] Timer tick, asking Buildbucket for the build status
[02:04:10.475] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:04:10.475] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:6:0) after 1m0s
[02:05:10.563] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:6:0)
[02:05:10.563] Timer tick, asking Buildbucket for the build status
[02:05:10.646] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:05:10.646] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:7:0) after 1m0s
[02:06:10.662] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:7:0)
[02:06:10.662] Timer tick, asking Buildbucket for the build status
[02:06:10.734] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:06:10.734] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:8:0) after 1m0s
[02:07:10.786] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:8:0)
[02:07:10.786] Timer tick, asking Buildbucket for the build status
[02:07:10.868] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:07:10.868] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:9:0) after 1m0s
[02:08:10.890] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:9:0)
[02:08:10.890] Timer tick, asking Buildbucket for the build status
[02:08:11.072] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:08:11.072] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:10:0) after 1m0s
[02:09:11.158] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:10:0)
[02:09:11.158] Timer tick, asking Buildbucket for the build status
[02:09:11.252] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:09:11.252] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:11:0) after 1m0s
[02:10:11.287] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:11:0)
[02:10:11.287] Timer tick, asking Buildbucket for the build status
[02:10:11.358] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:10:11.358] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:12:0) after 1m0s
[02:11:11.384] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:12:0)
[02:11:11.384] Timer tick, asking Buildbucket for the build status
[02:11:11.465] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:11:11.465] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:13:0) after 1m0s
[02:12:11.487] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:13:0)
[02:12:11.487] Timer tick, asking Buildbucket for the build status
[02:12:11.575] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:12:11.575] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:14:0) after 1m0s
[02:13:11.602] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:14:0)
[02:13:11.602] Timer tick, asking Buildbucket for the build status
[02:13:11.863] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:13:11.863] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:15:0) after 1m0s
[02:14:11.909] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:15:0)
[02:14:11.909] Timer tick, asking Buildbucket for the build status
[02:14:12.001] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:14:12.001] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:16:0) after 1m0s
[02:15:12.086] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:16:0)
[02:15:12.086] Timer tick, asking Buildbucket for the build status
[02:15:12.176] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:15:12.176] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:17:0) after 1m0s
[02:16:12.214] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:17:0)
[02:16:12.214] Timer tick, asking Buildbucket for the build status
[02:16:12.292] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:16:12.292] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:18:0) after 1m0s
[02:17:12.315] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:18:0)
[02:17:12.315] Timer tick, asking Buildbucket for the build status
[02:17:12.437] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:17:12.437] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:19:0) after 1m0s
[02:18:12.484] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:19:0)
[02:18:12.484] Timer tick, asking Buildbucket for the build status
[02:18:12.629] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:18:12.629] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:20:0) after 1m0s
[02:19:12.720] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:20:0)
[02:19:12.720] Timer tick, asking Buildbucket for the build status
[02:19:12.836] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:19:12.836] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:21:0) after 1m0s
[02:20:12.890] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:21:0)
[02:20:12.890] Timer tick, asking Buildbucket for the build status
[02:20:12.974] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:20:12.974] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:22:0) after 1m0s
[02:21:12.993] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:22:0)
[02:21:12.993] Timer tick, asking Buildbucket for the build status
[02:21:13.077] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:21:13.077] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:23:0) after 1m0s
[02:22:13.156] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:23:0)
[02:22:13.156] Timer tick, asking Buildbucket for the build status
[02:22:13.227] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:22:13.227] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:24:0) after 1m0s
[02:23:13.303] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:24:0)
[02:23:13.303] Timer tick, asking Buildbucket for the build status
[02:23:13.456] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:23:13.456] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:25:0) after 1m0s
[02:24:13.498] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:25:0)
[02:24:13.498] Timer tick, asking Buildbucket for the build status
[02:24:13.591] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:24:13.591] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:26:0) after 1m0s
[02:25:13.659] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:26:0)
[02:25:13.659] Timer tick, asking Buildbucket for the build status
[02:25:13.723] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:25:13.723] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:27:0) after 1m0s
[02:26:13.784] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:27:0)
[02:26:13.784] Timer tick, asking Buildbucket for the build status
[02:26:13.857] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:26:13.857] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:28:0) after 1m0s
[02:27:13.881] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:28:0)
[02:27:13.881] Timer tick, asking Buildbucket for the build status
[02:27:14.028] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:27:14.028] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:29:0) after 1m0s
[02:28:14.052] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:29:0)
[02:28:14.052] Timer tick, asking Buildbucket for the build status
[02:28:14.156] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:28:14.156] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:30:0) after 1m0s
[02:29:14.187] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:30:0)
[02:29:14.187] Timer tick, asking Buildbucket for the build status
[02:29:14.324] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:29:14.324] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:31:0) after 1m0s
[02:30:14.343] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:31:0)
[02:30:14.343] Timer tick, asking Buildbucket for the build status
[02:30:14.428] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:30:14.428] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:32:0) after 1m0s
[02:31:14.485] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:32:0)
[02:31:14.485] Timer tick, asking Buildbucket for the build status
[02:31:14.554] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:31:14.554] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:33:0) after 1m0s
[02:32:14.587] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:33:0)
[02:32:14.587] Timer tick, asking Buildbucket for the build status
[02:32:14.737] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:32:14.737] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:34:0) after 1m0s
[02:33:14.784] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:34:0)
[02:33:14.784] Timer tick, asking Buildbucket for the build status
[02:33:14.978] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:33:14.978] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:35:0) after 1m0s
[02:34:15.057] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:35:0)
[02:34:15.057] Timer tick, asking Buildbucket for the build status
[02:34:15.197] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:34:15.197] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:36:0) after 1m0s
[02:35:15.229] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:36:0)
[02:35:15.229] Timer tick, asking Buildbucket for the build status
[02:35:15.324] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:35:15.324] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:37:0) after 1m0s
[02:36:15.350] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:37:0)
[02:36:15.351] Timer tick, asking Buildbucket for the build status
[02:36:15.454] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:36:15.454] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:38:0) after 1m0s
[02:37:15.556] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:38:0)
[02:37:15.556] Timer tick, asking Buildbucket for the build status
[02:37:15.663] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:37:15.663] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:39:0) after 1m0s
[02:38:15.690] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:39:0)
[02:38:15.690] Timer tick, asking Buildbucket for the build status
[02:38:15.830] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:38:15.830] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:40:0) after 1m0s
[02:39:15.857] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:40:0)
[02:39:15.858] Timer tick, asking Buildbucket for the build status
[02:39:15.959] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:39:15.959] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:41:0) after 1m0s
[02:40:15.986] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:41:0)
[02:40:15.986] Timer tick, asking Buildbucket for the build status
[02:40:16.077] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:40:16.077] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:42:0) after 1m0s
[02:41:16.099] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:42:0)
[02:41:16.099] Timer tick, asking Buildbucket for the build status
[02:41:17.126] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:41:17.126] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:43:0) after 1m0s
[02:42:17.168] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:43:0)
[02:42:17.168] Timer tick, asking Buildbucket for the build status
[02:42:17.276] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:42:17.276] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:44:0) after 1m0s
[02:43:17.389] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:44:0)
[02:43:17.389] Timer tick, asking Buildbucket for the build status
[02:43:17.482] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:43:17.482] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:45:0) after 1m0s
[02:44:17.558] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:45:0)
[02:44:17.558] Timer tick, asking Buildbucket for the build status
[02:44:17.665] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:44:17.666] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:46:0) after 1m0s
[02:45:17.682] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:46:0)
[02:45:17.682] Timer tick, asking Buildbucket for the build status
[02:45:17.806] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:45:17.806] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:47:0) after 1m0s
[02:46:17.855] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:47:0)
[02:46:17.855] Timer tick, asking Buildbucket for the build status
[02:46:17.961] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:46:17.961] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:48:0) after 1m0s
[02:47:17.986] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:48:0)
[02:47:17.986] Timer tick, asking Buildbucket for the build status
[02:47:18.079] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:47:18.079] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:49:0) after 1m0s
[02:48:18.155] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:49:0)
[02:48:18.155] Timer tick, asking Buildbucket for the build status
[02:48:18.241] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:48:18.241] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:50:0) after 1m0s
[02:49:18.265] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:50:0)
[02:49:18.265] Timer tick, asking Buildbucket for the build status
[02:49:18.372] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:49:18.372] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:51:0) after 1m0s
[02:50:18.391] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:51:0)
[02:50:18.391] Timer tick, asking Buildbucket for the build status
[02:50:18.557] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:50:18.557] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:52:0) after 1m0s
[02:51:18.660] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:52:0)
[02:51:18.660] Timer tick, asking Buildbucket for the build status
[02:51:18.774] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:51:18.774] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:53:0) after 1m0s
[02:52:18.800] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:53:0)
[02:52:18.800] Timer tick, asking Buildbucket for the build status
[02:52:18.922] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:52:18.922] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:54:0) after 1m0s
[02:53:19.002] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:54:0)
[02:53:19.002] Timer tick, asking Buildbucket for the build status
[02:53:19.119] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:53:19.119] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:55:0) after 1m0s
[02:54:19.195] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:55:0)
[02:54:19.195] Timer tick, asking Buildbucket for the build status
[02:54:19.280] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:54:19.280] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:56:0) after 1m0s
[02:55:19.370] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:56:0)
[02:55:19.370] Timer tick, asking Buildbucket for the build status
[02:55:19.447] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:55:19.447] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:57:0) after 1m0s
[02:56:19.503] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:57:0)
[02:56:19.503] Timer tick, asking Buildbucket for the build status
[02:56:19.638] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:56:19.638] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:58:0) after 1m0s
[02:57:19.679] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:58:0)
[02:57:19.679] Timer tick, asking Buildbucket for the build status
[02:57:19.773] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:57:19.773] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:59:0) after 1m0s
[02:58:19.857] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:59:0)
[02:58:19.857] Timer tick, asking Buildbucket for the build status
[02:58:19.934] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:58:19.934] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:60:0) after 1m0s
[02:59:19.971] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:60:0)
[02:59:19.971] Timer tick, asking Buildbucket for the build status
[02:59:20.082] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:59:20.082] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:61:0) after 1m0s
[03:00:20.156] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:61:0)
[03:00:20.156] Timer tick, asking Buildbucket for the build status
[03:00:20.250] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:00:20.250] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:62:0) after 1m0s
[03:01:20.362] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:62:0)
[03:01:20.362] Timer tick, asking Buildbucket for the build status
[03:01:20.439] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:01:20.439] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:63:0) after 1m0s
[03:02:20.466] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:63:0)
[03:02:20.466] Timer tick, asking Buildbucket for the build status
[03:02:20.550] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:02:20.550] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:64:0) after 1m0s
[03:03:20.657] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:64:0)
[03:03:20.657] Timer tick, asking Buildbucket for the build status
[03:03:20.736] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:03:20.736] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:65:0) after 1m0s
[03:04:20.786] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:65:0)
[03:04:20.786] Timer tick, asking Buildbucket for the build status
[03:04:20.858] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:04:20.858] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:66:0) after 1m0s
[03:05:20.966] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:66:0)
[03:05:20.966] Timer tick, asking Buildbucket for the build status
[03:05:21.077] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:05:21.077] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:67:0) after 1m0s
[03:06:21.167] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:67:0)
[03:06:21.167] Timer tick, asking Buildbucket for the build status
[03:06:21.240] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:06:21.240] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:68:0) after 1m0s
[03:07:21.287] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:68:0)
[03:07:21.287] Timer tick, asking Buildbucket for the build status
[03:07:21.357] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:07:21.358] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:69:0) after 1m0s
[03:08:21.380] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:69:0)
[03:08:21.380] Timer tick, asking Buildbucket for the build status
[03:08:21.457] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:08:21.457] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:70:0) after 1m0s
[03:09:21.488] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:70:0)
[03:09:21.488] Timer tick, asking Buildbucket for the build status
[03:09:21.561] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:09:21.561] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:71:0) after 1m0s
[03:10:21.660] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:71:0)
[03:10:21.660] Timer tick, asking Buildbucket for the build status
[03:10:21.731] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:10:21.731] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:72:0) after 1m0s
[03:11:21.789] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:72:0)
[03:11:21.789] Timer tick, asking Buildbucket for the build status
[03:11:21.933] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:11:21.933] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:73:0) after 1m0s
[03:12:21.978] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:73:0)
[03:12:21.978] Timer tick, asking Buildbucket for the build status
[03:12:22.354] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:12:22.354] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:74:0) after 1m0s
[03:13:22.458] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:74:0)
[03:13:22.458] Timer tick, asking Buildbucket for the build status
[03:13:22.537] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:13:22.537] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:75:0) after 1m0s
[03:14:22.587] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:75:0)
[03:14:22.587] Timer tick, asking Buildbucket for the build status
[03:14:22.666] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:14:22.666] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:76:0) after 1m0s
[03:15:22.690] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:76:0)
[03:15:22.690] Timer tick, asking Buildbucket for the build status
[03:15:22.765] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:15:22.765] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:77:0) after 1m0s
[03:16:22.791] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:77:0)
[03:16:22.791] Timer tick, asking Buildbucket for the build status
[03:16:22.952] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:16:22.952] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:78:0) after 1m0s
[03:17:23.073] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:78:0)
[03:17:23.073] Timer tick, asking Buildbucket for the build status
[03:17:23.163] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:17:23.163] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:79:0) after 1m0s
[03:18:23.274] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:79:0)
[03:18:23.274] Timer tick, asking Buildbucket for the build status
[03:18:23.357] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:18:23.357] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:80:0) after 1m0s
[03:19:23.395] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:80:0)
[03:19:23.395] Timer tick, asking Buildbucket for the build status
[03:19:23.476] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:19:23.476] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:81:0) after 1m0s
[03:20:23.505] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:81:0)
[03:20:23.505] Timer tick, asking Buildbucket for the build status
[03:20:23.581] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:20:23.581] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:82:0) after 1m0s
[03:21:23.687] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:82:0)
[03:21:23.687] Timer tick, asking Buildbucket for the build status
[03:21:23.769] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:21:23.769] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:83:0) after 1m0s
[03:22:23.870] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:83:0)
[03:22:23.871] Timer tick, asking Buildbucket for the build status
[03:22:23.972] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:22:23.972] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:84:0) after 1m0s
[03:23:24.082] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:84:0)
[03:23:24.082] Timer tick, asking Buildbucket for the build status
[03:23:24.226] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:23:24.226] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:85:0) after 1m0s
[03:24:24.288] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:85:0)
[03:24:24.288] Timer tick, asking Buildbucket for the build status
[03:24:24.360] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:24:24.360] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:86:0) after 1m0s
[03:25:24.387] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:86:0)
[03:25:24.387] Timer tick, asking Buildbucket for the build status
[03:25:24.465] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:25:24.465] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:87:0) after 1m0s
[03:26:24.488] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:87:0)
[03:26:24.488] Timer tick, asking Buildbucket for the build status
[03:26:24.556] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:26:24.556] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:88:0) after 1m0s
[03:27:24.662] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:88:0)
[03:27:24.662] Timer tick, asking Buildbucket for the build status
[03:27:24.770] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:27:24.770] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:89:0) after 1m0s
[03:28:24.796] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:89:0)
[03:28:24.796] Timer tick, asking Buildbucket for the build status
[03:28:24.878] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:28:24.878] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:90:0) after 1m0s
[03:29:24.900] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:90:0)
[03:29:24.900] Timer tick, asking Buildbucket for the build status
[03:29:24.975] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:29:24.975] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:91:0) after 1m0s
[03:30:24.996] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:91:0)
[03:30:24.996] Timer tick, asking Buildbucket for the build status
[03:30:25.068] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:30:25.068] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:92:0) after 1m0s
[03:31:25.162] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:92:0)
[03:31:25.162] Timer tick, asking Buildbucket for the build status
[03:31:25.241] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:31:25.241] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:93:0) after 1m0s
[03:32:25.263] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:93:0)
[03:32:25.263] Timer tick, asking Buildbucket for the build status
[03:32:25.349] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:32:25.349] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:94:0) after 1m0s
[03:33:25.392] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:94:0)
[03:33:25.392] Timer tick, asking Buildbucket for the build status
[03:33:25.464] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:33:25.464] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:95:0) after 1m0s
[03:34:25.568] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:95:0)
[03:34:25.568] Timer tick, asking Buildbucket for the build status
[03:34:25.648] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:34:25.648] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:96:0) after 1m0s
[03:35:25.678] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:96:0)
[03:35:25.678] Timer tick, asking Buildbucket for the build status
[03:35:25.760] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:35:25.760] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:97:0) after 1m0s
[03:36:25.892] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:97:0)
[03:36:25.892] Timer tick, asking Buildbucket for the build status
[03:36:25.967] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:36:25.967] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:98:0) after 1m0s
[03:37:26.068] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:98:0)
[03:37:26.068] Timer tick, asking Buildbucket for the build status
[03:37:26.152] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:37:26.152] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:99:0) after 1m0s
[03:38:26.209] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:99:0)
[03:38:26.209] Timer tick, asking Buildbucket for the build status
[03:38:26.283] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:38:26.283] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:100:0) after 1m0s
[03:39:26.319] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:100:0)
[03:39:26.319] Timer tick, asking Buildbucket for the build status
[03:39:26.403] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:39:26.403] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:101:0) after 1m0s
[03:40:26.428] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:101:0)
[03:40:26.428] Timer tick, asking Buildbucket for the build status
[03:40:26.578] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:40:26.578] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:102:0) after 1m0s
[03:41:26.549] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:102:0)
[03:41:26.549] Timer tick, asking Buildbucket for the build status
[03:41:27.055] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:41:27.055] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:103:0) after 1m0s
[03:42:27.159] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:103:0)
[03:42:27.159] Timer tick, asking Buildbucket for the build status
[03:42:27.329] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:42:27.329] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:104:0) after 1m0s
[03:43:27.400] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:104:0)
[03:43:27.400] Timer tick, asking Buildbucket for the build status
[03:43:27.567] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:43:27.567] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:105:0) after 1m0s
[03:44:27.613] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:105:0)
[03:44:27.613] Timer tick, asking Buildbucket for the build status
[03:44:27.831] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:44:27.831] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:106:0) after 1m0s
[03:45:27.891] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:106:0)
[03:45:27.891] Timer tick, asking Buildbucket for the build status
[03:45:28.153] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:45:28.153] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:107:0) after 1m0s
[03:46:28.209] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:107:0)
[03:46:28.209] Timer tick, asking Buildbucket for the build status
[03:46:28.395] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:46:28.395] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:108:0) after 1m0s
[03:47:28.415] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:108:0)
[03:47:28.415] Timer tick, asking Buildbucket for the build status
[03:47:28.585] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:47:28.585] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:109:0) after 1m0s
[03:48:28.704] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:109:0)
[03:48:28.704] Timer tick, asking Buildbucket for the build status
[03:48:28.945] Build 8853094675544950720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:48:28.945] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:110:0) after 1m0s
[03:49:14.198] Received PubSub notification, asking Buildbucket for the build status
[03:49:14.351] Build 8853094675544950720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:28.974] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:110:0)
[03:49:28.974] Timer tick, asking Buildbucket for the build status
[03:49:29.157] Build 8853094675544950720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:29.158] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:112:0) after 1m0s
[03:50:29.195] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:112:0)
[03:50:29.195] Timer tick, asking Buildbucket for the build status
[03:50:29.358] Build 8853094675544950720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:29.358] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:113:0) after 1m0s
[03:51:29.393] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:113:0)
[03:51:29.393] Timer tick, asking Buildbucket for the build status
[03:51:29.569] Build 8853094675544950720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:29.569] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:114:0) after 1m0s
[03:52:29.595] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:114:0)
[03:52:29.595] Timer tick, asking Buildbucket for the build status
[03:52:29.867] Build 8853094675544950720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:29.867] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:115:0) after 1m0s
[03:53:29.960] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:115:0)
[03:53:29.960] Timer tick, asking Buildbucket for the build status
[03:53:30.127] Build 8853094675544950720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:30.127] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:116:0) after 1m0s
[03:54:30.166] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:116:0)
[03:54:30.166] Timer tick, asking Buildbucket for the build status
[03:54:30.347] Build 8853094675544950720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:30.347] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:117:0) after 1m0s
[03:55:30.370] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:117:0)
[03:55:30.370] Timer tick, asking Buildbucket for the build status
[03:55:30.631] Build 8853094675544950720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:30.631] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:118:0) after 1m0s
[03:56:30.652] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:118:0)
[03:56:30.652] Timer tick, asking Buildbucket for the build status
[03:56:30.866] Build 8853094675544950720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:30.866] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:119:0) after 1m0s
[03:57:30.973] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:119:0)
[03:57:30.973] Timer tick, asking Buildbucket for the build status
[03:57:31.242] Build 8853094675544950720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:31.242] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:120:0) after 1m0s
[03:58:31.263] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:120:0)
[03:58:31.263] Timer tick, asking Buildbucket for the build status
[03:58:31.450] Build 8853094675544950720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:31.450] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:121:0) after 1m0s
[03:59:31.576] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:121:0)
[03:59:31.576] Timer tick, asking Buildbucket for the build status
[03:59:31.649] Build 8853094675544950720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:31.649] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:122:0) after 1m0s
[04:00:31.759] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:122:0)
[04:00:31.759] Timer tick, asking Buildbucket for the build status
[04:00:31.983] Build 8853094675544950720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:31.983] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9018524737623242512:123:0) after 1m0s
[04:00:52.188] Received PubSub notification, asking Buildbucket for the build status
[04:00:52.322] Build 8853094675544950720: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:00:52.322] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1615435251687493",
"created_by": "project:infra",
"created_ts": "1615428009427607",
"id": "8853094675544950720",
"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\": \"133f9b9fadb24a588f41ce7a1c190d956e840b76\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@133f9b9fadb24a588f41ce7a1c190d956e840b76\", \"title\": \"133f9b9fadb24a588f41ce7a1c190d956e840b76\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/133f9b9fadb24a588f41ce7a1c190d956e840b76\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"f1d40dae9035aa9961f5a09976fe6eb8117f4e4a\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f1d40dae9035aa9961f5a09976fe6eb8117f4e4a\", \"title\": \"f1d40dae9035aa9961f5a09976fe6eb8117f4e4a\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/f1d40dae9035aa9961f5a09976fe6eb8117f4e4a\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"02b742a3de6094523b1114ebf151f1b3fde9aa95\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@02b742a3de6094523b1114ebf151f1b3fde9aa95\", \"title\": \"02b742a3de6094523b1114ebf151f1b3fde9aa95\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/02b742a3de6094523b1114ebf151f1b3fde9aa95\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"e1b51e805cdc8e2373f80c6b1302207a35c9b30c\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@e1b51e805cdc8e2373f80c6b1302207a35c9b30c\", \"title\": \"e1b51e805cdc8e2373f80c6b1302207a35c9b30c\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/e1b51e805cdc8e2373f80c6b1302207a35c9b30c\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"22a9c3b41a28b4a5ca1b061e950d5b307c49721c\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@22a9c3b41a28b4a5ca1b061e950d5b307c49721c\", \"title\": \"22a9c3b41a28b4a5ca1b061e950d5b307c49721c\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/22a9c3b41a28b4a5ca1b061e950d5b307c49721c\"}]}}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"22a9c3b41a28b4a5ca1b061e950d5b307c49721c\", \"got_revision_cp\": \"refs/heads/master@{#39241}\"}, \"swarming\": {\"bot_dimensions\": {\"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-gljc\"], \"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": "1615434552489695",
"status": "COMPLETED",
"status_changed_ts": "1615434552944476",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/18837",
"builder:infra-continuous-win10-64",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/22a9c3b41a28b4a5ca1b061e950d5b307c49721c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018524737623242512",
"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/8853094675544950720/+/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:5237d4c8721a4310",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1615435251688135",
"url": "https://luci-milo-dev.appspot.com/b/8853094675544950720",
"utcnow_ts": "1615435252314846"
}
}
[04:00:52.322] Invocation finished in 2h0m44.26090684s with status SUCCEEDED