[03:12:21.397] New invocation is queued and will start shortly
[03:12:23.021] Starting the invocation (attempt 1)
[03:12:23.061] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:12:23.061] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:12:23.061] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9035643020498689568",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"64b478eb69f196e1b243aee185952a8949e97996\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9035643020498689568",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/64b478eb69f196e1b243aee185952a8949e97996",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/64b478eb69f196e1b243aee185952a8949e97996",
"gitiles_ref:refs/heads/master"
]
}
[03:12:23.813] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1599102743158003",
"id": "8870212957948708512",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"64b478eb69f196e1b243aee185952a8949e97996\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1599102743637545",
"tags": [
"build_address:luci.chromium.ci/win-official/493",
"builder:win-official",
"buildset:commit/git/64b478eb69f196e1b243aee185952a8949e97996",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/64b478eb69f196e1b243aee185952a8949e97996",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035643020498689568",
"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/8870212957948708512/+/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": "1599102743637665",
"url": "https://ci.chromium.org/b/8870212957948708512",
"utcnow_ts": "1599102743804684"
}
}
[03:12:23.813] Task URL: https://ci.chromium.org/b/8870212957948708512
[03:12:23.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:2:0) after 1m0s
[03:13:06.906] Received PubSub notification, asking Buildbucket for the build status
[03:13:07.161] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:23.951] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:2:0)
[03:13:23.951] Timer tick, asking Buildbucket for the build status
[03:13:24.036] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:24.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:4:0) after 1m0s
[03:14:24.112] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:4:0)
[03:14:24.112] Timer tick, asking Buildbucket for the build status
[03:14:24.251] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:24.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:5:0) after 1m0s
[03:15:24.361] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:5:0)
[03:15:24.361] Timer tick, asking Buildbucket for the build status
[03:15:24.458] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:24.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:6:0) after 1m0s
[03:16:24.447] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:6:0)
[03:16:24.447] Timer tick, asking Buildbucket for the build status
[03:16:24.592] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:24.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:7:0) after 1m0s
[03:17:24.741] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:7:0)
[03:17:24.741] Timer tick, asking Buildbucket for the build status
[03:17:24.965] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:24.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:8:0) after 1m0s
[03:18:24.987] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:8:0)
[03:18:24.987] Timer tick, asking Buildbucket for the build status
[03:18:25.113] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:25.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:9:0) after 1m0s
[03:19:25.257] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:9:0)
[03:19:25.257] Timer tick, asking Buildbucket for the build status
[03:19:25.498] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:25.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:10:0) after 1m0s
[03:20:25.522] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:10:0)
[03:20:25.522] Timer tick, asking Buildbucket for the build status
[03:20:25.597] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:25.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:11:0) after 1m0s
[03:21:25.727] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:11:0)
[03:21:25.727] Timer tick, asking Buildbucket for the build status
[03:21:26.033] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:26.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:12:0) after 1m0s
[03:22:26.185] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:12:0)
[03:22:26.185] Timer tick, asking Buildbucket for the build status
[03:22:26.426] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:26.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:13:0) after 1m0s
[03:23:26.512] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:13:0)
[03:23:26.512] Timer tick, asking Buildbucket for the build status
[03:23:26.672] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:26.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:14:0) after 1m0s
[03:24:26.839] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:14:0)
[03:24:26.840] Timer tick, asking Buildbucket for the build status
[03:24:26.963] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:26.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:15:0) after 1m0s
[03:25:27.048] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:15:0)
[03:25:27.048] Timer tick, asking Buildbucket for the build status
[03:25:27.137] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:27.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:16:0) after 1m0s
[03:26:27.250] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:16:0)
[03:26:27.250] Timer tick, asking Buildbucket for the build status
[03:26:27.449] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:27.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:17:0) after 1m0s
[03:27:27.491] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:17:0)
[03:27:27.491] Timer tick, asking Buildbucket for the build status
[03:27:27.612] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:27.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:18:0) after 1m0s
[03:28:27.734] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:18:0)
[03:28:27.734] Timer tick, asking Buildbucket for the build status
[03:28:28.067] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:28.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:19:0) after 1m0s
[03:29:28.093] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:19:0)
[03:29:28.093] Timer tick, asking Buildbucket for the build status
[03:29:28.181] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:28.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:20:0) after 1m0s
[03:30:28.193] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:20:0)
[03:30:28.193] Timer tick, asking Buildbucket for the build status
[03:30:28.352] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:28.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:21:0) after 1m0s
[03:31:28.396] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:21:0)
[03:31:28.396] Timer tick, asking Buildbucket for the build status
[03:31:28.463] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:28.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:22:0) after 1m0s
[03:32:28.488] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:22:0)
[03:32:28.488] Timer tick, asking Buildbucket for the build status
[03:32:28.612] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:28.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:23:0) after 1m0s
[03:33:28.745] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:23:0)
[03:33:28.745] Timer tick, asking Buildbucket for the build status
[03:33:28.963] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:28.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:24:0) after 1m0s
[03:34:29.001] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:24:0)
[03:34:29.001] Timer tick, asking Buildbucket for the build status
[03:34:29.182] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:29.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:25:0) after 1m0s
[03:35:29.200] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:25:0)
[03:35:29.200] Timer tick, asking Buildbucket for the build status
[03:35:29.282] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:29.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:26:0) after 1m0s
[03:36:29.490] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:26:0)
[03:36:29.490] Timer tick, asking Buildbucket for the build status
[03:36:29.889] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:29.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:27:0) after 1m0s
[03:37:29.909] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:27:0)
[03:37:29.909] Timer tick, asking Buildbucket for the build status
[03:37:30.439] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:30.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:28:0) after 1m0s
[03:38:30.677] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:28:0)
[03:38:30.677] Timer tick, asking Buildbucket for the build status
[03:38:30.800] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:30.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:29:0) after 1m0s
[03:39:30.890] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:29:0)
[03:39:30.890] Timer tick, asking Buildbucket for the build status
[03:39:30.992] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:30.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:30:0) after 1m0s
[03:40:31.047] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:30:0)
[03:40:31.047] Timer tick, asking Buildbucket for the build status
[03:40:31.121] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:31.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:31:0) after 1m0s
[03:41:31.120] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:31:0)
[03:41:31.120] Timer tick, asking Buildbucket for the build status
[03:41:31.232] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:31.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:32:0) after 1m0s
[03:42:31.269] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:32:0)
[03:42:31.269] Timer tick, asking Buildbucket for the build status
[03:42:31.415] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:31.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:33:0) after 1m0s
[03:43:31.559] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:33:0)
[03:43:31.559] Timer tick, asking Buildbucket for the build status
[03:43:32.336] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:32.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:34:0) after 1m0s
[03:44:32.390] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:34:0)
[03:44:32.390] Timer tick, asking Buildbucket for the build status
[03:44:32.489] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:32.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:35:0) after 1m0s
[03:45:32.526] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:35:0)
[03:45:32.527] Timer tick, asking Buildbucket for the build status
[03:45:32.601] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:32.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:36:0) after 1m0s
[03:46:32.842] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:36:0)
[03:46:32.842] Timer tick, asking Buildbucket for the build status
[03:46:33.127] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:33.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:37:0) after 1m0s
[03:47:33.153] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:37:0)
[03:47:33.153] Timer tick, asking Buildbucket for the build status
[03:47:33.287] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:33.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:38:0) after 1m0s
[03:48:33.282] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:38:0)
[03:48:33.282] Timer tick, asking Buildbucket for the build status
[03:48:33.555] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:33.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:39:0) after 1m0s
[03:49:33.692] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:39:0)
[03:49:33.692] Timer tick, asking Buildbucket for the build status
[03:49:33.916] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:33.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:40:0) after 1m0s
[03:50:33.985] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:40:0)
[03:50:33.985] Timer tick, asking Buildbucket for the build status
[03:50:34.131] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:34.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:41:0) after 1m0s
[03:51:34.165] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:41:0)
[03:51:34.165] Timer tick, asking Buildbucket for the build status
[03:51:34.271] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:34.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:42:0) after 1m0s
[03:52:34.322] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:42:0)
[03:52:34.322] Timer tick, asking Buildbucket for the build status
[03:52:34.412] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:34.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:43:0) after 1m0s
[03:53:34.679] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:43:0)
[03:53:34.679] Timer tick, asking Buildbucket for the build status
[03:53:34.751] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:34.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:44:0) after 1m0s
[03:54:34.863] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:44:0)
[03:54:34.863] Timer tick, asking Buildbucket for the build status
[03:54:34.997] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:34.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:45:0) after 1m0s
[03:55:35.100] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:45:0)
[03:55:35.100] Timer tick, asking Buildbucket for the build status
[03:55:36.742] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:36.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:46:0) after 1m0s
[03:56:36.941] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:46:0)
[03:56:36.941] Timer tick, asking Buildbucket for the build status
[03:56:37.094] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:37.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:47:0) after 1m0s
[03:57:37.351] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:47:0)
[03:57:37.351] Timer tick, asking Buildbucket for the build status
[03:57:37.508] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:37.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:48:0) after 1m0s
[03:58:37.614] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:48:0)
[03:58:37.614] Timer tick, asking Buildbucket for the build status
[03:58:37.688] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:37.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:49:0) after 1m0s
[03:59:37.741] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:49:0)
[03:59:37.741] Timer tick, asking Buildbucket for the build status
[03:59:37.836] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:37.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:50:0) after 1m0s
[04:00:37.850] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:50:0)
[04:00:37.850] Timer tick, asking Buildbucket for the build status
[04:00:38.885] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:38.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:51:0) after 1m0s
[04:01:38.945] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:51:0)
[04:01:38.945] Timer tick, asking Buildbucket for the build status
[04:01:39.294] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:39.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:52:0) after 1m0s
[04:02:39.500] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:52:0)
[04:02:39.500] Timer tick, asking Buildbucket for the build status
[04:02:39.618] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:39.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:53:0) after 1m0s
[04:03:39.733] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:53:0)
[04:03:39.733] Timer tick, asking Buildbucket for the build status
[04:03:40.046] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:40.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:54:0) after 1m0s
[04:04:40.208] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:54:0)
[04:04:40.209] Timer tick, asking Buildbucket for the build status
[04:04:40.312] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:40.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:55:0) after 1m0s
[04:05:40.386] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:55:0)
[04:05:40.386] Timer tick, asking Buildbucket for the build status
[04:05:40.462] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:40.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:56:0) after 1m0s
[04:06:40.440] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:56:0)
[04:06:40.440] Timer tick, asking Buildbucket for the build status
[04:06:40.528] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:40.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:57:0) after 1m0s
[04:07:40.593] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:57:0)
[04:07:40.593] Timer tick, asking Buildbucket for the build status
[04:07:40.733] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:40.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:58:0) after 1m0s
[04:08:40.828] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:58:0)
[04:08:40.828] Timer tick, asking Buildbucket for the build status
[04:08:40.919] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:40.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:59:0) after 1m0s
[04:09:41.053] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:59:0)
[04:09:41.053] Timer tick, asking Buildbucket for the build status
[04:09:41.130] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:41.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:60:0) after 1m0s
[04:10:41.255] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:60:0)
[04:10:41.255] Timer tick, asking Buildbucket for the build status
[04:10:41.338] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:41.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:61:0) after 1m0s
[04:11:41.360] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:61:0)
[04:11:41.360] Timer tick, asking Buildbucket for the build status
[04:11:41.538] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:41.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:62:0) after 1m0s
[04:12:41.617] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:62:0)
[04:12:41.617] Timer tick, asking Buildbucket for the build status
[04:12:41.774] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:41.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:63:0) after 1m0s
[04:13:41.829] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:63:0)
[04:13:41.829] Timer tick, asking Buildbucket for the build status
[04:13:41.954] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:41.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:64:0) after 1m0s
[04:14:42.758] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:64:0)
[04:14:42.758] Timer tick, asking Buildbucket for the build status
[04:14:42.932] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:42.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:65:0) after 1m0s
[04:15:42.990] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:65:0)
[04:15:42.990] Timer tick, asking Buildbucket for the build status
[04:15:43.048] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:43.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:66:0) after 1m0s
[04:16:43.104] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:66:0)
[04:16:43.104] Timer tick, asking Buildbucket for the build status
[04:16:43.181] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:43.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:67:0) after 1m0s
[04:17:43.264] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:67:0)
[04:17:43.264] Timer tick, asking Buildbucket for the build status
[04:17:43.403] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:43.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:68:0) after 1m0s
[04:18:43.580] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:68:0)
[04:18:43.580] Timer tick, asking Buildbucket for the build status
[04:18:43.652] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:43.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:69:0) after 1m0s
[04:19:43.736] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:69:0)
[04:19:43.736] Timer tick, asking Buildbucket for the build status
[04:19:43.887] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:43.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:70:0) after 1m0s
[04:20:44.145] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:70:0)
[04:20:44.145] Timer tick, asking Buildbucket for the build status
[04:20:44.244] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:44.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:71:0) after 1m0s
[04:21:44.430] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:71:0)
[04:21:44.430] Timer tick, asking Buildbucket for the build status
[04:21:44.503] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:44.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:72:0) after 1m0s
[04:22:44.785] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:72:0)
[04:22:44.785] Timer tick, asking Buildbucket for the build status
[04:22:44.963] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:44.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:73:0) after 1m0s
[04:23:45.131] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:73:0)
[04:23:45.131] Timer tick, asking Buildbucket for the build status
[04:23:45.202] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:45.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:74:0) after 1m0s
[04:24:45.432] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:74:0)
[04:24:45.432] Timer tick, asking Buildbucket for the build status
[04:24:45.549] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:45.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:75:0) after 1m0s
[04:25:45.629] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:75:0)
[04:25:45.629] Timer tick, asking Buildbucket for the build status
[04:25:46.075] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:46.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:76:0) after 1m0s
[04:26:46.168] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:76:0)
[04:26:46.168] Timer tick, asking Buildbucket for the build status
[04:26:46.245] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:46.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:77:0) after 1m0s
[04:27:46.361] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:77:0)
[04:27:46.361] Timer tick, asking Buildbucket for the build status
[04:27:46.497] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:46.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:78:0) after 1m0s
[04:28:46.775] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:78:0)
[04:28:46.775] Timer tick, asking Buildbucket for the build status
[04:28:46.919] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:46.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:79:0) after 1m0s
[04:29:46.980] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:79:0)
[04:29:46.980] Timer tick, asking Buildbucket for the build status
[04:29:47.061] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:47.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:80:0) after 1m0s
[04:30:47.293] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:80:0)
[04:30:47.293] Timer tick, asking Buildbucket for the build status
[04:30:47.402] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:47.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:81:0) after 1m0s
[04:31:47.632] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:81:0)
[04:31:47.632] Timer tick, asking Buildbucket for the build status
[04:31:47.703] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:47.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:82:0) after 1m0s
[04:32:47.923] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:82:0)
[04:32:47.923] Timer tick, asking Buildbucket for the build status
[04:32:48.092] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:48.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:83:0) after 1m0s
[04:33:48.164] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:83:0)
[04:33:48.164] Timer tick, asking Buildbucket for the build status
[04:33:48.235] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:48.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:84:0) after 1m0s
[04:34:48.289] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:84:0)
[04:34:48.289] Timer tick, asking Buildbucket for the build status
[04:34:48.372] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:48.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:85:0) after 1m0s
[04:35:48.467] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:85:0)
[04:35:48.467] Timer tick, asking Buildbucket for the build status
[04:35:48.594] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:48.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:86:0) after 1m0s
[04:36:48.741] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:86:0)
[04:36:48.741] Timer tick, asking Buildbucket for the build status
[04:36:50.146] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:50.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:87:0) after 1m0s
[04:37:50.813] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:87:0)
[04:37:50.813] Timer tick, asking Buildbucket for the build status
[04:37:51.402] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:51.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:88:0) after 1m0s
[04:38:51.538] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:88:0)
[04:38:51.539] Timer tick, asking Buildbucket for the build status
[04:38:51.600] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:51.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:89:0) after 1m0s
[04:39:51.633] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:89:0)
[04:39:51.633] Timer tick, asking Buildbucket for the build status
[04:39:51.843] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:51.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:90:0) after 1m0s
[04:40:51.843] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:90:0)
[04:40:51.843] Timer tick, asking Buildbucket for the build status
[04:40:51.994] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:51.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:91:0) after 1m0s
[04:41:52.014] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:91:0)
[04:41:52.014] Timer tick, asking Buildbucket for the build status
[04:41:52.299] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:52.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:92:0) after 1m0s
[04:42:52.337] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:92:0)
[04:42:52.337] Timer tick, asking Buildbucket for the build status
[04:42:52.556] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:52.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:93:0) after 1m0s
[04:43:52.629] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:93:0)
[04:43:52.629] Timer tick, asking Buildbucket for the build status
[04:43:52.731] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:52.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:94:0) after 1m0s
[04:44:53.237] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:94:0)
[04:44:53.237] Timer tick, asking Buildbucket for the build status
[04:44:53.351] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:53.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:95:0) after 1m0s
[04:45:53.484] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:95:0)
[04:45:53.484] Timer tick, asking Buildbucket for the build status
[04:45:53.559] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:53.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:96:0) after 1m0s
[04:46:53.729] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:96:0)
[04:46:53.729] Timer tick, asking Buildbucket for the build status
[04:46:53.842] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:53.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:97:0) after 1m0s
[04:47:53.943] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:97:0)
[04:47:53.943] Timer tick, asking Buildbucket for the build status
[04:47:54.218] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:54.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:98:0) after 1m0s
[04:48:54.530] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:98:0)
[04:48:54.530] Timer tick, asking Buildbucket for the build status
[04:48:54.698] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:54.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:99:0) after 1m0s
[04:49:54.842] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:99:0)
[04:49:54.842] Timer tick, asking Buildbucket for the build status
[04:49:56.529] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:56.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:100:0) after 1m0s
[04:50:56.639] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:100:0)
[04:50:56.639] Timer tick, asking Buildbucket for the build status
[04:50:56.734] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:56.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:101:0) after 1m0s
[04:51:56.830] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:101:0)
[04:51:56.830] Timer tick, asking Buildbucket for the build status
[04:51:56.893] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:56.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:102:0) after 1m0s
[04:52:57.244] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:102:0)
[04:52:57.244] Timer tick, asking Buildbucket for the build status
[04:52:57.436] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:57.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:103:0) after 1m0s
[04:53:57.532] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:103:0)
[04:53:57.532] Timer tick, asking Buildbucket for the build status
[04:53:57.641] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:57.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:104:0) after 1m0s
[04:54:57.663] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:104:0)
[04:54:57.663] Timer tick, asking Buildbucket for the build status
[04:54:57.973] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:57.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:105:0) after 1m0s
[04:55:58.022] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:105:0)
[04:55:58.022] Timer tick, asking Buildbucket for the build status
[04:55:58.108] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:58.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:106:0) after 1m0s
[04:56:58.725] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:106:0)
[04:56:58.725] Timer tick, asking Buildbucket for the build status
[04:56:58.845] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:58.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:107:0) after 1m0s
[04:57:58.927] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:107:0)
[04:57:58.927] Timer tick, asking Buildbucket for the build status
[04:57:59.072] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:59.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:108:0) after 1m0s
[04:58:59.464] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:108:0)
[04:58:59.464] Timer tick, asking Buildbucket for the build status
[04:58:59.773] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:59.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:109:0) after 1m0s
[04:59:59.818] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:109:0)
[04:59:59.818] Timer tick, asking Buildbucket for the build status
[05:00:00.203] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:00.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:110:0) after 1m0s
[05:01:00.399] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:110:0)
[05:01:00.399] Timer tick, asking Buildbucket for the build status
[05:01:00.597] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:00.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:111:0) after 1m0s
[05:02:00.699] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:111:0)
[05:02:00.699] Timer tick, asking Buildbucket for the build status
[05:02:01.016] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:01.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:112:0) after 1m0s
[05:03:01.465] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:112:0)
[05:03:01.465] Timer tick, asking Buildbucket for the build status
[05:03:01.585] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:01.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:113:0) after 1m0s
[05:04:01.645] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:113:0)
[05:04:01.645] Timer tick, asking Buildbucket for the build status
[05:04:01.835] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:01.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:114:0) after 1m0s
[05:05:01.855] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:114:0)
[05:05:01.855] Timer tick, asking Buildbucket for the build status
[05:05:01.921] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:01.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:115:0) after 1m0s
[05:06:01.951] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:115:0)
[05:06:01.951] Timer tick, asking Buildbucket for the build status
[05:06:02.027] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:02.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:116:0) after 1m0s
[05:07:03.734] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:116:0)
[05:07:03.734] Timer tick, asking Buildbucket for the build status
[05:07:03.817] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:03.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:117:0) after 1m0s
[05:08:03.811] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:117:0)
[05:08:03.811] Timer tick, asking Buildbucket for the build status
[05:08:04.227] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:04.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:118:0) after 1m0s
[05:09:04.528] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:118:0)
[05:09:04.528] Timer tick, asking Buildbucket for the build status
[05:09:04.707] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:04.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:119:0) after 1m0s
[05:10:04.915] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:119:0)
[05:10:04.915] Timer tick, asking Buildbucket for the build status
[05:10:05.294] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:05.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:120:0) after 1m0s
[05:11:05.387] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:120:0)
[05:11:05.387] Timer tick, asking Buildbucket for the build status
[05:11:05.474] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:05.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:121:0) after 1m0s
[05:12:05.495] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:121:0)
[05:12:05.495] Timer tick, asking Buildbucket for the build status
[05:12:05.597] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:05.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:122:0) after 1m0s
[05:13:05.590] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:122:0)
[05:13:05.590] Timer tick, asking Buildbucket for the build status
[05:13:05.986] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:05.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:123:0) after 1m0s
[05:14:06.016] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:123:0)
[05:14:06.016] Timer tick, asking Buildbucket for the build status
[05:14:06.103] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:06.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:124:0) after 1m0s
[05:15:06.195] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:124:0)
[05:15:06.195] Timer tick, asking Buildbucket for the build status
[05:15:06.286] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:06.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:125:0) after 1m0s
[05:16:06.402] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:125:0)
[05:16:06.402] Timer tick, asking Buildbucket for the build status
[05:16:06.736] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:06.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:126:0) after 1m0s
[05:17:06.869] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:126:0)
[05:17:06.869] Timer tick, asking Buildbucket for the build status
[05:17:07.233] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:07.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:127:0) after 1m0s
[05:18:07.879] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:127:0)
[05:18:07.879] Timer tick, asking Buildbucket for the build status
[05:18:08.285] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:08.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:128:0) after 1m0s
[05:19:08.312] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:128:0)
[05:19:08.312] Timer tick, asking Buildbucket for the build status
[05:19:08.379] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:08.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:129:0) after 1m0s
[05:20:08.462] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:129:0)
[05:20:08.462] Timer tick, asking Buildbucket for the build status
[05:20:08.546] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:08.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:130:0) after 1m0s
[05:21:08.775] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:130:0)
[05:21:08.775] Timer tick, asking Buildbucket for the build status
[05:21:08.843] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:08.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:131:0) after 1m0s
[05:22:08.905] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:131:0)
[05:22:08.905] Timer tick, asking Buildbucket for the build status
[05:22:08.965] Build 8870212957948708512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:08.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035643020498689568:132:0) after 1m0s
[05:22:48.238] Received PubSub notification, asking Buildbucket for the build status
[05:22:48.323] Build 8870212957948708512: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:22:48.323] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1599110567593775",
"created_by": "project:chromium",
"created_ts": "1599102743158003",
"id": "8870212957948708512",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"64b478eb69f196e1b243aee185952a8949e97996\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"64b478eb69f196e1b243aee185952a8949e97996\", \"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-6-vnlw\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 493, \"got_angle_revision\": \"6b2639b023f90759d6be3607186e9b71530f0a85\", \"got_dawn_revision\": \"e9bc506e0ab03dd7984ab5a6aade07651f2d9c17\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"64b478eb69f196e1b243aee185952a8949e97996\", \"got_revision_cp\": \"refs/heads/master@{#803993}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"e1051cbaad46dc98967bee2e00a697d7f82b6658\", \"got_v8_revision\": \"8395751f04c51d782f0290e1fca342f78873937b\", \"got_v8_revision_cp\": \"refs/heads/8.7.59@{#1}\", \"got_webrtc_revision\": \"444c13c078a7e9bb2174419e12113e39929551d8\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32028}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"64b478eb69f196e1b243aee185952a8949e97996\"}, \"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-6-vnlw\"], \"image\": [\"chrome-win10-1703-20081700-d9392568d25\"], \"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\": [\"5507-6e8d655\"], \"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": "1599102786351702",
"status": "COMPLETED",
"status_changed_ts": "1599110567938998",
"tags": [
"build_address:luci.chromium.ci/win-official/493",
"builder:win-official",
"buildset:commit/git/64b478eb69f196e1b243aee185952a8949e97996",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/64b478eb69f196e1b243aee185952a8949e97996",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035643020498689568",
"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/8870212957948708512/+/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:4e6ac4fe2bf29010",
"user_agent:luci-scheduler"
],
"updated_ts": "1599110567949362",
"url": "https://ci.chromium.org/b/8870212957948708512",
"utcnow_ts": "1599110568312896"
}
}
[05:22:48.323] Invocation finished in 2h10m26.980032353s with status SUCCEEDED