[13:49:18.185] New invocation is queued and will start shortly
[13:49:19.326] Starting the invocation (attempt 1)
[13:49:19.362] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:49:19.362] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:49:19.363] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9039045631979291616",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8b10bc20f5a16c1257a472c70afa0cd58c49a8be\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9039045631979291616",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/8b10bc20f5a16c1257a472c70afa0cd58c49a8be",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8b10bc20f5a16c1257a472c70afa0cd58c49a8be",
"gitiles_ref:refs/heads/master"
]
}
[13:49:22.637] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1595857759701567",
"id": "8873615569722224224",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8b10bc20f5a16c1257a472c70afa0cd58c49a8be\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1595857762204633",
"tags": [
"build_address:luci.chromium.ci/win-official/184",
"builder:win-official",
"buildset:commit/git/8b10bc20f5a16c1257a472c70afa0cd58c49a8be",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8b10bc20f5a16c1257a472c70afa0cd58c49a8be",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039045631979291616",
"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/8873615569722224224/+/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": "1595857762204791",
"url": "https://ci.chromium.org/b/8873615569722224224",
"utcnow_ts": "1595857762604996"
}
}
[13:49:22.637] Task URL: https://ci.chromium.org/b/8873615569722224224
[13:49:22.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:2:0) after 1m0s
[13:49:57.173] Received PubSub notification, asking Buildbucket for the build status
[13:49:57.712] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:22.660] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:2:0)
[13:50:22.660] Timer tick, asking Buildbucket for the build status
[13:50:22.772] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:22.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:4:0) after 1m0s
[13:51:22.873] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:4:0)
[13:51:22.873] Timer tick, asking Buildbucket for the build status
[13:51:23.055] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:23.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:5:0) after 1m0s
[13:52:23.159] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:5:0)
[13:52:23.159] Timer tick, asking Buildbucket for the build status
[13:52:23.302] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:23.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:6:0) after 1m0s
[13:53:23.392] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:6:0)
[13:53:23.392] Timer tick, asking Buildbucket for the build status
[13:53:23.486] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:23.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:7:0) after 1m0s
[13:54:23.633] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:7:0)
[13:54:23.634] Timer tick, asking Buildbucket for the build status
[13:54:23.905] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:23.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:8:0) after 1m0s
[13:55:24.043] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:8:0)
[13:55:24.043] Timer tick, asking Buildbucket for the build status
[13:55:24.295] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:24.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:9:0) after 1m0s
[13:56:25.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:9:0)
[13:56:25.601] Timer tick, asking Buildbucket for the build status
[13:56:26.204] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:26.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:10:0) after 1m0s
[13:57:26.488] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:10:0)
[13:57:26.488] Timer tick, asking Buildbucket for the build status
[13:57:26.558] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:26.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:11:0) after 1m0s
[13:58:26.675] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:11:0)
[13:58:26.675] Timer tick, asking Buildbucket for the build status
[13:58:26.848] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:26.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:12:0) after 1m0s
[13:59:27.003] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:12:0)
[13:59:27.003] Timer tick, asking Buildbucket for the build status
[13:59:27.195] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:27.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:13:0) after 1m0s
[14:00:27.235] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:13:0)
[14:00:27.235] Timer tick, asking Buildbucket for the build status
[14:00:27.406] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:27.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:14:0) after 1m0s
[14:01:27.535] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:14:0)
[14:01:27.535] Timer tick, asking Buildbucket for the build status
[14:01:27.877] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:27.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:15:0) after 1m0s
[14:02:27.879] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:15:0)
[14:02:27.879] Timer tick, asking Buildbucket for the build status
[14:02:28.039] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:28.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:16:0) after 1m0s
[14:03:28.198] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:16:0)
[14:03:28.198] Timer tick, asking Buildbucket for the build status
[14:03:28.488] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:28.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:17:0) after 1m0s
[14:04:28.563] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:17:0)
[14:04:28.563] Timer tick, asking Buildbucket for the build status
[14:04:28.726] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:28.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:18:0) after 1m0s
[14:05:28.927] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:18:0)
[14:05:28.927] Timer tick, asking Buildbucket for the build status
[14:05:29.059] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:29.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:19:0) after 1m0s
[14:06:29.108] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:19:0)
[14:06:29.108] Timer tick, asking Buildbucket for the build status
[14:06:32.005] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:32.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:20:0) after 1m0s
[14:07:32.141] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:20:0)
[14:07:32.141] Timer tick, asking Buildbucket for the build status
[14:07:32.302] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:32.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:21:0) after 1m0s
[14:08:32.412] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:21:0)
[14:08:32.412] Timer tick, asking Buildbucket for the build status
[14:08:32.584] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:32.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:22:0) after 1m0s
[14:09:32.867] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:22:0)
[14:09:32.867] Timer tick, asking Buildbucket for the build status
[14:09:33.161] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:33.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:23:0) after 1m0s
[14:10:33.303] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:23:0)
[14:10:33.303] Timer tick, asking Buildbucket for the build status
[14:10:33.490] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:33.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:24:0) after 1m0s
[14:11:33.552] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:24:0)
[14:11:33.552] Timer tick, asking Buildbucket for the build status
[14:11:33.658] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:33.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:25:0) after 1m0s
[14:12:33.694] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:25:0)
[14:12:33.694] Timer tick, asking Buildbucket for the build status
[14:12:33.767] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:33.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:26:0) after 1m0s
[14:13:33.792] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:26:0)
[14:13:33.792] Timer tick, asking Buildbucket for the build status
[14:13:33.932] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:33.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:27:0) after 1m0s
[14:14:34.003] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:27:0)
[14:14:34.003] Timer tick, asking Buildbucket for the build status
[14:14:34.143] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:34.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:28:0) after 1m0s
[14:15:34.128] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:28:0)
[14:15:34.128] Timer tick, asking Buildbucket for the build status
[14:15:34.647] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:34.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:29:0) after 1m0s
[14:16:34.743] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:29:0)
[14:16:34.743] Timer tick, asking Buildbucket for the build status
[14:16:36.184] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:36.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:30:0) after 1m0s
[14:17:36.248] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:30:0)
[14:17:36.248] Timer tick, asking Buildbucket for the build status
[14:17:36.337] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:36.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:31:0) after 1m0s
[14:18:36.450] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:31:0)
[14:18:36.450] Timer tick, asking Buildbucket for the build status
[14:18:36.537] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:36.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:32:0) after 1m0s
[14:19:36.627] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:32:0)
[14:19:36.627] Timer tick, asking Buildbucket for the build status
[14:19:36.764] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:36.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:33:0) after 1m0s
[14:20:36.776] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:33:0)
[14:20:36.776] Timer tick, asking Buildbucket for the build status
[14:20:36.951] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:36.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:34:0) after 1m0s
[14:21:36.984] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:34:0)
[14:21:36.984] Timer tick, asking Buildbucket for the build status
[14:21:37.074] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:37.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:35:0) after 1m0s
[14:22:37.075] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:35:0)
[14:22:37.075] Timer tick, asking Buildbucket for the build status
[14:22:37.155] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:37.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:36:0) after 1m0s
[14:23:37.267] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:36:0)
[14:23:37.267] Timer tick, asking Buildbucket for the build status
[14:23:37.341] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:37.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:37:0) after 1m0s
[14:24:37.364] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:37:0)
[14:24:37.364] Timer tick, asking Buildbucket for the build status
[14:24:37.526] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:37.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:38:0) after 1m0s
[14:25:37.623] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:38:0)
[14:25:37.623] Timer tick, asking Buildbucket for the build status
[14:25:37.775] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:37.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:39:0) after 1m0s
[14:26:37.758] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:39:0)
[14:26:37.758] Timer tick, asking Buildbucket for the build status
[14:26:37.848] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:37.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:40:0) after 1m0s
[14:27:37.892] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:40:0)
[14:27:37.892] Timer tick, asking Buildbucket for the build status
[14:27:37.963] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:37.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:41:0) after 1m0s
[14:28:38.022] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:41:0)
[14:28:38.022] Timer tick, asking Buildbucket for the build status
[14:28:38.094] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:38.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:42:0) after 1m0s
[14:29:38.126] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:42:0)
[14:29:38.126] Timer tick, asking Buildbucket for the build status
[14:29:38.244] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:38.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:43:0) after 1m0s
[14:30:38.268] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:43:0)
[14:30:38.268] Timer tick, asking Buildbucket for the build status
[14:30:38.422] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:38.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:44:0) after 1m0s
[14:31:38.442] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:44:0)
[14:31:38.442] Timer tick, asking Buildbucket for the build status
[14:31:38.607] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:38.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:45:0) after 1m0s
[14:32:38.692] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:45:0)
[14:32:38.692] Timer tick, asking Buildbucket for the build status
[14:32:38.848] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:38.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:46:0) after 1m0s
[14:33:38.924] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:46:0)
[14:33:38.924] Timer tick, asking Buildbucket for the build status
[14:33:39.129] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:39.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:47:0) after 1m0s
[14:34:39.122] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:47:0)
[14:34:39.122] Timer tick, asking Buildbucket for the build status
[14:34:39.237] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:39.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:48:0) after 1m0s
[14:35:39.237] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:48:0)
[14:35:39.237] Timer tick, asking Buildbucket for the build status
[14:35:39.457] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:39.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:49:0) after 1m0s
[14:36:39.483] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:49:0)
[14:36:39.483] Timer tick, asking Buildbucket for the build status
[14:36:39.605] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:39.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:50:0) after 1m0s
[14:37:39.630] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:50:0)
[14:37:39.630] Timer tick, asking Buildbucket for the build status
[14:37:39.733] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:39.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:51:0) after 1m0s
[14:38:39.759] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:51:0)
[14:38:39.759] Timer tick, asking Buildbucket for the build status
[14:38:40.018] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:40.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:52:0) after 1m0s
[14:39:40.109] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:52:0)
[14:39:40.109] Timer tick, asking Buildbucket for the build status
[14:39:40.316] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:40.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:53:0) after 1m0s
[14:40:40.470] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:53:0)
[14:40:40.470] Timer tick, asking Buildbucket for the build status
[14:40:40.598] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:40.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:54:0) after 1m0s
[14:41:40.622] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:54:0)
[14:41:40.622] Timer tick, asking Buildbucket for the build status
[14:41:40.806] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:40.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:55:0) after 1m0s
[14:42:40.895] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:55:0)
[14:42:40.895] Timer tick, asking Buildbucket for the build status
[14:42:41.107] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:41.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:56:0) after 1m0s
[14:43:41.142] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:56:0)
[14:43:41.142] Timer tick, asking Buildbucket for the build status
[14:43:41.661] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:41.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:57:0) after 1m0s
[14:44:41.721] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:57:0)
[14:44:41.721] Timer tick, asking Buildbucket for the build status
[14:44:42.082] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:42.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:58:0) after 1m0s
[14:45:42.179] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:58:0)
[14:45:42.179] Timer tick, asking Buildbucket for the build status
[14:45:42.250] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:42.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:59:0) after 1m0s
[14:46:42.389] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:59:0)
[14:46:42.389] Timer tick, asking Buildbucket for the build status
[14:46:42.536] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:42.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:60:0) after 1m0s
[14:47:42.627] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:60:0)
[14:47:42.627] Timer tick, asking Buildbucket for the build status
[14:47:42.693] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:42.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:61:0) after 1m0s
[14:48:42.696] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:61:0)
[14:48:42.696] Timer tick, asking Buildbucket for the build status
[14:48:42.812] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:42.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:62:0) after 1m0s
[14:49:42.835] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:62:0)
[14:49:42.835] Timer tick, asking Buildbucket for the build status
[14:49:43.004] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:43.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:63:0) after 1m0s
[14:50:43.041] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:63:0)
[14:50:43.041] Timer tick, asking Buildbucket for the build status
[14:50:43.148] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:43.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:64:0) after 1m0s
[14:51:43.242] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:64:0)
[14:51:43.242] Timer tick, asking Buildbucket for the build status
[14:51:43.305] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:43.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:65:0) after 1m0s
[14:52:43.385] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:65:0)
[14:52:43.385] Timer tick, asking Buildbucket for the build status
[14:52:43.663] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:43.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:66:0) after 1m0s
[14:53:43.731] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:66:0)
[14:53:43.731] Timer tick, asking Buildbucket for the build status
[14:53:43.886] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:43.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:67:0) after 1m0s
[14:54:43.924] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:67:0)
[14:54:43.924] Timer tick, asking Buildbucket for the build status
[14:54:44.132] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:44.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:68:0) after 1m0s
[14:55:44.153] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:68:0)
[14:55:44.153] Timer tick, asking Buildbucket for the build status
[14:55:44.316] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:44.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:69:0) after 1m0s
[14:56:44.346] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:69:0)
[14:56:44.346] Timer tick, asking Buildbucket for the build status
[14:56:44.563] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:44.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:70:0) after 1m0s
[14:57:44.596] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:70:0)
[14:57:44.596] Timer tick, asking Buildbucket for the build status
[14:57:44.673] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:44.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:71:0) after 1m0s
[14:58:44.686] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:71:0)
[14:58:44.686] Timer tick, asking Buildbucket for the build status
[14:58:44.767] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:44.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:72:0) after 1m0s
[14:59:44.821] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:72:0)
[14:59:44.821] Timer tick, asking Buildbucket for the build status
[14:59:44.983] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:44.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:73:0) after 1m0s
[15:00:45.101] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:73:0)
[15:00:45.101] Timer tick, asking Buildbucket for the build status
[15:00:45.314] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:45.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:74:0) after 1m0s
[15:01:45.354] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:74:0)
[15:01:45.354] Timer tick, asking Buildbucket for the build status
[15:01:45.497] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:45.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:75:0) after 1m0s
[15:02:45.568] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:75:0)
[15:02:45.568] Timer tick, asking Buildbucket for the build status
[15:02:45.657] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:45.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:76:0) after 1m0s
[15:03:45.699] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:76:0)
[15:03:45.699] Timer tick, asking Buildbucket for the build status
[15:03:45.798] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:45.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:77:0) after 1m0s
[15:04:45.898] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:77:0)
[15:04:45.898] Timer tick, asking Buildbucket for the build status
[15:04:46.238] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:46.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:78:0) after 1m0s
[15:05:46.279] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:78:0)
[15:05:46.279] Timer tick, asking Buildbucket for the build status
[15:05:46.479] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:46.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:79:0) after 1m0s
[15:06:46.507] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:79:0)
[15:06:46.507] Timer tick, asking Buildbucket for the build status
[15:06:46.577] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:46.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:80:0) after 1m0s
[15:07:46.670] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:80:0)
[15:07:46.670] Timer tick, asking Buildbucket for the build status
[15:07:46.742] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:46.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:81:0) after 1m0s
[15:08:46.771] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:81:0)
[15:08:46.772] Timer tick, asking Buildbucket for the build status
[15:08:46.863] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:46.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:82:0) after 1m0s
[15:09:46.886] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:82:0)
[15:09:46.886] Timer tick, asking Buildbucket for the build status
[15:09:47.499] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:47.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:83:0) after 1m0s
[15:10:47.567] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:83:0)
[15:10:47.567] Timer tick, asking Buildbucket for the build status
[15:10:47.654] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:47.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:84:0) after 1m0s
[15:11:47.715] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:84:0)
[15:11:47.715] Timer tick, asking Buildbucket for the build status
[15:11:47.817] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:47.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:85:0) after 1m0s
[15:12:47.896] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:85:0)
[15:12:47.896] Timer tick, asking Buildbucket for the build status
[15:12:48.032] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:48.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:86:0) after 1m0s
[15:13:48.069] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:86:0)
[15:13:48.069] Timer tick, asking Buildbucket for the build status
[15:13:48.184] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:48.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:87:0) after 1m0s
[15:14:48.185] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:87:0)
[15:14:48.185] Timer tick, asking Buildbucket for the build status
[15:14:48.366] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:48.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:88:0) after 1m0s
[15:15:48.400] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:88:0)
[15:15:48.400] Timer tick, asking Buildbucket for the build status
[15:15:48.571] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:48.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:89:0) after 1m0s
[15:16:48.603] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:89:0)
[15:16:48.603] Timer tick, asking Buildbucket for the build status
[15:16:48.701] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:48.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:90:0) after 1m0s
[15:17:48.733] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:90:0)
[15:17:48.733] Timer tick, asking Buildbucket for the build status
[15:17:48.836] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:48.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:91:0) after 1m0s
[15:18:48.862] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:91:0)
[15:18:48.862] Timer tick, asking Buildbucket for the build status
[15:18:48.952] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:48.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:92:0) after 1m0s
[15:19:49.007] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:92:0)
[15:19:49.007] Timer tick, asking Buildbucket for the build status
[15:19:49.322] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:49.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:93:0) after 1m0s
[15:20:49.356] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:93:0)
[15:20:49.356] Timer tick, asking Buildbucket for the build status
[15:20:49.444] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:49.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:94:0) after 1m0s
[15:21:49.507] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:94:0)
[15:21:49.507] Timer tick, asking Buildbucket for the build status
[15:21:49.832] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:49.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:95:0) after 1m0s
[15:22:49.862] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:95:0)
[15:22:49.862] Timer tick, asking Buildbucket for the build status
[15:22:49.942] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:49.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:96:0) after 1m0s
[15:23:50.026] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:96:0)
[15:23:50.026] Timer tick, asking Buildbucket for the build status
[15:23:50.209] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:50.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:97:0) after 1m0s
[15:24:50.199] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:97:0)
[15:24:50.199] Timer tick, asking Buildbucket for the build status
[15:24:50.334] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:50.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:98:0) after 1m0s
[15:25:50.400] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:98:0)
[15:25:50.400] Timer tick, asking Buildbucket for the build status
[15:25:50.474] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:50.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:99:0) after 1m0s
[15:26:50.530] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:99:0)
[15:26:50.530] Timer tick, asking Buildbucket for the build status
[15:26:50.806] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:50.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:100:0) after 1m0s
[15:27:50.833] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:100:0)
[15:27:50.833] Timer tick, asking Buildbucket for the build status
[15:27:51.106] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:51.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:101:0) after 1m0s
[15:28:51.138] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:101:0)
[15:28:51.138] Timer tick, asking Buildbucket for the build status
[15:28:51.222] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:51.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:102:0) after 1m0s
[15:29:51.259] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:102:0)
[15:29:51.259] Timer tick, asking Buildbucket for the build status
[15:29:51.731] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:51.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:103:0) after 1m0s
[15:30:51.776] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:103:0)
[15:30:51.776] Timer tick, asking Buildbucket for the build status
[15:30:51.847] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:51.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:104:0) after 1m0s
[15:31:51.905] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:104:0)
[15:31:51.905] Timer tick, asking Buildbucket for the build status
[15:31:51.975] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:51.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:105:0) after 1m0s
[15:32:51.976] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:105:0)
[15:32:51.976] Timer tick, asking Buildbucket for the build status
[15:32:52.066] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:52.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:106:0) after 1m0s
[15:33:52.101] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:106:0)
[15:33:52.101] Timer tick, asking Buildbucket for the build status
[15:33:52.176] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:52.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:107:0) after 1m0s
[15:34:52.732] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:107:0)
[15:34:52.732] Timer tick, asking Buildbucket for the build status
[15:34:53.198] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:53.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:108:0) after 1m0s
[15:35:53.525] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:108:0)
[15:35:53.525] Timer tick, asking Buildbucket for the build status
[15:35:53.882] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:53.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:109:0) after 1m0s
[15:36:54.302] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:109:0)
[15:36:54.302] Timer tick, asking Buildbucket for the build status
[15:36:54.425] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:54.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:110:0) after 1m0s
[15:37:54.592] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:110:0)
[15:37:54.592] Timer tick, asking Buildbucket for the build status
[15:37:54.659] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:54.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:111:0) after 1m0s
[15:38:54.694] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:111:0)
[15:38:54.694] Timer tick, asking Buildbucket for the build status
[15:38:54.759] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:54.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:112:0) after 1m0s
[15:39:54.848] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:112:0)
[15:39:54.848] Timer tick, asking Buildbucket for the build status
[15:39:55.256] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:55.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:113:0) after 1m0s
[15:40:55.488] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:113:0)
[15:40:55.488] Timer tick, asking Buildbucket for the build status
[15:40:55.716] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:55.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:114:0) after 1m0s
[15:41:55.813] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:114:0)
[15:41:55.813] Timer tick, asking Buildbucket for the build status
[15:41:55.875] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:55.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:115:0) after 1m0s
[15:42:56.015] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:115:0)
[15:42:56.015] Timer tick, asking Buildbucket for the build status
[15:42:56.169] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:56.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:116:0) after 1m0s
[15:43:56.357] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:116:0)
[15:43:56.357] Timer tick, asking Buildbucket for the build status
[15:43:56.460] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:56.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:117:0) after 1m0s
[15:44:56.628] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:117:0)
[15:44:56.628] Timer tick, asking Buildbucket for the build status
[15:44:59.970] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:59.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:118:0) after 1m0s
[15:46:00.080] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:118:0)
[15:46:00.080] Timer tick, asking Buildbucket for the build status
[15:46:00.254] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:00.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:119:0) after 1m0s
[15:47:00.351] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:119:0)
[15:47:00.351] Timer tick, asking Buildbucket for the build status
[15:47:00.615] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:00.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:120:0) after 1m0s
[15:48:00.728] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:120:0)
[15:48:00.728] Timer tick, asking Buildbucket for the build status
[15:48:00.869] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:00.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:121:0) after 1m0s
[15:49:00.934] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:121:0)
[15:49:00.934] Timer tick, asking Buildbucket for the build status
[15:49:01.007] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:01.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:122:0) after 1m0s
[15:50:01.099] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:122:0)
[15:50:01.099] Timer tick, asking Buildbucket for the build status
[15:50:01.247] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:01.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:123:0) after 1m0s
[15:51:01.307] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:123:0)
[15:51:01.307] Timer tick, asking Buildbucket for the build status
[15:51:01.553] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:01.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:124:0) after 1m0s
[15:52:01.579] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:124:0)
[15:52:01.579] Timer tick, asking Buildbucket for the build status
[15:52:01.937] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:01.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:125:0) after 1m0s
[15:53:02.124] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:125:0)
[15:53:02.124] Timer tick, asking Buildbucket for the build status
[15:53:02.266] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:02.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:126:0) after 1m0s
[15:54:02.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:126:0)
[15:54:02.601] Timer tick, asking Buildbucket for the build status
[15:54:02.665] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:02.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:127:0) after 1m0s
[15:55:02.919] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:127:0)
[15:55:02.919] Timer tick, asking Buildbucket for the build status
[15:55:03.074] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:03.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:128:0) after 1m0s
[15:56:03.264] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:128:0)
[15:56:03.264] Timer tick, asking Buildbucket for the build status
[15:56:03.466] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:03.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:129:0) after 1m0s
[15:57:03.585] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:129:0)
[15:57:03.585] Timer tick, asking Buildbucket for the build status
[15:57:03.781] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:03.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:130:0) after 1m0s
[15:58:03.899] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:130:0)
[15:58:03.899] Timer tick, asking Buildbucket for the build status
[15:58:03.976] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:03.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:131:0) after 1m0s
[15:59:04.180] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:131:0)
[15:59:04.180] Timer tick, asking Buildbucket for the build status
[15:59:04.263] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:04.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:132:0) after 1m0s
[16:00:04.418] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:132:0)
[16:00:04.418] Timer tick, asking Buildbucket for the build status
[16:00:06.027] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:06.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:133:0) after 1m0s
[16:01:06.527] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:133:0)
[16:01:06.527] Timer tick, asking Buildbucket for the build status
[16:01:06.616] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:06.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:134:0) after 1m0s
[16:02:06.787] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:134:0)
[16:02:06.787] Timer tick, asking Buildbucket for the build status
[16:02:08.237] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:08.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:135:0) after 1m0s
[16:03:08.281] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:135:0)
[16:03:08.281] Timer tick, asking Buildbucket for the build status
[16:03:08.404] Build 8873615569722224224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:08.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039045631979291616:136:0) after 1m0s
[16:03:35.698] Received PubSub notification, asking Buildbucket for the build status
[16:03:35.857] Build 8873615569722224224: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:03:35.857] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1595865814645832",
"created_by": "project:chromium",
"created_ts": "1595857759701567",
"id": "8873615569722224224",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8b10bc20f5a16c1257a472c70afa0cd58c49a8be\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/chromium_tests\": {\"bucketed_triggers\": true}, \"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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-4-oarx\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 184, \"got_angle_revision\": \"3f851efa2adcaf4303d6d11b4612e5c46c08b661\", \"got_dawn_revision\": \"d5a4775cfc77da29870286a217ea49e4ab3270a3\", \"got_nacl_revision\": \"fd20fafa8ec19700e12daa031bf792bae8b6bb21\", \"got_revision\": \"8b10bc20f5a16c1257a472c70afa0cd58c49a8be\", \"got_revision_cp\": \"refs/heads/master@{#791797}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"3b2cd31c740071d7272029f6fbcef43ffc200f12\", \"got_v8_revision\": \"f23e4df55db0c7def040699deeb9c8c6003bb8aa\", \"got_v8_revision_cp\": \"refs/heads/8.6.239@{#1}\", \"got_webrtc_revision\": \"60cbf70944cf845781c14ee176cec26c61aa502a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31788}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8b10bc20f5a16c1257a472c70afa0cd58c49a8be\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-4-oarx\"], \"image\": [\"chrome-win10-1703-20071600-faa8609efbc\"], \"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\": [\"5434-62f16a1\"], \"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": "1595857796456003",
"status": "COMPLETED",
"status_changed_ts": "1595865815160383",
"tags": [
"build_address:luci.chromium.ci/win-official/184",
"builder:win-official",
"buildset:commit/git/8b10bc20f5a16c1257a472c70afa0cd58c49a8be",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8b10bc20f5a16c1257a472c70afa0cd58c49a8be",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039045631979291616",
"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/8873615569722224224/+/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:4da95a823cf05a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1595865815209478",
"url": "https://ci.chromium.org/b/8873615569722224224",
"utcnow_ts": "1595865815849265"
}
}
[16:03:35.857] Invocation finished in 2h14m17.691432629s with status SUCCEEDED