[23:45:50.545] New invocation is queued and will start shortly
[23:45:51.706] Starting the invocation (attempt 1)
[23:45:51.744] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:45:51.744] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:45:51.744] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027139898365395600",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"be8741c03da272232e3a888bc9fb91050086abb4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9027139898365395600",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/be8741c03da272232e3a888bc9fb91050086abb4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/be8741c03da272232e3a888bc9fb91050086abb4",
"gitiles_ref:refs/heads/master"
]
}
[23:45:52.653] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607211951855759",
"id": "8861709836329841296",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"be8741c03da272232e3a888bc9fb91050086abb4\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607211952475616",
"tags": [
"build_address:luci.chromium.ci/win32-official/1060",
"builder:win32-official",
"buildset:commit/git/be8741c03da272232e3a888bc9fb91050086abb4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/be8741c03da272232e3a888bc9fb91050086abb4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027139898365395600",
"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/8861709836329841296/+/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": "1607211952475775",
"url": "https://ci.chromium.org/b/8861709836329841296",
"utcnow_ts": "1607211952643255"
}
}
[23:45:52.653] Task URL: https://ci.chromium.org/b/8861709836329841296
[23:45:52.653] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:2:0) after 1m0s
[23:46:28.291] Received PubSub notification, asking Buildbucket for the build status
[23:46:29.008] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:52.676] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:2:0)
[23:46:52.676] Timer tick, asking Buildbucket for the build status
[23:46:52.811] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:52.811] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:4:0) after 1m0s
[23:47:52.887] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:4:0)
[23:47:52.887] Timer tick, asking Buildbucket for the build status
[23:47:53.018] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:53.018] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:5:0) after 1m0s
[23:48:53.098] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:5:0)
[23:48:53.098] Timer tick, asking Buildbucket for the build status
[23:48:53.228] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:53.228] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:6:0) after 1m0s
[23:49:53.260] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:6:0)
[23:49:53.260] Timer tick, asking Buildbucket for the build status
[23:49:53.338] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:53.338] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:7:0) after 1m0s
[23:50:53.356] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:7:0)
[23:50:53.356] Timer tick, asking Buildbucket for the build status
[23:50:53.789] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:53.790] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:8:0) after 1m0s
[23:51:53.862] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:8:0)
[23:51:53.862] Timer tick, asking Buildbucket for the build status
[23:51:54.195] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:54.195] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:9:0) after 1m0s
[23:52:54.215] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:9:0)
[23:52:54.215] Timer tick, asking Buildbucket for the build status
[23:52:55.027] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:55.027] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:10:0) after 1m0s
[23:53:55.057] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:10:0)
[23:53:55.057] Timer tick, asking Buildbucket for the build status
[23:53:55.169] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:55.169] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:11:0) after 1m0s
[23:54:55.227] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:11:0)
[23:54:55.227] Timer tick, asking Buildbucket for the build status
[23:54:58.225] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:58.225] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:12:0) after 1m0s
[23:55:58.236] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:12:0)
[23:55:58.236] Timer tick, asking Buildbucket for the build status
[23:55:58.407] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:58.407] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:13:0) after 1m0s
[23:56:58.459] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:13:0)
[23:56:58.459] Timer tick, asking Buildbucket for the build status
[23:56:58.615] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:58.615] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:14:0) after 1m0s
[23:57:58.638] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:14:0)
[23:57:58.638] Timer tick, asking Buildbucket for the build status
[23:57:58.900] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:58.900] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:15:0) after 1m0s
[23:58:59.093] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:15:0)
[23:58:59.093] Timer tick, asking Buildbucket for the build status
[23:58:59.170] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:59.170] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:16:0) after 1m0s
[23:59:59.276] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:16:0)
[23:59:59.276] Timer tick, asking Buildbucket for the build status
[23:59:59.430] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:59.430] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:17:0) after 1m0s
[00:00:59.508] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:17:0)
[00:00:59.508] Timer tick, asking Buildbucket for the build status
[00:01:00.261] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:00.261] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:18:0) after 1m0s
[00:02:00.413] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:18:0)
[00:02:00.413] Timer tick, asking Buildbucket for the build status
[00:02:00.606] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:00.606] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:19:0) after 1m0s
[00:03:01.249] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:19:0)
[00:03:01.249] Timer tick, asking Buildbucket for the build status
[00:03:02.215] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:02.215] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:20:0) after 1m0s
[00:04:02.324] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:20:0)
[00:04:02.324] Timer tick, asking Buildbucket for the build status
[00:04:02.488] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:02.488] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:21:0) after 1m0s
[00:05:02.517] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:21:0)
[00:05:02.517] Timer tick, asking Buildbucket for the build status
[00:05:07.566] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:07.566] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:22:0) after 1m0s
[00:06:07.749] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:22:0)
[00:06:07.749] Timer tick, asking Buildbucket for the build status
[00:06:07.851] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:07.851] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:23:0) after 1m0s
[00:07:07.928] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:23:0)
[00:07:07.928] Timer tick, asking Buildbucket for the build status
[00:07:08.031] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:08.031] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:24:0) after 1m0s
[00:08:08.119] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:24:0)
[00:08:08.119] Timer tick, asking Buildbucket for the build status
[00:08:08.227] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:08.227] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:25:0) after 1m0s
[00:09:08.252] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:25:0)
[00:09:08.252] Timer tick, asking Buildbucket for the build status
[00:09:08.356] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:08.356] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:26:0) after 1m0s
[00:10:08.347] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:26:0)
[00:10:08.347] Timer tick, asking Buildbucket for the build status
[00:10:08.790] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:08.790] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:27:0) after 1m0s
[00:11:08.863] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:27:0)
[00:11:08.863] Timer tick, asking Buildbucket for the build status
[00:11:09.013] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:09.013] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:28:0) after 1m0s
[00:12:09.143] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:28:0)
[00:12:09.143] Timer tick, asking Buildbucket for the build status
[00:12:09.248] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:09.248] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:29:0) after 1m0s
[00:13:09.422] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:29:0)
[00:13:09.422] Timer tick, asking Buildbucket for the build status
[00:13:09.591] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:09.591] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:30:0) after 1m0s
[00:14:09.774] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:30:0)
[00:14:09.774] Timer tick, asking Buildbucket for the build status
[00:14:09.985] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:09.985] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:31:0) after 1m0s
[00:15:10.096] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:31:0)
[00:15:10.096] Timer tick, asking Buildbucket for the build status
[00:15:10.237] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:10.237] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:32:0) after 1m0s
[00:16:10.342] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:32:0)
[00:16:10.342] Timer tick, asking Buildbucket for the build status
[00:16:10.904] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:10.904] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:33:0) after 1m0s
[00:17:10.959] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:33:0)
[00:17:10.959] Timer tick, asking Buildbucket for the build status
[00:17:11.781] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:11.781] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:34:0) after 1m0s
[00:18:11.900] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:34:0)
[00:18:11.900] Timer tick, asking Buildbucket for the build status
[00:18:12.013] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:12.013] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:35:0) after 1m0s
[00:19:12.262] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:35:0)
[00:19:12.262] Timer tick, asking Buildbucket for the build status
[00:19:15.200] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:15.200] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:36:0) after 1m0s
[00:20:15.245] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:36:0)
[00:20:15.245] Timer tick, asking Buildbucket for the build status
[00:20:15.368] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:15.368] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:37:0) after 1m0s
[00:21:15.499] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:37:0)
[00:21:15.499] Timer tick, asking Buildbucket for the build status
[00:21:16.676] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:16.676] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:38:0) after 1m0s
[00:22:16.910] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:38:0)
[00:22:16.910] Timer tick, asking Buildbucket for the build status
[00:22:17.193] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:17.193] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:39:0) after 1m0s
[00:23:17.378] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:39:0)
[00:23:17.378] Timer tick, asking Buildbucket for the build status
[00:23:17.511] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:17.511] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:40:0) after 1m0s
[00:24:17.613] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:40:0)
[00:24:17.613] Timer tick, asking Buildbucket for the build status
[00:24:17.863] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:17.863] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:41:0) after 1m0s
[00:25:17.952] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:41:0)
[00:25:17.952] Timer tick, asking Buildbucket for the build status
[00:25:18.029] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:18.029] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:42:0) after 1m0s
[00:26:18.209] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:42:0)
[00:26:18.209] Timer tick, asking Buildbucket for the build status
[00:26:18.347] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:18.347] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:43:0) after 1m0s
[00:27:18.549] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:43:0)
[00:27:18.549] Timer tick, asking Buildbucket for the build status
[00:27:18.914] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:18.914] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:44:0) after 1m0s
[00:28:18.941] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:44:0)
[00:28:18.941] Timer tick, asking Buildbucket for the build status
[00:28:18.995] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:18.995] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:45:0) after 1m0s
[00:29:19.016] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:45:0)
[00:29:19.016] Timer tick, asking Buildbucket for the build status
[00:29:19.132] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:19.132] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:46:0) after 1m0s
[00:30:19.255] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:46:0)
[00:30:19.255] Timer tick, asking Buildbucket for the build status
[00:30:19.386] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:19.387] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:47:0) after 1m0s
[00:31:19.508] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:47:0)
[00:31:19.508] Timer tick, asking Buildbucket for the build status
[00:31:20.615] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:20.615] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:48:0) after 1m0s
[00:32:20.678] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:48:0)
[00:32:20.678] Timer tick, asking Buildbucket for the build status
[00:32:22.262] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:22.262] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:49:0) after 1m0s
[00:33:22.292] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:49:0)
[00:33:22.292] Timer tick, asking Buildbucket for the build status
[00:33:22.448] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:22.448] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:50:0) after 1m0s
[00:34:22.517] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:50:0)
[00:34:22.517] Timer tick, asking Buildbucket for the build status
[00:34:22.640] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:22.640] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:51:0) after 1m0s
[00:35:22.871] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:51:0)
[00:35:22.871] Timer tick, asking Buildbucket for the build status
[00:35:23.157] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:23.157] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:52:0) after 1m0s
[00:36:23.189] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:52:0)
[00:36:23.189] Timer tick, asking Buildbucket for the build status
[00:36:23.298] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:23.298] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:53:0) after 1m0s
[00:37:23.401] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:53:0)
[00:37:23.401] Timer tick, asking Buildbucket for the build status
[00:37:23.788] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:23.788] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:54:0) after 1m0s
[00:38:24.073] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:54:0)
[00:38:24.073] Timer tick, asking Buildbucket for the build status
[00:38:24.285] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:24.285] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:55:0) after 1m0s
[00:39:24.374] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:55:0)
[00:39:24.374] Timer tick, asking Buildbucket for the build status
[00:39:31.807] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:31.807] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:56:0) after 1m0s
[00:40:31.934] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:56:0)
[00:40:31.934] Timer tick, asking Buildbucket for the build status
[00:40:32.003] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:32.003] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:57:0) after 1m0s
[00:41:32.093] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:57:0)
[00:41:32.093] Timer tick, asking Buildbucket for the build status
[00:41:32.660] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:32.660] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:58:0) after 1m0s
[00:42:32.736] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:58:0)
[00:42:32.736] Timer tick, asking Buildbucket for the build status
[00:42:32.820] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:32.820] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:59:0) after 1m0s
[00:43:32.925] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:59:0)
[00:43:32.925] Timer tick, asking Buildbucket for the build status
[00:43:33.051] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:33.051] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:60:0) after 1m0s
[00:44:33.260] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:60:0)
[00:44:33.260] Timer tick, asking Buildbucket for the build status
[00:44:38.185] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:38.185] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:61:0) after 1m0s
[00:45:38.225] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:61:0)
[00:45:38.225] Timer tick, asking Buildbucket for the build status
[00:45:38.333] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:38.333] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:62:0) after 1m0s
[00:46:38.331] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:62:0)
[00:46:38.331] Timer tick, asking Buildbucket for the build status
[00:46:38.574] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:38.574] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:63:0) after 1m0s
[00:47:38.577] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:63:0)
[00:47:38.577] Timer tick, asking Buildbucket for the build status
[00:47:38.663] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:38.663] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:64:0) after 1m0s
[00:48:38.745] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:64:0)
[00:48:38.745] Timer tick, asking Buildbucket for the build status
[00:48:38.815] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:38.815] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:65:0) after 1m0s
[00:49:49.159] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:65:0)
[00:49:49.159] Timer tick, asking Buildbucket for the build status
[00:49:53.207] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:53.207] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:66:0) after 1m0s
[00:50:53.754] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:66:0)
[00:50:53.754] Timer tick, asking Buildbucket for the build status
[00:50:53.845] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:53.845] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:67:0) after 1m0s
[00:51:54.110] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:67:0)
[00:51:54.110] Timer tick, asking Buildbucket for the build status
[00:51:54.259] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:54.259] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:68:0) after 1m0s
[00:52:54.473] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:68:0)
[00:52:54.473] Timer tick, asking Buildbucket for the build status
[00:52:54.850] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:54.850] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:69:0) after 1m0s
[00:53:54.889] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:69:0)
[00:53:54.889] Timer tick, asking Buildbucket for the build status
[00:53:54.982] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:54.982] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:70:0) after 1m0s
[00:54:55.068] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:70:0)
[00:54:55.068] Timer tick, asking Buildbucket for the build status
[00:54:58.711] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:58.711] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:71:0) after 1m0s
[00:55:58.904] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:71:0)
[00:55:58.904] Timer tick, asking Buildbucket for the build status
[00:55:59.122] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:59.122] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:72:0) after 1m0s
[00:56:59.143] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:72:0)
[00:56:59.143] Timer tick, asking Buildbucket for the build status
[00:56:59.353] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:59.353] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:73:0) after 1m0s
[00:57:59.389] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:73:0)
[00:57:59.389] Timer tick, asking Buildbucket for the build status
[00:57:59.454] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:59.454] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:74:0) after 1m0s
[00:58:59.532] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:74:0)
[00:58:59.532] Timer tick, asking Buildbucket for the build status
[00:58:59.587] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:59.587] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:75:0) after 1m0s
[00:59:59.662] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:75:0)
[00:59:59.662] Timer tick, asking Buildbucket for the build status
[01:00:00.459] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:00.459] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:76:0) after 1m0s
[01:01:00.524] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:76:0)
[01:01:00.524] Timer tick, asking Buildbucket for the build status
[01:01:00.944] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:00.944] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:77:0) after 1m0s
[01:02:01.636] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:77:0)
[01:02:01.636] Timer tick, asking Buildbucket for the build status
[01:02:01.831] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:01.831] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:78:0) after 1m0s
[01:03:01.881] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:78:0)
[01:03:01.881] Timer tick, asking Buildbucket for the build status
[01:03:01.980] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:01.980] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:79:0) after 1m0s
[01:04:01.996] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:79:0)
[01:04:01.996] Timer tick, asking Buildbucket for the build status
[01:04:03.085] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:03.085] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:80:0) after 1m0s
[01:05:03.168] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:80:0)
[01:05:03.168] Timer tick, asking Buildbucket for the build status
[01:05:03.224] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:03.224] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:81:0) after 1m0s
[01:06:03.383] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:81:0)
[01:06:03.384] Timer tick, asking Buildbucket for the build status
[01:06:03.654] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:03.654] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:82:0) after 1m0s
[01:07:03.789] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:82:0)
[01:07:03.789] Timer tick, asking Buildbucket for the build status
[01:07:04.009] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:04.009] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:83:0) after 1m0s
[01:08:04.039] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:83:0)
[01:08:04.039] Timer tick, asking Buildbucket for the build status
[01:08:04.133] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:04.133] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:84:0) after 1m0s
[01:09:04.365] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:84:0)
[01:09:04.365] Timer tick, asking Buildbucket for the build status
[01:09:04.590] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:04.590] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:85:0) after 1m0s
[01:10:04.766] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:85:0)
[01:10:04.766] Timer tick, asking Buildbucket for the build status
[01:10:04.839] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:04.839] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:86:0) after 1m0s
[01:11:05.034] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:86:0)
[01:11:05.034] Timer tick, asking Buildbucket for the build status
[01:11:05.658] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:05.658] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:87:0) after 1m0s
[01:12:05.807] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:87:0)
[01:12:05.807] Timer tick, asking Buildbucket for the build status
[01:12:05.906] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:05.906] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:88:0) after 1m0s
[01:13:06.057] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:88:0)
[01:13:06.057] Timer tick, asking Buildbucket for the build status
[01:13:07.382] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:07.382] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:89:0) after 1m0s
[01:14:07.413] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:89:0)
[01:14:07.413] Timer tick, asking Buildbucket for the build status
[01:14:07.683] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:07.683] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:90:0) after 1m0s
[01:15:07.723] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:90:0)
[01:15:07.723] Timer tick, asking Buildbucket for the build status
[01:15:07.789] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:07.789] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:91:0) after 1m0s
[01:16:07.845] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:91:0)
[01:16:07.845] Timer tick, asking Buildbucket for the build status
[01:16:07.956] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:07.956] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:92:0) after 1m0s
[01:17:08.048] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:92:0)
[01:17:08.048] Timer tick, asking Buildbucket for the build status
[01:17:08.674] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:08.674] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:93:0) after 1m0s
[01:18:08.938] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:93:0)
[01:18:08.938] Timer tick, asking Buildbucket for the build status
[01:18:09.163] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:09.163] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:94:0) after 1m0s
[01:19:09.203] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:94:0)
[01:19:09.203] Timer tick, asking Buildbucket for the build status
[01:19:09.354] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:09.354] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:95:0) after 1m0s
[01:20:09.340] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:95:0)
[01:20:09.340] Timer tick, asking Buildbucket for the build status
[01:20:09.409] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:09.410] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:96:0) after 1m0s
[01:21:09.426] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:96:0)
[01:21:09.426] Timer tick, asking Buildbucket for the build status
[01:21:10.032] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:10.032] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:97:0) after 1m0s
[01:22:10.072] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:97:0)
[01:22:10.072] Timer tick, asking Buildbucket for the build status
[01:22:10.359] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:10.359] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:98:0) after 1m0s
[01:23:10.380] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:98:0)
[01:23:10.380] Timer tick, asking Buildbucket for the build status
[01:23:10.768] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:10.768] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:99:0) after 1m0s
[01:24:10.801] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:99:0)
[01:24:10.801] Timer tick, asking Buildbucket for the build status
[01:24:11.119] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:11.119] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:100:0) after 1m0s
[01:25:11.215] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:100:0)
[01:25:11.215] Timer tick, asking Buildbucket for the build status
[01:25:11.384] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:11.384] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:101:0) after 1m0s
[01:26:11.407] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:101:0)
[01:26:11.407] Timer tick, asking Buildbucket for the build status
[01:26:11.624] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:11.624] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:102:0) after 1m0s
[01:27:11.631] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:102:0)
[01:27:11.631] Timer tick, asking Buildbucket for the build status
[01:27:11.779] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:11.779] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:103:0) after 1m0s
[01:28:11.778] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:103:0)
[01:28:11.778] Timer tick, asking Buildbucket for the build status
[01:28:12.046] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:12.046] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:104:0) after 1m0s
[01:29:12.076] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:104:0)
[01:29:12.076] Timer tick, asking Buildbucket for the build status
[01:29:12.136] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:12.136] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:105:0) after 1m0s
[01:30:12.161] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:105:0)
[01:30:12.161] Timer tick, asking Buildbucket for the build status
[01:30:12.249] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:12.249] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:106:0) after 1m0s
[01:31:12.378] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:106:0)
[01:31:12.378] Timer tick, asking Buildbucket for the build status
[01:31:12.709] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:12.709] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:107:0) after 1m0s
[01:32:12.740] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:107:0)
[01:32:12.740] Timer tick, asking Buildbucket for the build status
[01:32:12.926] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:12.926] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:108:0) after 1m0s
[01:33:12.952] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:108:0)
[01:33:12.952] Timer tick, asking Buildbucket for the build status
[01:33:13.237] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:13.238] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:109:0) after 1m0s
[01:34:13.356] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:109:0)
[01:34:13.356] Timer tick, asking Buildbucket for the build status
[01:34:14.306] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:14.306] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:110:0) after 1m0s
[01:35:14.344] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:110:0)
[01:35:14.344] Timer tick, asking Buildbucket for the build status
[01:35:14.675] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:14.675] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:111:0) after 1m0s
[01:36:14.700] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:111:0)
[01:36:14.700] Timer tick, asking Buildbucket for the build status
[01:36:15.696] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:15.696] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:112:0) after 1m0s
[01:37:15.771] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:112:0)
[01:37:15.771] Timer tick, asking Buildbucket for the build status
[01:37:15.873] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:15.873] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:113:0) after 1m0s
[01:38:16.041] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:113:0)
[01:38:16.041] Timer tick, asking Buildbucket for the build status
[01:38:16.123] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:16.123] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:114:0) after 1m0s
[01:39:16.173] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:114:0)
[01:39:16.173] Timer tick, asking Buildbucket for the build status
[01:39:18.845] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:18.845] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:115:0) after 1m0s
[01:40:18.907] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:115:0)
[01:40:18.908] Timer tick, asking Buildbucket for the build status
[01:40:19.218] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:19.218] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:116:0) after 1m0s
[01:41:19.271] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:116:0)
[01:41:19.271] Timer tick, asking Buildbucket for the build status
[01:41:19.668] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:19.668] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:117:0) after 1m0s
[01:42:19.749] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:117:0)
[01:42:19.749] Timer tick, asking Buildbucket for the build status
[01:42:19.818] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:19.818] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:118:0) after 1m0s
[01:43:19.880] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:118:0)
[01:43:19.880] Timer tick, asking Buildbucket for the build status
[01:43:19.974] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:19.974] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:119:0) after 1m0s
[01:44:19.956] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:119:0)
[01:44:19.956] Timer tick, asking Buildbucket for the build status
[01:44:20.258] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:20.258] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:120:0) after 1m0s
[01:45:20.282] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:120:0)
[01:45:20.282] Timer tick, asking Buildbucket for the build status
[01:45:20.361] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:20.361] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:121:0) after 1m0s
[01:46:20.404] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:121:0)
[01:46:20.404] Timer tick, asking Buildbucket for the build status
[01:46:20.490] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:20.490] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:122:0) after 1m0s
[01:47:20.517] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:122:0)
[01:47:20.517] Timer tick, asking Buildbucket for the build status
[01:47:20.590] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:20.590] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:123:0) after 1m0s
[01:48:20.733] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:123:0)
[01:48:20.733] Timer tick, asking Buildbucket for the build status
[01:48:20.836] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:20.836] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:124:0) after 1m0s
[01:49:20.873] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:124:0)
[01:49:20.873] Timer tick, asking Buildbucket for the build status
[01:49:21.084] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:21.084] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:125:0) after 1m0s
[01:50:21.121] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:125:0)
[01:50:21.121] Timer tick, asking Buildbucket for the build status
[01:50:21.205] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:21.205] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:126:0) after 1m0s
[01:51:21.238] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:126:0)
[01:51:21.238] Timer tick, asking Buildbucket for the build status
[01:51:21.403] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:21.403] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:127:0) after 1m0s
[01:52:21.467] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:127:0)
[01:52:21.467] Timer tick, asking Buildbucket for the build status
[01:52:21.707] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:21.707] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:128:0) after 1m0s
[01:53:21.762] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:128:0)
[01:53:21.762] Timer tick, asking Buildbucket for the build status
[01:53:21.880] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:21.880] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:129:0) after 1m0s
[01:54:21.953] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:129:0)
[01:54:21.953] Timer tick, asking Buildbucket for the build status
[01:54:22.240] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:22.240] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:130:0) after 1m0s
[01:55:22.297] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:130:0)
[01:55:22.297] Timer tick, asking Buildbucket for the build status
[01:55:22.463] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:22.463] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:131:0) after 1m0s
[01:56:22.447] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:131:0)
[01:56:22.447] Timer tick, asking Buildbucket for the build status
[01:56:22.614] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:22.614] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:132:0) after 1m0s
[01:57:22.695] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:132:0)
[01:57:22.695] Timer tick, asking Buildbucket for the build status
[01:57:22.840] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:22.840] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:133:0) after 1m0s
[01:58:22.887] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:133:0)
[01:58:22.887] Timer tick, asking Buildbucket for the build status
[01:58:22.952] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:22.953] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:134:0) after 1m0s
[01:59:23.004] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:134:0)
[01:59:23.004] Timer tick, asking Buildbucket for the build status
[01:59:23.934] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:23.934] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:135:0) after 1m0s
[02:00:24.027] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:135:0)
[02:00:24.027] Timer tick, asking Buildbucket for the build status
[02:00:24.307] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:24.307] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:136:0) after 1m0s
[02:01:24.354] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:136:0)
[02:01:24.354] Timer tick, asking Buildbucket for the build status
[02:01:24.947] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:24.947] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:137:0) after 1m0s
[02:02:25.039] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:137:0)
[02:02:25.039] Timer tick, asking Buildbucket for the build status
[02:02:25.133] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:25.133] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:138:0) after 1m0s
[02:03:25.173] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:138:0)
[02:03:25.173] Timer tick, asking Buildbucket for the build status
[02:03:25.455] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:25.455] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:139:0) after 1m0s
[02:04:25.522] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:139:0)
[02:04:25.522] Timer tick, asking Buildbucket for the build status
[02:04:25.632] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:25.632] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:140:0) after 1m0s
[02:05:25.664] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:140:0)
[02:05:25.664] Timer tick, asking Buildbucket for the build status
[02:05:25.851] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:25.851] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:141:0) after 1m0s
[02:06:25.898] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:141:0)
[02:06:25.898] Timer tick, asking Buildbucket for the build status
[02:06:26.228] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:26.228] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:142:0) after 1m0s
[02:07:26.258] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:142:0)
[02:07:26.258] Timer tick, asking Buildbucket for the build status
[02:07:26.395] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:26.395] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:143:0) after 1m0s
[02:08:26.445] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:143:0)
[02:08:26.445] Timer tick, asking Buildbucket for the build status
[02:08:26.533] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:26.533] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:144:0) after 1m0s
[02:09:26.569] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:144:0)
[02:09:26.569] Timer tick, asking Buildbucket for the build status
[02:09:30.950] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:30.950] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:145:0) after 1m0s
[02:10:30.975] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:145:0)
[02:10:30.975] Timer tick, asking Buildbucket for the build status
[02:10:31.049] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:31.049] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:146:0) after 1m0s
[02:11:31.133] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:146:0)
[02:11:31.133] Timer tick, asking Buildbucket for the build status
[02:11:31.248] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:31.248] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:147:0) after 1m0s
[02:12:31.286] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:147:0)
[02:12:31.286] Timer tick, asking Buildbucket for the build status
[02:12:31.453] Build 8861709836329841296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:31.453] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027139898365395600:148:0) after 1m0s
[02:13:22.965] Received PubSub notification, asking Buildbucket for the build status
[02:13:23.057] Build 8861709836329841296: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:13:23.057] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607220801880244",
"created_by": "project:chromium",
"created_ts": "1607211951855759",
"id": "8861709836329841296",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"be8741c03da272232e3a888bc9fb91050086abb4\"}}",
"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\": \"be8741c03da272232e3a888bc9fb91050086abb4\", \"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-9-8z7f\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 1060, \"got_angle_revision\": \"251ba5cb119ff2fed0e861cbc9b096c45004c1fa\", \"got_dawn_revision\": \"d63d562d1b26e01b369d9a58eddeaccfaa688a02\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"be8741c03da272232e3a888bc9fb91050086abb4\", \"got_revision_cp\": \"refs/heads/master@{#834045}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"f36e874ade1dbc6f5ddfc857753a892a8bb358c3\", \"got_v8_revision\": \"61f3e61408129afc0d9e1268d3354a283ea3c881\", \"got_v8_revision_cp\": \"refs/heads/8.9.100@{#1}\", \"got_webrtc_revision\": \"6c80aebd00360ee00c84c7b14422c5fe475d8916\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32774}\", \"mirrored_builders\": [\"tryserver.chromium:win32-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"be8741c03da272232e3a888bc9fb91050086abb4\"}, \"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-9-8z7f\"], \"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\": [\"5640-d0eb544\"], \"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": "1607211987398952",
"status": "COMPLETED",
"status_changed_ts": "1607220802265044",
"tags": [
"build_address:luci.chromium.ci/win32-official/1060",
"builder:win32-official",
"buildset:commit/git/be8741c03da272232e3a888bc9fb91050086abb4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/be8741c03da272232e3a888bc9fb91050086abb4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027139898365395600",
"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/8861709836329841296/+/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:504e1db2971fdb10",
"user_agent:luci-scheduler"
],
"updated_ts": "1607220802291284",
"url": "https://ci.chromium.org/b/8861709836329841296",
"utcnow_ts": "1607220803046394"
}
}
[02:13:23.057] Invocation finished in 2h27m32.527264665s with status SUCCEEDED