[06:14:23.572] New invocation is queued and will start shortly
[06:14:24.666] Starting the invocation (attempt 1)
[06:14:24.774] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:14:24.774] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:14:24.774] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026300080196391136",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"af0f1fcd0213efc089038fdbb7b14423b7af0220\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9026300080196391136",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/af0f1fcd0213efc089038fdbb7b14423b7af0220",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/af0f1fcd0213efc089038fdbb7b14423b7af0220",
"gitiles_ref:refs/heads/master"
]
}
[06:14:26.044] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608012864852874",
"id": "8860870018182864576",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"af0f1fcd0213efc089038fdbb7b14423b7af0220\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608012865320817",
"tags": [
"build_address:luci.chromium.ci/win32-official/1116",
"builder:win32-official",
"buildset:commit/git/af0f1fcd0213efc089038fdbb7b14423b7af0220",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/af0f1fcd0213efc089038fdbb7b14423b7af0220",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026300080196391136",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8860870018182864576/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1608012865320929",
"url": "https://ci.chromium.org/b/8860870018182864576",
"utcnow_ts": "1608012866024240"
}
}
[06:14:26.044] Task URL: https://ci.chromium.org/b/8860870018182864576
[06:14:26.044] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:2:0) after 1m0s
[06:15:02.115] Received PubSub notification, asking Buildbucket for the build status
[06:15:02.202] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:26.072] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:2:0)
[06:15:26.072] Timer tick, asking Buildbucket for the build status
[06:15:26.130] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:26.130] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:4:0) after 1m0s
[06:16:26.499] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:4:0)
[06:16:26.499] Timer tick, asking Buildbucket for the build status
[06:16:26.560] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:26.560] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:5:0) after 1m0s
[06:17:26.689] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:5:0)
[06:17:26.689] Timer tick, asking Buildbucket for the build status
[06:17:26.753] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:26.753] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:6:0) after 1m0s
[06:18:26.815] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:6:0)
[06:18:26.815] Timer tick, asking Buildbucket for the build status
[06:18:26.875] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:26.875] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:7:0) after 1m0s
[06:19:27.378] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:7:0)
[06:19:27.378] Timer tick, asking Buildbucket for the build status
[06:19:27.613] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:27.613] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:8:0) after 1m0s
[06:20:27.701] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:8:0)
[06:20:27.701] Timer tick, asking Buildbucket for the build status
[06:20:27.768] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:27.768] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:9:0) after 1m0s
[06:21:27.842] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:9:0)
[06:21:27.842] Timer tick, asking Buildbucket for the build status
[06:21:27.909] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:27.909] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:10:0) after 1m0s
[06:22:28.018] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:10:0)
[06:22:28.018] Timer tick, asking Buildbucket for the build status
[06:22:28.164] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:28.164] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:11:0) after 1m0s
[06:23:28.318] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:11:0)
[06:23:28.318] Timer tick, asking Buildbucket for the build status
[06:23:28.635] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:28.635] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:12:0) after 1m0s
[06:24:28.665] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:12:0)
[06:24:28.665] Timer tick, asking Buildbucket for the build status
[06:24:28.761] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:28.761] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:13:0) after 1m0s
[06:25:28.788] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:13:0)
[06:25:28.788] Timer tick, asking Buildbucket for the build status
[06:25:28.850] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:28.850] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:14:0) after 1m0s
[06:26:28.882] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:14:0)
[06:26:28.882] Timer tick, asking Buildbucket for the build status
[06:26:29.346] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:29.346] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:15:0) after 1m0s
[06:27:29.399] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:15:0)
[06:27:29.399] Timer tick, asking Buildbucket for the build status
[06:27:29.467] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:29.467] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:16:0) after 1m0s
[06:28:29.550] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:16:0)
[06:28:29.550] Timer tick, asking Buildbucket for the build status
[06:28:29.730] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:29.730] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:17:0) after 1m0s
[06:29:29.805] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:17:0)
[06:29:29.805] Timer tick, asking Buildbucket for the build status
[06:29:29.923] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:29.923] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:18:0) after 1m0s
[06:30:29.977] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:18:0)
[06:30:29.977] Timer tick, asking Buildbucket for the build status
[06:30:30.055] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:30.055] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:19:0) after 1m0s
[06:31:30.162] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:19:0)
[06:31:30.162] Timer tick, asking Buildbucket for the build status
[06:31:30.315] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:30.315] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:20:0) after 1m0s
[06:32:30.380] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:20:0)
[06:32:30.380] Timer tick, asking Buildbucket for the build status
[06:32:30.704] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:30.704] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:21:0) after 1m0s
[06:33:30.723] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:21:0)
[06:33:30.723] Timer tick, asking Buildbucket for the build status
[06:33:30.782] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:30.782] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:22:0) after 1m0s
[06:34:30.789] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:22:0)
[06:34:30.789] Timer tick, asking Buildbucket for the build status
[06:34:30.950] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:30.950] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:23:0) after 1m0s
[06:35:31.021] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:23:0)
[06:35:31.021] Timer tick, asking Buildbucket for the build status
[06:35:31.172] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:31.172] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:24:0) after 1m0s
[06:36:31.204] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:24:0)
[06:36:31.204] Timer tick, asking Buildbucket for the build status
[06:36:31.307] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:31.307] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:25:0) after 1m0s
[06:37:31.405] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:25:0)
[06:37:31.405] Timer tick, asking Buildbucket for the build status
[06:37:31.478] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:31.478] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:26:0) after 1m0s
[06:38:31.506] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:26:0)
[06:38:31.506] Timer tick, asking Buildbucket for the build status
[06:38:31.563] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:31.563] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:27:0) after 1m0s
[06:39:31.613] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:27:0)
[06:39:31.613] Timer tick, asking Buildbucket for the build status
[06:39:31.739] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:31.739] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:28:0) after 1m0s
[06:40:31.760] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:28:0)
[06:40:31.760] Timer tick, asking Buildbucket for the build status
[06:40:31.820] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:31.820] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:29:0) after 1m0s
[06:41:31.961] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:29:0)
[06:41:31.961] Timer tick, asking Buildbucket for the build status
[06:41:32.034] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:32.034] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:30:0) after 1m0s
[06:42:32.139] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:30:0)
[06:42:32.139] Timer tick, asking Buildbucket for the build status
[06:42:32.208] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:32.208] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:31:0) after 1m0s
[06:43:32.316] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:31:0)
[06:43:32.316] Timer tick, asking Buildbucket for the build status
[06:43:32.377] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:32.377] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:32:0) after 1m0s
[06:44:32.464] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:32:0)
[06:44:32.464] Timer tick, asking Buildbucket for the build status
[06:44:32.563] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:32.563] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:33:0) after 1m0s
[06:45:32.558] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:33:0)
[06:45:32.558] Timer tick, asking Buildbucket for the build status
[06:45:32.616] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:32.616] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:34:0) after 1m0s
[06:46:32.653] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:34:0)
[06:46:32.653] Timer tick, asking Buildbucket for the build status
[06:46:32.722] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:32.722] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:35:0) after 1m0s
[06:47:32.787] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:35:0)
[06:47:32.787] Timer tick, asking Buildbucket for the build status
[06:47:32.904] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:32.904] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:36:0) after 1m0s
[06:48:32.924] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:36:0)
[06:48:32.924] Timer tick, asking Buildbucket for the build status
[06:48:33.007] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:33.007] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:37:0) after 1m0s
[06:49:33.034] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:37:0)
[06:49:33.034] Timer tick, asking Buildbucket for the build status
[06:49:34.472] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:34.472] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:38:0) after 1m0s
[06:50:34.540] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:38:0)
[06:50:34.540] Timer tick, asking Buildbucket for the build status
[06:50:34.671] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:34.671] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:39:0) after 1m0s
[06:51:34.684] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:39:0)
[06:51:34.684] Timer tick, asking Buildbucket for the build status
[06:51:34.742] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:34.742] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:40:0) after 1m0s
[06:52:34.838] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:40:0)
[06:52:34.838] Timer tick, asking Buildbucket for the build status
[06:52:34.904] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:34.904] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:41:0) after 1m0s
[06:53:34.945] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:41:0)
[06:53:34.945] Timer tick, asking Buildbucket for the build status
[06:53:35.001] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:35.001] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:42:0) after 1m0s
[06:54:35.057] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:42:0)
[06:54:35.057] Timer tick, asking Buildbucket for the build status
[06:54:35.165] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:35.165] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:43:0) after 1m0s
[06:55:35.272] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:43:0)
[06:55:35.272] Timer tick, asking Buildbucket for the build status
[06:55:35.324] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:35.324] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:44:0) after 1m0s
[06:56:35.367] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:44:0)
[06:56:35.367] Timer tick, asking Buildbucket for the build status
[06:56:35.428] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:35.428] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:45:0) after 1m0s
[06:57:35.451] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:45:0)
[06:57:35.451] Timer tick, asking Buildbucket for the build status
[06:57:35.509] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:35.509] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:46:0) after 1m0s
[06:58:35.531] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:46:0)
[06:58:35.531] Timer tick, asking Buildbucket for the build status
[06:58:35.625] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:35.625] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:47:0) after 1m0s
[06:59:35.854] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:47:0)
[06:59:35.854] Timer tick, asking Buildbucket for the build status
[06:59:35.954] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:35.954] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:48:0) after 1m0s
[07:00:36.084] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:48:0)
[07:00:36.084] Timer tick, asking Buildbucket for the build status
[07:00:36.187] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:36.187] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:49:0) after 1m0s
[07:01:36.363] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:49:0)
[07:01:36.363] Timer tick, asking Buildbucket for the build status
[07:01:36.493] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:36.493] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:50:0) after 1m0s
[07:02:36.645] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:50:0)
[07:02:36.645] Timer tick, asking Buildbucket for the build status
[07:02:36.717] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:36.717] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:51:0) after 1m0s
[07:03:36.761] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:51:0)
[07:03:36.761] Timer tick, asking Buildbucket for the build status
[07:03:36.840] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:36.840] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:52:0) after 1m0s
[07:04:36.847] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:52:0)
[07:04:36.847] Timer tick, asking Buildbucket for the build status
[07:04:36.950] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:36.950] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:53:0) after 1m0s
[07:05:36.999] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:53:0)
[07:05:36.999] Timer tick, asking Buildbucket for the build status
[07:05:37.067] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:37.067] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:54:0) after 1m0s
[07:06:37.102] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:54:0)
[07:06:37.102] Timer tick, asking Buildbucket for the build status
[07:06:37.222] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:37.222] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:55:0) after 1m0s
[07:07:37.214] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:55:0)
[07:07:37.214] Timer tick, asking Buildbucket for the build status
[07:07:37.286] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:37.286] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:56:0) after 1m0s
[07:08:37.317] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:56:0)
[07:08:37.317] Timer tick, asking Buildbucket for the build status
[07:08:37.376] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:37.376] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:57:0) after 1m0s
[07:09:37.396] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:57:0)
[07:09:37.396] Timer tick, asking Buildbucket for the build status
[07:09:37.844] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:37.844] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:58:0) after 1m0s
[07:10:37.846] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:58:0)
[07:10:37.846] Timer tick, asking Buildbucket for the build status
[07:10:38.038] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:38.038] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:59:0) after 1m0s
[07:11:38.071] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:59:0)
[07:11:38.071] Timer tick, asking Buildbucket for the build status
[07:11:38.132] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:38.132] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:60:0) after 1m0s
[07:12:38.154] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:60:0)
[07:12:38.154] Timer tick, asking Buildbucket for the build status
[07:12:39.013] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:39.014] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:61:0) after 1m0s
[07:13:40.121] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:61:0)
[07:13:40.121] Timer tick, asking Buildbucket for the build status
[07:13:40.187] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:40.187] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:62:0) after 1m0s
[07:14:40.462] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:62:0)
[07:14:40.462] Timer tick, asking Buildbucket for the build status
[07:14:40.580] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:40.580] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:63:0) after 1m0s
[07:15:40.782] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:63:0)
[07:15:40.782] Timer tick, asking Buildbucket for the build status
[07:15:40.844] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:40.844] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:64:0) after 1m0s
[07:16:41.109] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:64:0)
[07:16:41.109] Timer tick, asking Buildbucket for the build status
[07:16:41.194] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:41.194] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:65:0) after 1m0s
[07:17:41.397] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:65:0)
[07:17:41.397] Timer tick, asking Buildbucket for the build status
[07:17:41.511] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:41.511] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:66:0) after 1m0s
[07:18:41.683] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:66:0)
[07:18:41.683] Timer tick, asking Buildbucket for the build status
[07:18:41.834] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:41.834] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:67:0) after 1m0s
[07:19:42.082] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:67:0)
[07:19:42.082] Timer tick, asking Buildbucket for the build status
[07:19:42.184] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:42.184] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:68:0) after 1m0s
[07:20:42.608] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:68:0)
[07:20:42.608] Timer tick, asking Buildbucket for the build status
[07:20:42.686] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:42.686] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:69:0) after 1m0s
[07:21:42.811] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:69:0)
[07:21:42.811] Timer tick, asking Buildbucket for the build status
[07:21:42.868] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:42.868] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:70:0) after 1m0s
[07:22:42.955] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:70:0)
[07:22:42.955] Timer tick, asking Buildbucket for the build status
[07:22:43.013] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:43.013] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:71:0) after 1m0s
[07:23:43.102] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:71:0)
[07:23:43.102] Timer tick, asking Buildbucket for the build status
[07:23:43.221] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:43.221] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:72:0) after 1m0s
[07:24:43.284] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:72:0)
[07:24:43.284] Timer tick, asking Buildbucket for the build status
[07:24:43.386] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:43.386] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:73:0) after 1m0s
[07:25:43.550] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:73:0)
[07:25:43.550] Timer tick, asking Buildbucket for the build status
[07:25:43.614] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:43.614] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:74:0) after 1m0s
[07:26:44.327] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:74:0)
[07:26:44.327] Timer tick, asking Buildbucket for the build status
[07:26:44.386] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:44.386] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:75:0) after 1m0s
[07:27:44.455] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:75:0)
[07:27:44.455] Timer tick, asking Buildbucket for the build status
[07:27:44.540] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:44.540] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:76:0) after 1m0s
[07:28:44.571] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:76:0)
[07:28:44.571] Timer tick, asking Buildbucket for the build status
[07:28:44.641] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:44.641] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:77:0) after 1m0s
[07:29:44.787] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:77:0)
[07:29:44.787] Timer tick, asking Buildbucket for the build status
[07:29:44.880] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:44.880] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:78:0) after 1m0s
[07:30:45.014] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:78:0)
[07:30:45.014] Timer tick, asking Buildbucket for the build status
[07:30:45.123] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:45.123] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:79:0) after 1m0s
[07:31:45.274] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:79:0)
[07:31:45.274] Timer tick, asking Buildbucket for the build status
[07:31:45.335] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:45.335] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:80:0) after 1m0s
[07:32:45.470] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:80:0)
[07:32:45.470] Timer tick, asking Buildbucket for the build status
[07:32:45.538] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:45.538] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:81:0) after 1m0s
[07:33:45.593] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:81:0)
[07:33:45.593] Timer tick, asking Buildbucket for the build status
[07:33:45.651] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:45.651] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:82:0) after 1m0s
[07:34:45.740] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:82:0)
[07:34:45.740] Timer tick, asking Buildbucket for the build status
[07:34:45.851] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:45.851] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:83:0) after 1m0s
[07:35:45.894] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:83:0)
[07:35:45.894] Timer tick, asking Buildbucket for the build status
[07:35:46.003] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:46.003] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:84:0) after 1m0s
[07:36:46.087] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:84:0)
[07:36:46.087] Timer tick, asking Buildbucket for the build status
[07:36:46.139] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:46.139] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:85:0) after 1m0s
[07:37:46.278] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:85:0)
[07:37:46.278] Timer tick, asking Buildbucket for the build status
[07:37:46.409] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:46.409] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:86:0) after 1m0s
[07:38:46.503] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:86:0)
[07:38:46.503] Timer tick, asking Buildbucket for the build status
[07:38:46.568] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:46.569] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:87:0) after 1m0s
[07:39:46.618] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:87:0)
[07:39:46.618] Timer tick, asking Buildbucket for the build status
[07:39:46.731] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:46.731] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:88:0) after 1m0s
[07:40:46.758] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:88:0)
[07:40:46.758] Timer tick, asking Buildbucket for the build status
[07:40:46.840] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:46.840] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:89:0) after 1m0s
[07:41:46.845] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:89:0)
[07:41:46.845] Timer tick, asking Buildbucket for the build status
[07:41:46.908] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:46.908] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:90:0) after 1m0s
[07:42:46.958] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:90:0)
[07:42:46.958] Timer tick, asking Buildbucket for the build status
[07:42:47.026] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:47.026] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:91:0) after 1m0s
[07:43:47.093] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:91:0)
[07:43:47.093] Timer tick, asking Buildbucket for the build status
[07:43:47.158] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:47.158] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:92:0) after 1m0s
[07:44:47.294] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:92:0)
[07:44:47.294] Timer tick, asking Buildbucket for the build status
[07:44:47.397] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:47.398] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:93:0) after 1m0s
[07:45:47.468] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:93:0)
[07:45:47.468] Timer tick, asking Buildbucket for the build status
[07:45:47.549] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:47.549] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:94:0) after 1m0s
[07:46:47.619] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:94:0)
[07:46:47.619] Timer tick, asking Buildbucket for the build status
[07:46:47.675] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:47.675] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:95:0) after 1m0s
[07:47:47.678] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:95:0)
[07:47:47.678] Timer tick, asking Buildbucket for the build status
[07:47:48.017] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:48.017] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:96:0) after 1m0s
[07:48:48.119] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:96:0)
[07:48:48.119] Timer tick, asking Buildbucket for the build status
[07:48:48.179] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:48.179] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:97:0) after 1m0s
[07:49:48.197] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:97:0)
[07:49:48.197] Timer tick, asking Buildbucket for the build status
[07:49:48.502] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:48.502] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:98:0) after 1m0s
[07:50:48.601] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:98:0)
[07:50:48.601] Timer tick, asking Buildbucket for the build status
[07:50:48.662] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:48.662] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:99:0) after 1m0s
[07:51:48.741] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:99:0)
[07:51:48.741] Timer tick, asking Buildbucket for the build status
[07:51:48.789] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:48.789] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:100:0) after 1m0s
[07:52:48.809] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:100:0)
[07:52:48.809] Timer tick, asking Buildbucket for the build status
[07:52:48.866] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:48.866] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:101:0) after 1m0s
[07:53:48.892] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:101:0)
[07:53:48.892] Timer tick, asking Buildbucket for the build status
[07:53:49.504] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:49.504] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:102:0) after 1m0s
[07:54:49.618] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:102:0)
[07:54:49.618] Timer tick, asking Buildbucket for the build status
[07:54:50.115] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:50.115] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:103:0) after 1m0s
[07:55:50.128] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:103:0)
[07:55:50.128] Timer tick, asking Buildbucket for the build status
[07:55:50.192] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:50.192] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:104:0) after 1m0s
[07:56:50.260] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:104:0)
[07:56:50.260] Timer tick, asking Buildbucket for the build status
[07:56:50.334] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:50.334] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:105:0) after 1m0s
[07:57:50.380] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:105:0)
[07:57:50.380] Timer tick, asking Buildbucket for the build status
[07:57:50.705] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:50.705] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:106:0) after 1m0s
[07:58:50.745] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:106:0)
[07:58:50.745] Timer tick, asking Buildbucket for the build status
[07:58:50.814] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:50.814] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:107:0) after 1m0s
[07:59:50.812] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:107:0)
[07:59:50.812] Timer tick, asking Buildbucket for the build status
[07:59:50.927] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:50.927] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:108:0) after 1m0s
[08:00:51.001] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:108:0)
[08:00:51.001] Timer tick, asking Buildbucket for the build status
[08:00:51.068] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:51.069] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:109:0) after 1m0s
[08:01:51.108] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:109:0)
[08:01:51.108] Timer tick, asking Buildbucket for the build status
[08:01:51.189] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:51.189] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:110:0) after 1m0s
[08:02:51.244] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:110:0)
[08:02:51.244] Timer tick, asking Buildbucket for the build status
[08:02:51.322] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:51.322] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:111:0) after 1m0s
[08:03:51.395] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:111:0)
[08:03:51.395] Timer tick, asking Buildbucket for the build status
[08:03:51.459] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:51.459] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:112:0) after 1m0s
[08:04:51.483] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:112:0)
[08:04:51.483] Timer tick, asking Buildbucket for the build status
[08:04:51.587] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:51.587] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:113:0) after 1m0s
[08:05:51.622] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:113:0)
[08:05:51.622] Timer tick, asking Buildbucket for the build status
[08:05:51.732] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:51.732] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:114:0) after 1m0s
[08:06:51.756] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:114:0)
[08:06:51.756] Timer tick, asking Buildbucket for the build status
[08:06:51.815] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:51.815] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:115:0) after 1m0s
[08:07:51.852] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:115:0)
[08:07:51.852] Timer tick, asking Buildbucket for the build status
[08:07:52.078] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:52.078] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:116:0) after 1m0s
[08:08:52.117] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:116:0)
[08:08:52.117] Timer tick, asking Buildbucket for the build status
[08:08:52.308] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:52.308] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:117:0) after 1m0s
[08:09:52.326] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:117:0)
[08:09:52.326] Timer tick, asking Buildbucket for the build status
[08:09:52.405] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:52.405] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:118:0) after 1m0s
[08:10:52.400] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:118:0)
[08:10:52.400] Timer tick, asking Buildbucket for the build status
[08:10:52.529] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:52.529] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:119:0) after 1m0s
[08:11:52.590] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:119:0)
[08:11:52.590] Timer tick, asking Buildbucket for the build status
[08:11:52.655] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:52.655] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:120:0) after 1m0s
[08:12:52.706] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:120:0)
[08:12:52.706] Timer tick, asking Buildbucket for the build status
[08:12:52.774] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:52.774] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:121:0) after 1m0s
[08:13:52.840] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:121:0)
[08:13:52.840] Timer tick, asking Buildbucket for the build status
[08:13:52.915] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:52.915] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:122:0) after 1m0s
[08:14:52.955] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:122:0)
[08:14:52.955] Timer tick, asking Buildbucket for the build status
[08:14:53.069] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:53.069] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:123:0) after 1m0s
[08:15:53.136] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:123:0)
[08:15:53.136] Timer tick, asking Buildbucket for the build status
[08:15:53.398] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:53.398] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:124:0) after 1m0s
[08:16:53.499] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:124:0)
[08:16:53.499] Timer tick, asking Buildbucket for the build status
[08:16:53.617] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:53.617] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:125:0) after 1m0s
[08:17:53.639] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:125:0)
[08:17:53.639] Timer tick, asking Buildbucket for the build status
[08:17:53.720] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:53.720] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:126:0) after 1m0s
[08:18:53.795] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:126:0)
[08:18:53.795] Timer tick, asking Buildbucket for the build status
[08:18:53.866] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:53.866] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:127:0) after 1m0s
[08:19:53.889] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:127:0)
[08:19:53.889] Timer tick, asking Buildbucket for the build status
[08:19:54.153] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:54.153] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:128:0) after 1m0s
[08:20:54.167] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:128:0)
[08:20:54.167] Timer tick, asking Buildbucket for the build status
[08:20:54.247] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:54.247] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:129:0) after 1m0s
[08:21:54.276] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:129:0)
[08:21:54.276] Timer tick, asking Buildbucket for the build status
[08:21:54.347] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:54.347] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:130:0) after 1m0s
[08:22:54.361] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:130:0)
[08:22:54.361] Timer tick, asking Buildbucket for the build status
[08:22:54.421] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:54.421] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:131:0) after 1m0s
[08:23:54.467] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:131:0)
[08:23:54.467] Timer tick, asking Buildbucket for the build status
[08:23:54.546] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:54.546] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:132:0) after 1m0s
[08:24:54.640] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:132:0)
[08:24:54.640] Timer tick, asking Buildbucket for the build status
[08:24:54.744] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:54.744] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:133:0) after 1m0s
[08:25:54.800] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:133:0)
[08:25:54.800] Timer tick, asking Buildbucket for the build status
[08:25:54.857] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:54.857] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:134:0) after 1m0s
[08:26:55.005] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:134:0)
[08:26:55.005] Timer tick, asking Buildbucket for the build status
[08:26:55.105] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:55.105] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:135:0) after 1m0s
[08:27:55.181] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:135:0)
[08:27:55.181] Timer tick, asking Buildbucket for the build status
[08:27:55.441] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:55.441] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:136:0) after 1m0s
[08:28:55.465] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:136:0)
[08:28:55.465] Timer tick, asking Buildbucket for the build status
[08:28:55.703] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:55.703] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:137:0) after 1m0s
[08:29:55.677] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:137:0)
[08:29:55.677] Timer tick, asking Buildbucket for the build status
[08:29:55.830] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:55.830] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:138:0) after 1m0s
[08:30:55.856] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:138:0)
[08:30:55.856] Timer tick, asking Buildbucket for the build status
[08:30:55.934] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:55.934] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:139:0) after 1m0s
[08:31:55.986] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:139:0)
[08:31:55.986] Timer tick, asking Buildbucket for the build status
[08:31:56.049] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:56.049] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:140:0) after 1m0s
[08:32:56.076] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:140:0)
[08:32:56.077] Timer tick, asking Buildbucket for the build status
[08:32:56.144] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:56.144] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:141:0) after 1m0s
[08:33:56.161] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:141:0)
[08:33:56.161] Timer tick, asking Buildbucket for the build status
[08:33:56.247] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:56.248] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:142:0) after 1m0s
[08:34:56.267] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:142:0)
[08:34:56.267] Timer tick, asking Buildbucket for the build status
[08:34:56.494] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:56.494] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:143:0) after 1m0s
[08:35:56.527] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:143:0)
[08:35:56.527] Timer tick, asking Buildbucket for the build status
[08:35:56.579] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:56.579] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:144:0) after 1m0s
[08:36:56.634] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:144:0)
[08:36:56.634] Timer tick, asking Buildbucket for the build status
[08:36:57.013] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:57.013] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:145:0) after 1m0s
[08:37:57.100] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:145:0)
[08:37:57.100] Timer tick, asking Buildbucket for the build status
[08:37:57.172] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:57.172] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:146:0) after 1m0s
[08:38:57.238] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:146:0)
[08:38:57.238] Timer tick, asking Buildbucket for the build status
[08:38:57.289] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:57.289] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:147:0) after 1m0s
[08:39:57.364] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:147:0)
[08:39:57.364] Timer tick, asking Buildbucket for the build status
[08:39:57.470] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:57.470] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:148:0) after 1m0s
[08:40:57.543] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:148:0)
[08:40:57.543] Timer tick, asking Buildbucket for the build status
[08:40:57.615] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:57.615] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:149:0) after 1m0s
[08:41:57.731] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:149:0)
[08:41:57.731] Timer tick, asking Buildbucket for the build status
[08:41:57.797] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:57.797] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:150:0) after 1m0s
[08:42:57.872] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:150:0)
[08:42:57.872] Timer tick, asking Buildbucket for the build status
[08:42:57.974] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:57.974] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:151:0) after 1m0s
[08:43:58.097] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:151:0)
[08:43:58.097] Timer tick, asking Buildbucket for the build status
[08:43:58.179] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:58.179] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:152:0) after 1m0s
[08:44:58.231] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:152:0)
[08:44:58.231] Timer tick, asking Buildbucket for the build status
[08:44:58.644] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:58.644] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:153:0) after 1m0s
[08:45:58.700] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:153:0)
[08:45:58.700] Timer tick, asking Buildbucket for the build status
[08:45:58.856] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:58.856] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:154:0) after 1m0s
[08:46:58.871] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:154:0)
[08:46:58.871] Timer tick, asking Buildbucket for the build status
[08:46:58.945] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:58.945] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:155:0) after 1m0s
[08:47:58.944] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:155:0)
[08:47:58.944] Timer tick, asking Buildbucket for the build status
[08:47:59.065] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:59.065] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:156:0) after 1m0s
[08:48:59.084] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:156:0)
[08:48:59.084] Timer tick, asking Buildbucket for the build status
[08:48:59.251] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:59.251] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:157:0) after 1m0s
[08:49:59.285] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:157:0)
[08:49:59.285] Timer tick, asking Buildbucket for the build status
[08:49:59.368] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:59.368] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:158:0) after 1m0s
[08:50:59.406] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:158:0)
[08:50:59.406] Timer tick, asking Buildbucket for the build status
[08:50:59.508] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:59.508] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:159:0) after 1m0s
[08:51:59.561] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:159:0)
[08:51:59.561] Timer tick, asking Buildbucket for the build status
[08:51:59.628] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:59.628] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:160:0) after 1m0s
[08:52:59.655] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:160:0)
[08:52:59.655] Timer tick, asking Buildbucket for the build status
[08:53:00.406] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:00.406] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:161:0) after 1m0s
[08:54:00.427] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:161:0)
[08:54:00.427] Timer tick, asking Buildbucket for the build status
[08:54:00.640] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:00.640] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:162:0) after 1m0s
[08:55:00.724] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:162:0)
[08:55:00.724] Timer tick, asking Buildbucket for the build status
[08:55:01.204] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:01.204] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:163:0) after 1m0s
[08:56:01.239] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:163:0)
[08:56:01.239] Timer tick, asking Buildbucket for the build status
[08:56:01.451] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:01.451] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:164:0) after 1m0s
[08:57:01.485] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:164:0)
[08:57:01.485] Timer tick, asking Buildbucket for the build status
[08:57:01.836] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:01.836] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:165:0) after 1m0s
[08:58:01.859] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:165:0)
[08:58:01.859] Timer tick, asking Buildbucket for the build status
[08:58:01.927] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:01.927] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:166:0) after 1m0s
[08:59:01.959] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:166:0)
[08:59:01.959] Timer tick, asking Buildbucket for the build status
[08:59:04.777] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:04.777] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:167:0) after 1m0s
[09:00:04.755] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:167:0)
[09:00:04.755] Timer tick, asking Buildbucket for the build status
[09:00:04.884] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:04.884] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:168:0) after 1m0s
[09:01:05.646] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:168:0)
[09:01:05.646] Timer tick, asking Buildbucket for the build status
[09:01:05.725] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:05.725] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:169:0) after 1m0s
[09:02:05.858] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:169:0)
[09:02:05.858] Timer tick, asking Buildbucket for the build status
[09:02:05.936] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:05.936] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:170:0) after 1m0s
[09:03:05.985] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:170:0)
[09:03:05.985] Timer tick, asking Buildbucket for the build status
[09:03:06.059] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:06.059] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:171:0) after 1m0s
[09:04:06.112] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:171:0)
[09:04:06.112] Timer tick, asking Buildbucket for the build status
[09:04:06.231] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:06.231] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:172:0) after 1m0s
[09:05:06.325] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:172:0)
[09:05:06.325] Timer tick, asking Buildbucket for the build status
[09:05:06.665] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:06.665] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:173:0) after 1m0s
[09:06:06.720] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:173:0)
[09:06:06.720] Timer tick, asking Buildbucket for the build status
[09:06:06.826] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:06.826] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:174:0) after 1m0s
[09:07:06.931] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:174:0)
[09:07:06.931] Timer tick, asking Buildbucket for the build status
[09:07:07.057] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:07.057] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:175:0) after 1m0s
[09:08:07.110] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:175:0)
[09:08:07.110] Timer tick, asking Buildbucket for the build status
[09:08:07.226] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:07.226] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:176:0) after 1m0s
[09:09:07.268] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:176:0)
[09:09:07.268] Timer tick, asking Buildbucket for the build status
[09:09:07.369] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:07.369] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:177:0) after 1m0s
[09:10:07.413] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:177:0)
[09:10:07.413] Timer tick, asking Buildbucket for the build status
[09:10:07.513] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:07.513] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:178:0) after 1m0s
[09:11:07.558] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:178:0)
[09:11:07.558] Timer tick, asking Buildbucket for the build status
[09:11:07.634] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:07.634] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:179:0) after 1m0s
[09:12:07.686] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:179:0)
[09:12:07.686] Timer tick, asking Buildbucket for the build status
[09:12:07.743] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:07.743] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:180:0) after 1m0s
[09:13:07.821] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:180:0)
[09:13:07.821] Timer tick, asking Buildbucket for the build status
[09:13:07.887] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:07.887] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:181:0) after 1m0s
[09:14:07.979] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:181:0)
[09:14:07.979] Timer tick, asking Buildbucket for the build status
[09:14:08.077] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:08.077] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:182:0) after 1m0s
[09:15:08.175] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:182:0)
[09:15:08.175] Timer tick, asking Buildbucket for the build status
[09:15:08.278] Build 8860870018182864576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:08.278] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9026300080196391136:183:0) after 1m0s
[09:15:50.366] Received PubSub notification, asking Buildbucket for the build status
[09:15:50.414] Build 8860870018182864576: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:15:50.414] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608023749680244",
"created_by": "project:chromium",
"created_ts": "1608012864852874",
"id": "8860870018182864576",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"af0f1fcd0213efc089038fdbb7b14423b7af0220\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"af0f1fcd0213efc089038fdbb7b14423b7af0220\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-2-zd44\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 1116, \"got_angle_revision\": \"cb8903b14336d86e3d0889a367df60f417910872\", \"got_dawn_revision\": \"43c39a1ba42a1b1bc94337b11fe744fc36663693\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"af0f1fcd0213efc089038fdbb7b14423b7af0220\", \"got_revision_cp\": \"refs/heads/master@{#836981}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"fa44979d90aac8cca42e2fee711d44f23c9c4d67\", \"got_v8_revision\": \"8a79762d41dd3e32ad22991883d17e5036a66b73\", \"got_v8_revision_cp\": \"refs/heads/8.9.148@{#1}\", \"got_webrtc_revision\": \"d7808f1c464a07c8f1e2f97ec7ee92fda998d590\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32804}\", \"mirrored_builders\": [\"tryserver.chromium:win32-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"af0f1fcd0213efc089038fdbb7b14423b7af0220\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_f6b49936a2965b3cdad8f63ce13313d0c4b82119a9acfffbfe199e91c26d0c88_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-2-zd44\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608012901185351",
"status": "COMPLETED",
"status_changed_ts": "1608023750022277",
"tags": [
"build_address:luci.chromium.ci/win32-official/1116",
"builder:win32-official",
"buildset:commit/git/af0f1fcd0213efc089038fdbb7b14423b7af0220",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/af0f1fcd0213efc089038fdbb7b14423b7af0220",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026300080196391136",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8860870018182864576/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:507ddaaa1a751e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1608023750026306",
"url": "https://ci.chromium.org/b/8860870018182864576",
"utcnow_ts": "1608023750407865"
}
}
[09:15:50.414] Invocation finished in 3h1m26.865674433s with status SUCCEEDED