[02:34:22.232] New invocation is queued and will start shortly
[02:34:23.279] Starting the invocation (attempt 1)
[02:34:23.319] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:34:23.319] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:34:23.319] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029575413591911360",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0774d32e38efe38775736880019c8e721a83dc04\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9029575413591911360",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/0774d32e38efe38775736880019c8e721a83dc04",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0774d32e38efe38775736880019c8e721a83dc04",
"gitiles_ref:refs/heads/master"
]
}
[02:34:24.084] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604889263406339",
"id": "8864145351693523184",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0774d32e38efe38775736880019c8e721a83dc04\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604889263902427",
"tags": [
"build_address:luci.chromium.ci/win-official/993",
"builder:win-official",
"buildset:commit/git/0774d32e38efe38775736880019c8e721a83dc04",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0774d32e38efe38775736880019c8e721a83dc04",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029575413591911360",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8864145351693523184/+/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": "1604889263902873",
"url": "https://ci.chromium.org/b/8864145351693523184",
"utcnow_ts": "1604889264074150"
}
}
[02:34:24.084] Task URL: https://ci.chromium.org/b/8864145351693523184
[02:34:24.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:2:0) after 1m0s
[02:35:16.252] Received PubSub notification, asking Buildbucket for the build status
[02:35:16.422] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:24.210] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:2:0)
[02:35:24.210] Timer tick, asking Buildbucket for the build status
[02:35:24.473] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:24.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:4:0) after 1m0s
[02:36:24.582] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:4:0)
[02:36:24.582] Timer tick, asking Buildbucket for the build status
[02:36:24.925] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:24.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:5:0) after 1m0s
[02:37:24.987] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:5:0)
[02:37:24.987] Timer tick, asking Buildbucket for the build status
[02:37:25.067] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:25.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:6:0) after 1m0s
[02:38:25.126] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:6:0)
[02:38:25.126] Timer tick, asking Buildbucket for the build status
[02:38:25.225] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:25.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:7:0) after 1m0s
[02:39:25.303] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:7:0)
[02:39:25.303] Timer tick, asking Buildbucket for the build status
[02:39:25.455] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:25.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:8:0) after 1m0s
[02:40:25.538] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:8:0)
[02:40:25.538] Timer tick, asking Buildbucket for the build status
[02:40:25.739] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:25.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:9:0) after 1m0s
[02:41:25.749] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:9:0)
[02:41:25.749] Timer tick, asking Buildbucket for the build status
[02:41:25.866] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:25.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:10:0) after 1m0s
[02:42:25.939] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:10:0)
[02:42:25.939] Timer tick, asking Buildbucket for the build status
[02:42:26.223] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:26.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:11:0) after 1m0s
[02:43:26.279] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:11:0)
[02:43:26.279] Timer tick, asking Buildbucket for the build status
[02:43:26.364] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:26.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:12:0) after 1m0s
[02:44:26.388] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:12:0)
[02:44:26.388] Timer tick, asking Buildbucket for the build status
[02:44:26.516] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:26.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:13:0) after 1m0s
[02:45:26.538] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:13:0)
[02:45:26.538] Timer tick, asking Buildbucket for the build status
[02:45:26.838] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:26.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:14:0) after 1m0s
[02:46:26.861] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:14:0)
[02:46:26.861] Timer tick, asking Buildbucket for the build status
[02:46:26.951] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:26.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:15:0) after 1m0s
[02:47:26.984] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:15:0)
[02:47:26.984] Timer tick, asking Buildbucket for the build status
[02:47:27.085] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:27.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:16:0) after 1m0s
[02:48:27.078] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:16:0)
[02:48:27.078] Timer tick, asking Buildbucket for the build status
[02:48:27.148] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:27.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:17:0) after 1m0s
[02:49:27.195] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:17:0)
[02:49:27.195] Timer tick, asking Buildbucket for the build status
[02:49:27.505] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:27.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:18:0) after 1m0s
[02:50:27.608] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:18:0)
[02:50:27.609] Timer tick, asking Buildbucket for the build status
[02:50:27.692] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:27.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:19:0) after 1m0s
[02:51:27.730] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:19:0)
[02:51:27.730] Timer tick, asking Buildbucket for the build status
[02:51:27.797] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:27.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:20:0) after 1m0s
[02:52:27.802] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:20:0)
[02:52:27.802] Timer tick, asking Buildbucket for the build status
[02:52:27.870] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:27.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:21:0) after 1m0s
[02:53:27.900] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:21:0)
[02:53:27.900] Timer tick, asking Buildbucket for the build status
[02:53:27.982] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:27.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:22:0) after 1m0s
[02:54:28.064] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:22:0)
[02:54:28.064] Timer tick, asking Buildbucket for the build status
[02:54:28.189] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:28.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:23:0) after 1m0s
[02:55:28.230] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:23:0)
[02:55:28.230] Timer tick, asking Buildbucket for the build status
[02:55:28.636] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:28.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:24:0) after 1m0s
[02:56:28.846] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:24:0)
[02:56:28.846] Timer tick, asking Buildbucket for the build status
[02:56:29.008] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:29.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:25:0) after 1m0s
[02:57:29.041] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:25:0)
[02:57:29.041] Timer tick, asking Buildbucket for the build status
[02:57:29.121] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:29.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:26:0) after 1m0s
[02:58:29.286] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:26:0)
[02:58:29.286] Timer tick, asking Buildbucket for the build status
[02:58:29.359] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:29.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:27:0) after 1m0s
[02:59:29.424] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:27:0)
[02:59:29.424] Timer tick, asking Buildbucket for the build status
[02:59:29.536] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:29.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:28:0) after 1m0s
[03:00:29.671] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:28:0)
[03:00:29.671] Timer tick, asking Buildbucket for the build status
[03:00:29.783] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:29.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:29:0) after 1m0s
[03:01:29.785] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:29:0)
[03:01:29.785] Timer tick, asking Buildbucket for the build status
[03:01:29.843] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:29.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:30:0) after 1m0s
[03:02:30.990] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:30:0)
[03:02:30.990] Timer tick, asking Buildbucket for the build status
[03:02:31.126] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:31.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:31:0) after 1m0s
[03:03:32.381] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:31:0)
[03:03:32.381] Timer tick, asking Buildbucket for the build status
[03:03:32.451] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:32.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:32:0) after 1m0s
[03:04:32.997] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:32:0)
[03:04:32.997] Timer tick, asking Buildbucket for the build status
[03:04:33.108] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:33.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:33:0) after 1m0s
[03:05:33.217] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:33:0)
[03:05:33.217] Timer tick, asking Buildbucket for the build status
[03:05:33.279] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:33.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:34:0) after 1m0s
[03:06:33.583] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:34:0)
[03:06:33.583] Timer tick, asking Buildbucket for the build status
[03:06:33.656] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:33.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:35:0) after 1m0s
[03:07:33.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:35:0)
[03:07:33.957] Timer tick, asking Buildbucket for the build status
[03:07:34.171] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:34.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:36:0) after 1m0s
[03:08:34.327] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:36:0)
[03:08:34.327] Timer tick, asking Buildbucket for the build status
[03:08:34.450] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:34.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:37:0) after 1m0s
[03:09:34.526] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:37:0)
[03:09:34.526] Timer tick, asking Buildbucket for the build status
[03:09:34.670] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:34.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:38:0) after 1m0s
[03:10:34.845] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:38:0)
[03:10:34.845] Timer tick, asking Buildbucket for the build status
[03:10:34.928] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:34.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:39:0) after 1m0s
[03:11:35.042] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:39:0)
[03:11:35.042] Timer tick, asking Buildbucket for the build status
[03:11:35.120] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:35.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:40:0) after 1m0s
[03:12:35.147] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:40:0)
[03:12:35.147] Timer tick, asking Buildbucket for the build status
[03:12:35.381] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:35.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:41:0) after 1m0s
[03:13:35.500] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:41:0)
[03:13:35.500] Timer tick, asking Buildbucket for the build status
[03:13:35.681] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:35.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:42:0) after 1m0s
[03:14:35.754] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:42:0)
[03:14:35.754] Timer tick, asking Buildbucket for the build status
[03:14:35.872] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:35.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:43:0) after 1m0s
[03:15:35.927] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:43:0)
[03:15:35.927] Timer tick, asking Buildbucket for the build status
[03:15:36.024] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:36.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:44:0) after 1m0s
[03:16:36.116] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:44:0)
[03:16:36.116] Timer tick, asking Buildbucket for the build status
[03:16:36.185] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:36.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:45:0) after 1m0s
[03:17:36.401] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:45:0)
[03:17:36.401] Timer tick, asking Buildbucket for the build status
[03:17:36.479] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:36.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:46:0) after 1m0s
[03:18:36.642] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:46:0)
[03:18:36.642] Timer tick, asking Buildbucket for the build status
[03:18:36.794] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:36.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:47:0) after 1m0s
[03:19:37.065] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:47:0)
[03:19:37.065] Timer tick, asking Buildbucket for the build status
[03:19:37.364] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:37.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:48:0) after 1m0s
[03:20:37.412] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:48:0)
[03:20:37.412] Timer tick, asking Buildbucket for the build status
[03:20:37.520] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:37.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:49:0) after 1m0s
[03:21:37.701] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:49:0)
[03:21:37.701] Timer tick, asking Buildbucket for the build status
[03:21:37.765] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:37.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:50:0) after 1m0s
[03:22:37.877] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:50:0)
[03:22:37.877] Timer tick, asking Buildbucket for the build status
[03:22:38.017] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:38.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:51:0) after 1m0s
[03:23:38.030] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:51:0)
[03:23:38.030] Timer tick, asking Buildbucket for the build status
[03:23:38.339] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:38.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:52:0) after 1m0s
[03:24:38.404] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:52:0)
[03:24:38.404] Timer tick, asking Buildbucket for the build status
[03:24:38.532] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:38.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:53:0) after 1m0s
[03:25:38.633] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:53:0)
[03:25:38.633] Timer tick, asking Buildbucket for the build status
[03:25:38.701] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:38.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:54:0) after 1m0s
[03:26:38.743] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:54:0)
[03:26:38.743] Timer tick, asking Buildbucket for the build status
[03:26:38.803] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:38.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:55:0) after 1m0s
[03:27:38.926] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:55:0)
[03:27:38.926] Timer tick, asking Buildbucket for the build status
[03:27:38.993] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:38.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:56:0) after 1m0s
[03:28:39.076] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:56:0)
[03:28:39.076] Timer tick, asking Buildbucket for the build status
[03:28:39.202] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:39.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:57:0) after 1m0s
[03:29:39.314] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:57:0)
[03:29:39.314] Timer tick, asking Buildbucket for the build status
[03:29:39.425] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:39.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:58:0) after 1m0s
[03:30:39.486] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:58:0)
[03:30:39.486] Timer tick, asking Buildbucket for the build status
[03:30:39.547] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:39.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:59:0) after 1m0s
[03:31:39.572] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:59:0)
[03:31:39.572] Timer tick, asking Buildbucket for the build status
[03:31:39.665] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:39.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:60:0) after 1m0s
[03:32:39.692] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:60:0)
[03:32:39.692] Timer tick, asking Buildbucket for the build status
[03:32:40.015] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:40.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:61:0) after 1m0s
[03:33:40.040] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:61:0)
[03:33:40.040] Timer tick, asking Buildbucket for the build status
[03:33:40.134] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:40.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:62:0) after 1m0s
[03:34:40.172] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:62:0)
[03:34:40.172] Timer tick, asking Buildbucket for the build status
[03:34:43.034] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:43.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:63:0) after 1m0s
[03:35:43.084] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:63:0)
[03:35:43.084] Timer tick, asking Buildbucket for the build status
[03:35:43.243] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:43.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:64:0) after 1m0s
[03:36:43.280] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:64:0)
[03:36:43.280] Timer tick, asking Buildbucket for the build status
[03:36:43.360] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:43.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:65:0) after 1m0s
[03:37:43.423] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:65:0)
[03:37:43.423] Timer tick, asking Buildbucket for the build status
[03:37:43.682] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:43.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:66:0) after 1m0s
[03:38:43.743] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:66:0)
[03:38:43.743] Timer tick, asking Buildbucket for the build status
[03:38:43.807] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:43.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:67:0) after 1m0s
[03:39:43.838] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:67:0)
[03:39:43.838] Timer tick, asking Buildbucket for the build status
[03:39:43.955] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:43.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:68:0) after 1m0s
[03:40:43.982] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:68:0)
[03:40:43.982] Timer tick, asking Buildbucket for the build status
[03:40:44.097] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:44.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:69:0) after 1m0s
[03:41:44.132] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:69:0)
[03:41:44.132] Timer tick, asking Buildbucket for the build status
[03:41:44.202] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:44.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:70:0) after 1m0s
[03:42:44.341] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:70:0)
[03:42:44.341] Timer tick, asking Buildbucket for the build status
[03:42:46.233] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:46.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:71:0) after 1m0s
[03:43:46.318] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:71:0)
[03:43:46.318] Timer tick, asking Buildbucket for the build status
[03:43:46.434] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:46.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:72:0) after 1m0s
[03:44:46.533] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:72:0)
[03:44:46.533] Timer tick, asking Buildbucket for the build status
[03:44:46.691] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:46.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:73:0) after 1m0s
[03:45:46.726] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:73:0)
[03:45:46.726] Timer tick, asking Buildbucket for the build status
[03:45:46.798] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:46.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:74:0) after 1m0s
[03:46:46.908] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:74:0)
[03:46:46.908] Timer tick, asking Buildbucket for the build status
[03:46:46.999] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:46.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:75:0) after 1m0s
[03:47:47.045] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:75:0)
[03:47:47.045] Timer tick, asking Buildbucket for the build status
[03:47:47.142] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:47.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:76:0) after 1m0s
[03:48:47.196] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:76:0)
[03:48:47.196] Timer tick, asking Buildbucket for the build status
[03:48:47.360] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:47.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:77:0) after 1m0s
[03:49:47.457] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:77:0)
[03:49:47.457] Timer tick, asking Buildbucket for the build status
[03:49:47.563] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:47.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:78:0) after 1m0s
[03:50:47.736] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:78:0)
[03:50:47.736] Timer tick, asking Buildbucket for the build status
[03:50:48.125] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:48.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:79:0) after 1m0s
[03:51:48.147] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:79:0)
[03:51:48.147] Timer tick, asking Buildbucket for the build status
[03:51:48.288] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:48.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:80:0) after 1m0s
[03:52:48.352] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:80:0)
[03:52:48.352] Timer tick, asking Buildbucket for the build status
[03:52:48.448] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:48.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:81:0) after 1m0s
[03:53:48.531] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:81:0)
[03:53:48.531] Timer tick, asking Buildbucket for the build status
[03:53:48.597] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:48.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:82:0) after 1m0s
[03:54:48.614] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:82:0)
[03:54:48.614] Timer tick, asking Buildbucket for the build status
[03:54:48.723] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:48.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:83:0) after 1m0s
[03:55:48.770] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:83:0)
[03:55:48.770] Timer tick, asking Buildbucket for the build status
[03:55:48.842] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:48.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:84:0) after 1m0s
[03:56:48.910] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:84:0)
[03:56:48.910] Timer tick, asking Buildbucket for the build status
[03:56:48.984] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:48.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:85:0) after 1m0s
[03:57:49.133] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:85:0)
[03:57:49.133] Timer tick, asking Buildbucket for the build status
[03:57:49.204] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:49.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:86:0) after 1m0s
[03:58:49.283] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:86:0)
[03:58:49.283] Timer tick, asking Buildbucket for the build status
[03:58:49.349] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:49.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:87:0) after 1m0s
[03:59:49.399] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:87:0)
[03:59:49.399] Timer tick, asking Buildbucket for the build status
[03:59:49.668] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:49.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:88:0) after 1m0s
[04:00:49.670] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:88:0)
[04:00:49.670] Timer tick, asking Buildbucket for the build status
[04:00:49.893] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:49.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:89:0) after 1m0s
[04:01:50.173] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:89:0)
[04:01:50.174] Timer tick, asking Buildbucket for the build status
[04:01:50.539] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:50.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:90:0) after 1m0s
[04:02:50.674] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:90:0)
[04:02:50.674] Timer tick, asking Buildbucket for the build status
[04:02:51.279] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:51.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:91:0) after 1m0s
[04:03:51.480] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:91:0)
[04:03:51.480] Timer tick, asking Buildbucket for the build status
[04:03:51.596] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:51.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:92:0) after 1m0s
[04:04:51.692] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:92:0)
[04:04:51.692] Timer tick, asking Buildbucket for the build status
[04:04:51.802] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:51.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:93:0) after 1m0s
[04:05:51.905] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:93:0)
[04:05:51.905] Timer tick, asking Buildbucket for the build status
[04:05:51.976] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:51.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:94:0) after 1m0s
[04:06:52.043] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:94:0)
[04:06:52.043] Timer tick, asking Buildbucket for the build status
[04:06:52.129] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:52.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:95:0) after 1m0s
[04:07:52.193] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:95:0)
[04:07:52.193] Timer tick, asking Buildbucket for the build status
[04:07:52.266] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:52.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:96:0) after 1m0s
[04:08:52.314] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:96:0)
[04:08:52.314] Timer tick, asking Buildbucket for the build status
[04:08:52.413] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:52.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:97:0) after 1m0s
[04:09:52.430] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:97:0)
[04:09:52.430] Timer tick, asking Buildbucket for the build status
[04:09:52.630] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:52.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:98:0) after 1m0s
[04:10:52.694] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:98:0)
[04:10:52.694] Timer tick, asking Buildbucket for the build status
[04:10:52.929] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:52.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:99:0) after 1m0s
[04:11:58.480] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:99:0)
[04:11:58.480] Timer tick, asking Buildbucket for the build status
[04:11:58.569] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:58.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:100:0) after 1m0s
[04:12:58.732] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:100:0)
[04:12:58.732] Timer tick, asking Buildbucket for the build status
[04:12:58.837] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:58.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:101:0) after 1m0s
[04:13:59.697] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:101:0)
[04:13:59.697] Timer tick, asking Buildbucket for the build status
[04:13:59.784] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:59.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:102:0) after 1m0s
[04:14:59.840] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:102:0)
[04:14:59.840] Timer tick, asking Buildbucket for the build status
[04:14:59.978] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:59.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:103:0) after 1m0s
[04:16:00.053] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:103:0)
[04:16:00.053] Timer tick, asking Buildbucket for the build status
[04:16:00.278] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:00.278] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:104:0) after 1m0s
[04:17:00.358] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:104:0)
[04:17:00.358] Timer tick, asking Buildbucket for the build status
[04:17:00.457] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:00.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:105:0) after 1m0s
[04:18:00.666] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:105:0)
[04:18:00.666] Timer tick, asking Buildbucket for the build status
[04:18:00.745] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:00.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:106:0) after 1m0s
[04:19:00.807] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:106:0)
[04:19:00.807] Timer tick, asking Buildbucket for the build status
[04:19:00.872] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:00.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:107:0) after 1m0s
[04:20:01.301] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:107:0)
[04:20:01.301] Timer tick, asking Buildbucket for the build status
[04:20:01.509] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:01.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:108:0) after 1m0s
[04:21:01.664] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:108:0)
[04:21:01.664] Timer tick, asking Buildbucket for the build status
[04:21:01.729] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:01.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:109:0) after 1m0s
[04:22:02.056] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:109:0)
[04:22:02.056] Timer tick, asking Buildbucket for the build status
[04:22:02.126] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:02.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:110:0) after 1m0s
[04:23:02.386] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:110:0)
[04:23:02.386] Timer tick, asking Buildbucket for the build status
[04:23:02.639] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:02.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:111:0) after 1m0s
[04:24:02.807] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:111:0)
[04:24:02.807] Timer tick, asking Buildbucket for the build status
[04:24:02.877] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:02.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:112:0) after 1m0s
[04:25:03.032] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:112:0)
[04:25:03.032] Timer tick, asking Buildbucket for the build status
[04:25:03.135] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:03.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:113:0) after 1m0s
[04:26:03.270] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:113:0)
[04:26:03.270] Timer tick, asking Buildbucket for the build status
[04:26:03.566] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:03.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:114:0) after 1m0s
[04:27:03.659] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:114:0)
[04:27:03.659] Timer tick, asking Buildbucket for the build status
[04:27:03.721] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:03.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:115:0) after 1m0s
[04:28:03.810] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:115:0)
[04:28:03.810] Timer tick, asking Buildbucket for the build status
[04:28:03.867] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:03.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:116:0) after 1m0s
[04:29:03.904] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:116:0)
[04:29:03.904] Timer tick, asking Buildbucket for the build status
[04:29:03.969] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:03.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:117:0) after 1m0s
[04:30:04.082] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:117:0)
[04:30:04.082] Timer tick, asking Buildbucket for the build status
[04:30:04.218] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:04.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:118:0) after 1m0s
[04:31:04.380] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:118:0)
[04:31:04.380] Timer tick, asking Buildbucket for the build status
[04:31:04.450] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:04.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:119:0) after 1m0s
[04:32:04.602] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:119:0)
[04:32:04.602] Timer tick, asking Buildbucket for the build status
[04:32:04.807] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:04.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:120:0) after 1m0s
[04:33:05.149] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:120:0)
[04:33:05.149] Timer tick, asking Buildbucket for the build status
[04:33:05.228] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:05.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:121:0) after 1m0s
[04:34:05.522] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:121:0)
[04:34:05.522] Timer tick, asking Buildbucket for the build status
[04:34:05.593] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:05.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:122:0) after 1m0s
[04:35:05.780] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:122:0)
[04:35:05.780] Timer tick, asking Buildbucket for the build status
[04:35:06.190] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:06.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:123:0) after 1m0s
[04:36:06.275] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:123:0)
[04:36:06.275] Timer tick, asking Buildbucket for the build status
[04:36:06.397] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:06.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:124:0) after 1m0s
[04:37:06.673] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:124:0)
[04:37:06.673] Timer tick, asking Buildbucket for the build status
[04:37:06.734] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:06.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:125:0) after 1m0s
[04:38:06.863] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:125:0)
[04:38:06.863] Timer tick, asking Buildbucket for the build status
[04:38:06.943] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:06.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:126:0) after 1m0s
[04:39:07.148] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:126:0)
[04:39:07.148] Timer tick, asking Buildbucket for the build status
[04:39:07.219] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:07.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:127:0) after 1m0s
[04:40:07.303] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:127:0)
[04:40:07.303] Timer tick, asking Buildbucket for the build status
[04:40:07.402] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:07.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:128:0) after 1m0s
[04:41:07.462] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:128:0)
[04:41:07.462] Timer tick, asking Buildbucket for the build status
[04:41:07.574] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:07.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:129:0) after 1m0s
[04:42:07.619] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:129:0)
[04:42:07.619] Timer tick, asking Buildbucket for the build status
[04:42:07.677] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:07.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:130:0) after 1m0s
[04:43:07.790] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:130:0)
[04:43:07.790] Timer tick, asking Buildbucket for the build status
[04:43:07.861] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:07.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:131:0) after 1m0s
[04:44:07.908] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:131:0)
[04:44:07.908] Timer tick, asking Buildbucket for the build status
[04:44:08.125] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:08.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:132:0) after 1m0s
[04:45:08.217] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:132:0)
[04:45:08.217] Timer tick, asking Buildbucket for the build status
[04:45:08.315] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:08.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:133:0) after 1m0s
[04:46:08.367] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:133:0)
[04:46:08.367] Timer tick, asking Buildbucket for the build status
[04:46:08.531] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:08.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:134:0) after 1m0s
[04:47:08.592] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:134:0)
[04:47:08.592] Timer tick, asking Buildbucket for the build status
[04:47:08.682] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:08.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:135:0) after 1m0s
[04:48:08.759] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:135:0)
[04:48:08.759] Timer tick, asking Buildbucket for the build status
[04:48:08.863] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:08.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:136:0) after 1m0s
[04:49:08.948] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:136:0)
[04:49:08.948] Timer tick, asking Buildbucket for the build status
[04:49:09.176] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:09.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:137:0) after 1m0s
[04:50:09.261] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:137:0)
[04:50:09.261] Timer tick, asking Buildbucket for the build status
[04:50:09.370] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:09.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:138:0) after 1m0s
[04:51:09.420] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:138:0)
[04:51:09.420] Timer tick, asking Buildbucket for the build status
[04:51:09.515] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:09.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:139:0) after 1m0s
[04:52:09.604] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:139:0)
[04:52:09.604] Timer tick, asking Buildbucket for the build status
[04:52:09.761] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:09.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:140:0) after 1m0s
[04:53:09.765] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:140:0)
[04:53:09.765] Timer tick, asking Buildbucket for the build status
[04:53:09.886] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:09.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:141:0) after 1m0s
[04:54:09.973] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:141:0)
[04:54:09.973] Timer tick, asking Buildbucket for the build status
[04:54:10.025] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:10.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:142:0) after 1m0s
[04:55:10.125] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:142:0)
[04:55:10.125] Timer tick, asking Buildbucket for the build status
[04:55:10.526] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:10.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:143:0) after 1m0s
[04:56:10.658] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:143:0)
[04:56:10.658] Timer tick, asking Buildbucket for the build status
[04:56:10.727] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:10.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:144:0) after 1m0s
[04:57:10.802] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:144:0)
[04:57:10.802] Timer tick, asking Buildbucket for the build status
[04:57:10.859] Build 8864145351693523184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:10.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029575413591911360:145:0) after 1m0s
[04:57:46.591] Received PubSub notification, asking Buildbucket for the build status
[04:57:46.672] Build 8864145351693523184: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:57:46.672] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604897865605945",
"created_by": "project:chromium",
"created_ts": "1604889263406339",
"id": "8864145351693523184",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0774d32e38efe38775736880019c8e721a83dc04\"}}",
"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\": \"0774d32e38efe38775736880019c8e721a83dc04\", \"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-1-xnxg\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 993, \"got_angle_revision\": \"d4439824c55428a699aa543a2f397d06a00841b6\", \"got_dawn_revision\": \"3e17e33724b4d0b4c4fc1985340882e1f543e3a0\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"0774d32e38efe38775736880019c8e721a83dc04\", \"got_revision_cp\": \"refs/heads/master@{#825244}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"4ed9d3498dcffa987acba1a8007ff8dec336f263\", \"got_v8_revision\": \"4075f123295bb39d50e472b7af1fc04d4f4fe228\", \"got_v8_revision_cp\": \"refs/heads/8.8.250@{#1}\", \"got_webrtc_revision\": \"9c99b7964fabb16a022293ffbd2804f9b46551f6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32565}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0774d32e38efe38775736880019c8e721a83dc04\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-1-xnxg\"], \"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\": [\"5626-39642e9\"], \"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": "1604889315443946",
"status": "COMPLETED",
"status_changed_ts": "1604897866126471",
"tags": [
"build_address:luci.chromium.ci/win-official/993",
"builder:win-official",
"buildset:commit/git/0774d32e38efe38775736880019c8e721a83dc04",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0774d32e38efe38775736880019c8e721a83dc04",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029575413591911360",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8864145351693523184/+/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:4fc3ac487c6c6810",
"user_agent:luci-scheduler"
],
"updated_ts": "1604897866130917",
"url": "https://ci.chromium.org/b/8864145351693523184",
"utcnow_ts": "1604897866662216"
}
}
[04:57:46.672] Invocation finished in 2h23m24.459689585s with status SUCCEEDED