[09:24:50.496] New invocation is queued and will start shortly
[09:24:51.602] Starting the invocation (attempt 1)
[09:24:51.645] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:24:51.645] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:24:51.645] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022392428638966608",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1996f74b30b833784236f05faaafe725f529d530\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9022392428638966608",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/1996f74b30b833784236f05faaafe725f529d530",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1996f74b30b833784236f05faaafe725f529d530",
"gitiles_ref:refs/heads/master"
]
}
[09:24:52.275] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611739491763879",
"id": "8856962366643440800",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1996f74b30b833784236f05faaafe725f529d530\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611739492153569",
"tags": [
"build_address:luci.chromium.ci/win-official/1474",
"builder:win-official",
"buildset:commit/git/1996f74b30b833784236f05faaafe725f529d530",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1996f74b30b833784236f05faaafe725f529d530",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022392428638966608",
"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/8856962366643440800/+/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": "1611739492153682",
"url": "https://ci.chromium.org/b/8856962366643440800",
"utcnow_ts": "1611739492268652"
}
}
[09:24:52.275] Task URL: https://ci.chromium.org/b/8856962366643440800
[09:24:52.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:2:0) after 1m0s
[09:25:39.113] Received PubSub notification, asking Buildbucket for the build status
[09:25:39.323] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:52.314] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:2:0)
[09:25:52.314] Timer tick, asking Buildbucket for the build status
[09:25:52.372] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:52.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:4:0) after 1m0s
[09:26:52.500] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:4:0)
[09:26:52.500] Timer tick, asking Buildbucket for the build status
[09:26:52.595] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:52.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:5:0) after 1m0s
[09:27:52.687] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:5:0)
[09:27:52.687] Timer tick, asking Buildbucket for the build status
[09:27:53.315] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:53.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:6:0) after 1m0s
[09:28:53.348] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:6:0)
[09:28:53.348] Timer tick, asking Buildbucket for the build status
[09:28:53.517] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:53.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:7:0) after 1m0s
[09:29:53.552] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:7:0)
[09:29:53.552] Timer tick, asking Buildbucket for the build status
[09:29:53.663] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:53.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:8:0) after 1m0s
[09:30:53.685] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:8:0)
[09:30:53.685] Timer tick, asking Buildbucket for the build status
[09:30:53.775] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:53.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:9:0) after 1m0s
[09:31:53.825] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:9:0)
[09:31:53.825] Timer tick, asking Buildbucket for the build status
[09:31:53.923] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:53.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:10:0) after 1m0s
[09:32:53.991] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:10:0)
[09:32:53.991] Timer tick, asking Buildbucket for the build status
[09:32:54.111] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:54.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:11:0) after 1m0s
[09:33:54.167] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:11:0)
[09:33:54.167] Timer tick, asking Buildbucket for the build status
[09:33:56.652] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:56.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:12:0) after 1m0s
[09:34:56.720] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:12:0)
[09:34:56.720] Timer tick, asking Buildbucket for the build status
[09:34:56.882] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:56.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:13:0) after 1m0s
[09:35:57.027] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:13:0)
[09:35:57.027] Timer tick, asking Buildbucket for the build status
[09:35:57.153] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:57.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:14:0) after 1m0s
[09:36:57.209] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:14:0)
[09:36:57.209] Timer tick, asking Buildbucket for the build status
[09:36:57.330] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:57.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:15:0) after 1m0s
[09:37:57.400] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:15:0)
[09:37:57.400] Timer tick, asking Buildbucket for the build status
[09:37:57.625] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:57.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:16:0) after 1m0s
[09:38:57.618] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:16:0)
[09:38:57.618] Timer tick, asking Buildbucket for the build status
[09:38:57.901] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:57.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:17:0) after 1m0s
[09:39:57.964] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:17:0)
[09:39:57.964] Timer tick, asking Buildbucket for the build status
[09:39:58.051] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:58.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:18:0) after 1m0s
[09:40:58.549] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:18:0)
[09:40:58.549] Timer tick, asking Buildbucket for the build status
[09:40:58.624] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:58.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:19:0) after 1m0s
[09:41:58.629] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:19:0)
[09:41:58.629] Timer tick, asking Buildbucket for the build status
[09:41:58.714] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:58.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:20:0) after 1m0s
[09:42:58.892] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:20:0)
[09:42:58.892] Timer tick, asking Buildbucket for the build status
[09:42:59.103] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:59.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:21:0) after 1m0s
[09:43:59.476] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:21:0)
[09:43:59.476] Timer tick, asking Buildbucket for the build status
[09:43:59.571] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:59.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:22:0) after 1m0s
[09:44:59.708] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:22:0)
[09:44:59.708] Timer tick, asking Buildbucket for the build status
[09:44:59.810] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:59.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:23:0) after 1m0s
[09:45:59.876] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:23:0)
[09:45:59.876] Timer tick, asking Buildbucket for the build status
[09:45:59.930] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:59.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:24:0) after 1m0s
[09:47:00.065] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:24:0)
[09:47:00.065] Timer tick, asking Buildbucket for the build status
[09:47:00.378] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:00.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:25:0) after 1m0s
[09:48:00.497] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:25:0)
[09:48:00.497] Timer tick, asking Buildbucket for the build status
[09:48:00.554] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:00.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:26:0) after 1m0s
[09:49:00.597] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:26:0)
[09:49:00.597] Timer tick, asking Buildbucket for the build status
[09:49:00.705] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:00.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:27:0) after 1m0s
[09:50:01.143] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:27:0)
[09:50:01.143] Timer tick, asking Buildbucket for the build status
[09:50:01.263] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:01.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:28:0) after 1m0s
[09:51:01.471] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:28:0)
[09:51:01.471] Timer tick, asking Buildbucket for the build status
[09:51:01.558] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:01.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:29:0) after 1m0s
[09:52:01.952] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:29:0)
[09:52:01.952] Timer tick, asking Buildbucket for the build status
[09:52:04.039] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:04.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:30:0) after 1m0s
[09:53:04.084] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:30:0)
[09:53:04.084] Timer tick, asking Buildbucket for the build status
[09:53:04.163] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:04.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:31:0) after 1m0s
[09:54:04.204] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:31:0)
[09:54:04.204] Timer tick, asking Buildbucket for the build status
[09:54:04.268] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:04.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:32:0) after 1m0s
[09:55:04.521] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:32:0)
[09:55:04.521] Timer tick, asking Buildbucket for the build status
[09:55:05.821] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:05.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:33:0) after 1m0s
[09:56:05.853] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:33:0)
[09:56:05.853] Timer tick, asking Buildbucket for the build status
[09:56:06.039] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:06.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:34:0) after 1m0s
[09:57:06.109] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:34:0)
[09:57:06.109] Timer tick, asking Buildbucket for the build status
[09:57:06.189] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:06.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:35:0) after 1m0s
[09:58:06.232] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:35:0)
[09:58:06.232] Timer tick, asking Buildbucket for the build status
[09:58:06.383] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:06.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:36:0) after 1m0s
[09:59:06.405] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:36:0)
[09:59:06.405] Timer tick, asking Buildbucket for the build status
[09:59:06.474] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:06.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:37:0) after 1m0s
[10:00:06.506] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:37:0)
[10:00:06.506] Timer tick, asking Buildbucket for the build status
[10:00:06.798] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:06.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:38:0) after 1m0s
[10:01:06.902] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:38:0)
[10:01:06.902] Timer tick, asking Buildbucket for the build status
[10:01:06.990] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:06.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:39:0) after 1m0s
[10:02:06.994] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:39:0)
[10:02:06.994] Timer tick, asking Buildbucket for the build status
[10:02:07.378] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:07.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:40:0) after 1m0s
[10:03:07.517] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:40:0)
[10:03:07.517] Timer tick, asking Buildbucket for the build status
[10:03:07.621] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:07.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:41:0) after 1m0s
[10:04:07.734] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:41:0)
[10:04:07.734] Timer tick, asking Buildbucket for the build status
[10:04:07.897] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:07.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:42:0) after 1m0s
[10:05:08.021] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:42:0)
[10:05:08.021] Timer tick, asking Buildbucket for the build status
[10:05:08.173] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:08.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:43:0) after 1m0s
[10:06:08.291] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:43:0)
[10:06:08.291] Timer tick, asking Buildbucket for the build status
[10:06:08.656] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:08.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:44:0) after 1m0s
[10:07:08.753] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:44:0)
[10:07:08.753] Timer tick, asking Buildbucket for the build status
[10:07:11.596] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:11.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:45:0) after 1m0s
[10:08:12.051] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:45:0)
[10:08:12.051] Timer tick, asking Buildbucket for the build status
[10:08:12.352] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:12.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:46:0) after 1m0s
[10:09:12.593] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:46:0)
[10:09:12.593] Timer tick, asking Buildbucket for the build status
[10:09:13.063] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:13.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:47:0) after 1m0s
[10:10:13.097] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:47:0)
[10:10:13.097] Timer tick, asking Buildbucket for the build status
[10:10:14.423] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:14.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:48:0) after 1m0s
[10:11:14.496] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:48:0)
[10:11:14.496] Timer tick, asking Buildbucket for the build status
[10:11:14.591] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:14.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:49:0) after 1m0s
[10:12:14.646] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:49:0)
[10:12:14.646] Timer tick, asking Buildbucket for the build status
[10:12:14.732] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:14.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:50:0) after 1m0s
[10:13:14.779] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:50:0)
[10:13:14.779] Timer tick, asking Buildbucket for the build status
[10:13:14.837] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:14.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:51:0) after 1m0s
[10:14:14.891] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:51:0)
[10:14:14.891] Timer tick, asking Buildbucket for the build status
[10:14:15.327] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:15.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:52:0) after 1m0s
[10:15:15.345] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:52:0)
[10:15:15.345] Timer tick, asking Buildbucket for the build status
[10:15:15.497] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:15.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:53:0) after 1m0s
[10:16:15.576] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:53:0)
[10:16:15.576] Timer tick, asking Buildbucket for the build status
[10:16:15.863] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:15.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:54:0) after 1m0s
[10:17:16.009] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:54:0)
[10:17:16.009] Timer tick, asking Buildbucket for the build status
[10:17:16.082] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:16.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:55:0) after 1m0s
[10:18:16.213] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:55:0)
[10:18:16.213] Timer tick, asking Buildbucket for the build status
[10:18:16.384] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:16.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:56:0) after 1m0s
[10:19:16.424] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:56:0)
[10:19:16.424] Timer tick, asking Buildbucket for the build status
[10:19:16.551] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:16.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:57:0) after 1m0s
[10:20:16.795] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:57:0)
[10:20:16.795] Timer tick, asking Buildbucket for the build status
[10:20:17.404] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:17.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:58:0) after 1m0s
[10:21:17.470] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:58:0)
[10:21:17.470] Timer tick, asking Buildbucket for the build status
[10:21:17.559] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:17.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:59:0) after 1m0s
[10:22:17.577] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:59:0)
[10:22:17.577] Timer tick, asking Buildbucket for the build status
[10:22:17.642] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:17.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:60:0) after 1m0s
[10:23:17.676] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:60:0)
[10:23:17.676] Timer tick, asking Buildbucket for the build status
[10:23:17.826] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:17.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:61:0) after 1m0s
[10:24:17.836] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:61:0)
[10:24:17.836] Timer tick, asking Buildbucket for the build status
[10:24:17.896] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:17.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:62:0) after 1m0s
[10:25:18.080] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:62:0)
[10:25:18.080] Timer tick, asking Buildbucket for the build status
[10:25:18.236] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:18.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:63:0) after 1m0s
[10:26:18.334] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:63:0)
[10:26:18.334] Timer tick, asking Buildbucket for the build status
[10:26:18.402] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:18.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:64:0) after 1m0s
[10:27:18.483] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:64:0)
[10:27:18.483] Timer tick, asking Buildbucket for the build status
[10:27:18.549] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:18.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:65:0) after 1m0s
[10:28:18.574] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:65:0)
[10:28:18.574] Timer tick, asking Buildbucket for the build status
[10:28:18.848] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:18.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:66:0) after 1m0s
[10:29:18.912] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:66:0)
[10:29:18.912] Timer tick, asking Buildbucket for the build status
[10:29:19.187] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:19.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:67:0) after 1m0s
[10:30:19.412] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:67:0)
[10:30:19.412] Timer tick, asking Buildbucket for the build status
[10:30:19.879] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:19.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:68:0) after 1m0s
[10:31:20.019] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:68:0)
[10:31:20.019] Timer tick, asking Buildbucket for the build status
[10:31:22.728] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:22.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:69:0) after 1m0s
[10:32:22.898] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:69:0)
[10:32:22.898] Timer tick, asking Buildbucket for the build status
[10:32:23.189] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:23.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:70:0) after 1m0s
[10:33:23.381] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:70:0)
[10:33:23.381] Timer tick, asking Buildbucket for the build status
[10:33:23.495] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:23.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:71:0) after 1m0s
[10:34:23.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:71:0)
[10:34:23.555] Timer tick, asking Buildbucket for the build status
[10:34:23.833] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:23.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:72:0) after 1m0s
[10:35:23.949] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:72:0)
[10:35:23.949] Timer tick, asking Buildbucket for the build status
[10:35:24.087] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:24.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:73:0) after 1m0s
[10:36:24.171] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:73:0)
[10:36:24.171] Timer tick, asking Buildbucket for the build status
[10:36:24.279] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:24.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:74:0) after 1m0s
[10:37:24.323] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:74:0)
[10:37:24.323] Timer tick, asking Buildbucket for the build status
[10:37:24.437] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:24.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:75:0) after 1m0s
[10:38:24.618] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:75:0)
[10:38:24.618] Timer tick, asking Buildbucket for the build status
[10:38:24.710] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:24.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:76:0) after 1m0s
[10:39:24.834] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:76:0)
[10:39:24.834] Timer tick, asking Buildbucket for the build status
[10:39:24.908] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:24.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:77:0) after 1m0s
[10:40:25.067] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:77:0)
[10:40:25.067] Timer tick, asking Buildbucket for the build status
[10:40:25.261] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:25.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:78:0) after 1m0s
[10:41:25.349] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:78:0)
[10:41:25.349] Timer tick, asking Buildbucket for the build status
[10:41:25.433] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:25.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:79:0) after 1m0s
[10:42:25.490] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:79:0)
[10:42:25.490] Timer tick, asking Buildbucket for the build status
[10:42:25.544] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:25.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:80:0) after 1m0s
[10:43:25.632] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:80:0)
[10:43:25.632] Timer tick, asking Buildbucket for the build status
[10:43:25.693] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:25.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:81:0) after 1m0s
[10:44:25.765] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:81:0)
[10:44:25.765] Timer tick, asking Buildbucket for the build status
[10:44:25.817] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:25.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:82:0) after 1m0s
[10:45:25.890] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:82:0)
[10:45:25.890] Timer tick, asking Buildbucket for the build status
[10:45:26.034] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:26.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:83:0) after 1m0s
[10:46:26.116] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:83:0)
[10:46:26.116] Timer tick, asking Buildbucket for the build status
[10:46:26.280] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:26.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:84:0) after 1m0s
[10:47:26.272] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:84:0)
[10:47:26.272] Timer tick, asking Buildbucket for the build status
[10:47:26.329] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:26.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:85:0) after 1m0s
[10:48:26.400] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:85:0)
[10:48:26.400] Timer tick, asking Buildbucket for the build status
[10:48:26.468] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:26.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:86:0) after 1m0s
[10:49:26.618] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:86:0)
[10:49:26.618] Timer tick, asking Buildbucket for the build status
[10:49:27.111] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:27.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:87:0) after 1m0s
[10:50:27.247] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:87:0)
[10:50:27.247] Timer tick, asking Buildbucket for the build status
[10:50:27.375] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:27.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:88:0) after 1m0s
[10:51:27.619] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:88:0)
[10:51:27.619] Timer tick, asking Buildbucket for the build status
[10:51:30.028] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:30.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:89:0) after 1m0s
[10:52:30.170] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:89:0)
[10:52:30.170] Timer tick, asking Buildbucket for the build status
[10:52:30.240] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:30.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:90:0) after 1m0s
[10:53:30.315] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:90:0)
[10:53:30.315] Timer tick, asking Buildbucket for the build status
[10:53:30.391] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:30.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:91:0) after 1m0s
[10:54:30.498] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:91:0)
[10:54:30.498] Timer tick, asking Buildbucket for the build status
[10:54:30.685] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:30.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:92:0) after 1m0s
[10:55:30.814] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:92:0)
[10:55:30.814] Timer tick, asking Buildbucket for the build status
[10:55:33.482] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:33.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:93:0) after 1m0s
[10:56:33.471] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:93:0)
[10:56:33.471] Timer tick, asking Buildbucket for the build status
[10:56:33.545] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:33.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:94:0) after 1m0s
[10:57:33.574] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:94:0)
[10:57:33.574] Timer tick, asking Buildbucket for the build status
[10:57:33.802] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:33.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:95:0) after 1m0s
[10:58:33.866] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:95:0)
[10:58:33.866] Timer tick, asking Buildbucket for the build status
[10:58:33.960] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:33.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:96:0) after 1m0s
[10:59:34.003] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:96:0)
[10:59:34.003] Timer tick, asking Buildbucket for the build status
[10:59:34.061] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:34.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:97:0) after 1m0s
[11:00:34.076] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:97:0)
[11:00:34.076] Timer tick, asking Buildbucket for the build status
[11:00:34.507] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:34.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:98:0) after 1m0s
[11:01:34.533] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:98:0)
[11:01:34.533] Timer tick, asking Buildbucket for the build status
[11:01:34.819] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:34.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:99:0) after 1m0s
[11:02:34.932] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:99:0)
[11:02:34.932] Timer tick, asking Buildbucket for the build status
[11:02:35.332] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:35.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:100:0) after 1m0s
[11:03:35.430] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:100:0)
[11:03:35.430] Timer tick, asking Buildbucket for the build status
[11:03:35.498] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:35.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:101:0) after 1m0s
[11:04:35.526] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:101:0)
[11:04:35.527] Timer tick, asking Buildbucket for the build status
[11:04:36.544] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:36.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:102:0) after 1m0s
[11:05:36.600] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:102:0)
[11:05:36.600] Timer tick, asking Buildbucket for the build status
[11:05:36.770] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:36.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:103:0) after 1m0s
[11:06:36.926] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:103:0)
[11:06:36.926] Timer tick, asking Buildbucket for the build status
[11:06:39.608] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:39.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:104:0) after 1m0s
[11:07:40.138] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:104:0)
[11:07:40.138] Timer tick, asking Buildbucket for the build status
[11:07:40.318] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:40.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:105:0) after 1m0s
[11:08:40.875] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:105:0)
[11:08:40.875] Timer tick, asking Buildbucket for the build status
[11:08:40.952] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:40.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:106:0) after 1m0s
[11:09:41.250] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:106:0)
[11:09:41.250] Timer tick, asking Buildbucket for the build status
[11:09:41.380] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:41.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:107:0) after 1m0s
[11:10:41.671] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:107:0)
[11:10:41.671] Timer tick, asking Buildbucket for the build status
[11:10:41.864] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:41.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:108:0) after 1m0s
[11:11:41.993] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:108:0)
[11:11:41.993] Timer tick, asking Buildbucket for the build status
[11:11:42.074] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:42.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:109:0) after 1m0s
[11:12:42.396] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:109:0)
[11:12:42.396] Timer tick, asking Buildbucket for the build status
[11:12:42.489] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:42.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:110:0) after 1m0s
[11:13:42.749] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:110:0)
[11:13:42.749] Timer tick, asking Buildbucket for the build status
[11:13:42.974] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:42.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:111:0) after 1m0s
[11:14:43.052] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:111:0)
[11:14:43.052] Timer tick, asking Buildbucket for the build status
[11:14:43.125] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:43.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:112:0) after 1m0s
[11:15:43.336] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:112:0)
[11:15:43.336] Timer tick, asking Buildbucket for the build status
[11:15:43.479] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:43.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:113:0) after 1m0s
[11:16:43.671] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:113:0)
[11:16:43.671] Timer tick, asking Buildbucket for the build status
[11:16:43.976] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:43.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:114:0) after 1m0s
[11:17:44.064] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:114:0)
[11:17:44.064] Timer tick, asking Buildbucket for the build status
[11:17:44.180] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:44.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:115:0) after 1m0s
[11:18:44.210] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:115:0)
[11:18:44.210] Timer tick, asking Buildbucket for the build status
[11:18:44.279] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:44.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:116:0) after 1m0s
[11:19:44.436] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:116:0)
[11:19:44.436] Timer tick, asking Buildbucket for the build status
[11:19:44.505] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:44.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:117:0) after 1m0s
[11:20:44.920] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:117:0)
[11:20:44.920] Timer tick, asking Buildbucket for the build status
[11:20:45.411] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:45.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:118:0) after 1m0s
[11:21:45.475] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:118:0)
[11:21:45.475] Timer tick, asking Buildbucket for the build status
[11:21:45.547] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:45.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:119:0) after 1m0s
[11:22:45.582] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:119:0)
[11:22:45.582] Timer tick, asking Buildbucket for the build status
[11:22:45.855] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:45.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:120:0) after 1m0s
[11:23:45.880] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:120:0)
[11:23:45.880] Timer tick, asking Buildbucket for the build status
[11:23:46.092] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:46.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:121:0) after 1m0s
[11:24:46.122] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:121:0)
[11:24:46.122] Timer tick, asking Buildbucket for the build status
[11:24:46.262] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:46.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:122:0) after 1m0s
[11:25:46.278] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:122:0)
[11:25:46.278] Timer tick, asking Buildbucket for the build status
[11:25:46.475] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:46.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:123:0) after 1m0s
[11:26:46.564] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:123:0)
[11:26:46.564] Timer tick, asking Buildbucket for the build status
[11:26:46.639] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:46.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:124:0) after 1m0s
[11:27:46.651] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:124:0)
[11:27:46.651] Timer tick, asking Buildbucket for the build status
[11:27:46.797] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:46.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:125:0) after 1m0s
[11:28:46.821] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:125:0)
[11:28:46.821] Timer tick, asking Buildbucket for the build status
[11:28:46.889] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:46.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:126:0) after 1m0s
[11:29:46.866] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:126:0)
[11:29:46.866] Timer tick, asking Buildbucket for the build status
[11:29:46.962] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:46.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:127:0) after 1m0s
[11:30:47.035] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:127:0)
[11:30:47.035] Timer tick, asking Buildbucket for the build status
[11:30:47.556] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:47.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:128:0) after 1m0s
[11:31:47.586] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:128:0)
[11:31:47.586] Timer tick, asking Buildbucket for the build status
[11:31:47.657] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:47.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:129:0) after 1m0s
[11:32:47.693] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:129:0)
[11:32:47.694] Timer tick, asking Buildbucket for the build status
[11:32:47.758] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:47.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:130:0) after 1m0s
[11:33:47.779] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:130:0)
[11:33:47.779] Timer tick, asking Buildbucket for the build status
[11:33:47.844] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:47.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:131:0) after 1m0s
[11:34:47.941] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:131:0)
[11:34:47.941] Timer tick, asking Buildbucket for the build status
[11:34:48.173] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:48.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:132:0) after 1m0s
[11:35:48.247] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:132:0)
[11:35:48.247] Timer tick, asking Buildbucket for the build status
[11:35:48.567] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:48.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:133:0) after 1m0s
[11:36:48.622] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:133:0)
[11:36:48.622] Timer tick, asking Buildbucket for the build status
[11:36:48.729] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:48.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:134:0) after 1m0s
[11:37:48.785] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:134:0)
[11:37:48.785] Timer tick, asking Buildbucket for the build status
[11:37:48.925] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:48.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:135:0) after 1m0s
[11:38:49.015] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:135:0)
[11:38:49.015] Timer tick, asking Buildbucket for the build status
[11:38:49.118] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:49.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:136:0) after 1m0s
[11:39:49.368] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:136:0)
[11:39:49.368] Timer tick, asking Buildbucket for the build status
[11:39:49.509] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:49.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:137:0) after 1m0s
[11:40:49.602] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:137:0)
[11:40:49.603] Timer tick, asking Buildbucket for the build status
[11:40:49.723] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:49.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:138:0) after 1m0s
[11:41:49.721] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:138:0)
[11:41:49.721] Timer tick, asking Buildbucket for the build status
[11:41:49.779] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:49.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:139:0) after 1m0s
[11:42:49.847] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:139:0)
[11:42:49.847] Timer tick, asking Buildbucket for the build status
[11:42:49.903] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:49.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:140:0) after 1m0s
[11:43:50.036] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:140:0)
[11:43:50.036] Timer tick, asking Buildbucket for the build status
[11:43:50.110] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:50.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:141:0) after 1m0s
[11:44:50.211] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:141:0)
[11:44:50.211] Timer tick, asking Buildbucket for the build status
[11:44:50.282] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:50.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:142:0) after 1m0s
[11:45:50.358] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:142:0)
[11:45:50.358] Timer tick, asking Buildbucket for the build status
[11:45:50.625] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:50.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:143:0) after 1m0s
[11:46:50.719] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:143:0)
[11:46:50.719] Timer tick, asking Buildbucket for the build status
[11:46:50.804] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:50.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:144:0) after 1m0s
[11:47:50.899] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:144:0)
[11:47:50.899] Timer tick, asking Buildbucket for the build status
[11:47:50.969] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:50.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:145:0) after 1m0s
[11:48:51.113] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:145:0)
[11:48:51.113] Timer tick, asking Buildbucket for the build status
[11:48:51.182] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:51.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:146:0) after 1m0s
[11:49:51.327] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:146:0)
[11:49:51.327] Timer tick, asking Buildbucket for the build status
[11:49:51.442] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:51.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:147:0) after 1m0s
[11:50:51.554] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:147:0)
[11:50:51.554] Timer tick, asking Buildbucket for the build status
[11:50:51.709] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:51.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:148:0) after 1m0s
[11:51:51.877] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:148:0)
[11:51:51.877] Timer tick, asking Buildbucket for the build status
[11:51:51.995] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:51.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:149:0) after 1m0s
[11:52:52.079] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:149:0)
[11:52:52.079] Timer tick, asking Buildbucket for the build status
[11:52:52.595] Build 8856962366643440800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:52.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022392428638966608:150:0) after 1m0s
[11:53:10.724] Received PubSub notification, asking Buildbucket for the build status
[11:53:10.816] Build 8856962366643440800: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:53:10.816] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611748389660123",
"created_by": "project:chromium",
"created_ts": "1611739491763879",
"id": "8856962366643440800",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1996f74b30b833784236f05faaafe725f529d530\"}}",
"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\": \"1996f74b30b833784236f05faaafe725f529d530\", \"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-7-y9nh\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1474, \"got_angle_revision\": \"c38413f86cb98f088043c05eac900e7a9986d52a\", \"got_dawn_revision\": \"2df77f4325841e667e6b9ff9921fd626496a3b64\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"1996f74b30b833784236f05faaafe725f529d530\", \"got_revision_cp\": \"refs/heads/master@{#847551}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"aec0826aba0db51914209ec85a515d3a99311f07\", \"got_v8_revision\": \"ca438ce0794e4f833cc87f59232139df05f0ae73\", \"got_v8_revision_cp\": \"refs/heads/9.0.84@{#1}\", \"got_webrtc_revision\": \"fae4fb13451bea44ae1a94509befe8d7c6b11eb6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33077}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1996f74b30b833784236f05faaafe725f529d530\"}, \"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-7-y9nh\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5672-4713de1\"], \"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": "1611739538373765",
"status": "COMPLETED",
"status_changed_ts": "1611748390415982",
"tags": [
"build_address:luci.chromium.ci/win-official/1474",
"builder:win-official",
"buildset:commit/git/1996f74b30b833784236f05faaafe725f529d530",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1996f74b30b833784236f05faaafe725f529d530",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022392428638966608",
"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/8856962366643440800/+/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:515bfa788c82bb10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611748390426646",
"url": "https://ci.chromium.org/b/8856962366643440800",
"utcnow_ts": "1611748390802248"
}
}
[11:53:10.816] Invocation finished in 2h28m20.339229048s with status SUCCEEDED