[21:48:43.318] New invocation is queued and will start shortly
[21:48:44.975] Starting the invocation (attempt 1)
[21:48:45.029] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:48:45.030] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:48:45.030] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022255030762363664",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b24d1a3871cf07bd0ea002ba6bc79890558bb0c2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9022255030762363664",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/b24d1a3871cf07bd0ea002ba6bc79890558bb0c2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b24d1a3871cf07bd0ea002ba6bc79890558bb0c2",
"gitiles_ref:refs/heads/master"
]
}
[21:48:45.937] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611870525155104",
"id": "8856824968172984832",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b24d1a3871cf07bd0ea002ba6bc79890558bb0c2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611870525657014",
"tags": [
"build_address:luci.chromium.ci/win-official/1486",
"builder:win-official",
"buildset:commit/git/b24d1a3871cf07bd0ea002ba6bc79890558bb0c2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b24d1a3871cf07bd0ea002ba6bc79890558bb0c2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022255030762363664",
"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/8856824968172984832/+/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": "1611870525657168",
"url": "https://ci.chromium.org/b/8856824968172984832",
"utcnow_ts": "1611870525925303"
}
}
[21:48:45.937] Task URL: https://ci.chromium.org/b/8856824968172984832
[21:48:45.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:2:0) after 1m0s
[21:49:28.213] Received PubSub notification, asking Buildbucket for the build status
[21:49:29.552] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:45.971] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:2:0)
[21:49:45.971] Timer tick, asking Buildbucket for the build status
[21:49:46.048] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:46.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:4:0) after 1m0s
[21:50:46.041] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:4:0)
[21:50:46.041] Timer tick, asking Buildbucket for the build status
[21:50:46.347] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:46.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:5:0) after 1m0s
[21:51:46.392] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:5:0)
[21:51:46.392] Timer tick, asking Buildbucket for the build status
[21:51:46.490] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:46.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:6:0) after 1m0s
[21:52:46.518] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:6:0)
[21:52:46.518] Timer tick, asking Buildbucket for the build status
[21:52:46.644] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:46.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:7:0) after 1m0s
[21:53:46.710] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:7:0)
[21:53:46.710] Timer tick, asking Buildbucket for the build status
[21:53:47.343] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:47.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:8:0) after 1m0s
[21:54:47.365] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:8:0)
[21:54:47.365] Timer tick, asking Buildbucket for the build status
[21:54:47.494] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:47.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:9:0) after 1m0s
[21:55:47.516] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:9:0)
[21:55:47.517] Timer tick, asking Buildbucket for the build status
[21:55:47.647] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:47.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:10:0) after 1m0s
[21:56:47.729] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:10:0)
[21:56:47.729] Timer tick, asking Buildbucket for the build status
[21:56:48.000] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:48.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:11:0) after 1m0s
[21:57:48.016] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:11:0)
[21:57:48.016] Timer tick, asking Buildbucket for the build status
[21:57:48.098] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:48.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:12:0) after 1m0s
[21:58:48.146] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:12:0)
[21:58:48.146] Timer tick, asking Buildbucket for the build status
[21:58:48.631] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:48.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:13:0) after 1m0s
[21:59:48.697] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:13:0)
[21:59:48.697] Timer tick, asking Buildbucket for the build status
[21:59:48.924] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:48.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:14:0) after 1m0s
[22:00:49.006] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:14:0)
[22:00:49.006] Timer tick, asking Buildbucket for the build status
[22:00:49.204] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:49.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:15:0) after 1m0s
[22:01:49.328] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:15:0)
[22:01:49.328] Timer tick, asking Buildbucket for the build status
[22:01:49.482] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:49.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:16:0) after 1m0s
[22:02:49.674] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:16:0)
[22:02:49.674] Timer tick, asking Buildbucket for the build status
[22:02:49.758] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:49.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:17:0) after 1m0s
[22:03:49.805] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:17:0)
[22:03:49.805] Timer tick, asking Buildbucket for the build status
[22:03:50.123] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:50.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:18:0) after 1m0s
[22:04:50.221] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:18:0)
[22:04:50.221] Timer tick, asking Buildbucket for the build status
[22:04:50.358] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:50.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:19:0) after 1m0s
[22:05:50.471] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:19:0)
[22:05:50.471] Timer tick, asking Buildbucket for the build status
[22:05:50.869] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:50.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:20:0) after 1m0s
[22:06:50.891] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:20:0)
[22:06:50.891] Timer tick, asking Buildbucket for the build status
[22:06:50.990] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:50.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:21:0) after 1m0s
[22:07:51.043] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:21:0)
[22:07:51.043] Timer tick, asking Buildbucket for the build status
[22:07:51.145] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:51.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:22:0) after 1m0s
[22:08:51.152] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:22:0)
[22:08:51.152] Timer tick, asking Buildbucket for the build status
[22:08:51.334] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:51.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:23:0) after 1m0s
[22:09:51.350] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:23:0)
[22:09:51.350] Timer tick, asking Buildbucket for the build status
[22:09:51.515] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:51.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:24:0) after 1m0s
[22:10:51.537] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:24:0)
[22:10:51.537] Timer tick, asking Buildbucket for the build status
[22:10:52.104] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:52.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:25:0) after 1m0s
[22:11:52.132] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:25:0)
[22:11:52.132] Timer tick, asking Buildbucket for the build status
[22:11:52.260] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:52.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:26:0) after 1m0s
[22:12:52.264] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:26:0)
[22:12:52.264] Timer tick, asking Buildbucket for the build status
[22:12:53.543] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:53.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:27:0) after 1m0s
[22:13:53.589] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:27:0)
[22:13:53.589] Timer tick, asking Buildbucket for the build status
[22:13:53.744] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:53.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:28:0) after 1m0s
[22:14:53.787] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:28:0)
[22:14:53.787] Timer tick, asking Buildbucket for the build status
[22:14:53.854] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:53.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:29:0) after 1m0s
[22:15:53.873] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:29:0)
[22:15:53.873] Timer tick, asking Buildbucket for the build status
[22:15:53.964] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:53.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:30:0) after 1m0s
[22:16:53.963] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:30:0)
[22:16:53.963] Timer tick, asking Buildbucket for the build status
[22:16:54.205] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:54.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:31:0) after 1m0s
[22:17:54.219] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:31:0)
[22:17:54.219] Timer tick, asking Buildbucket for the build status
[22:17:55.854] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:55.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:32:0) after 1m0s
[22:18:55.930] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:32:0)
[22:18:55.930] Timer tick, asking Buildbucket for the build status
[22:18:56.057] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:56.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:33:0) after 1m0s
[22:19:56.143] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:33:0)
[22:19:56.143] Timer tick, asking Buildbucket for the build status
[22:19:56.498] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:56.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:34:0) after 1m0s
[22:20:56.701] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:34:0)
[22:20:56.701] Timer tick, asking Buildbucket for the build status
[22:20:56.770] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:56.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:35:0) after 1m0s
[22:21:56.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:35:0)
[22:21:56.933] Timer tick, asking Buildbucket for the build status
[22:21:57.093] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:57.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:36:0) after 1m0s
[22:22:57.111] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:36:0)
[22:22:57.111] Timer tick, asking Buildbucket for the build status
[22:22:57.244] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:57.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:37:0) after 1m0s
[22:23:57.345] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:37:0)
[22:23:57.345] Timer tick, asking Buildbucket for the build status
[22:23:57.802] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:57.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:38:0) after 1m0s
[22:24:57.861] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:38:0)
[22:24:57.861] Timer tick, asking Buildbucket for the build status
[22:24:57.947] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:57.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:39:0) after 1m0s
[22:25:57.999] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:39:0)
[22:25:57.999] Timer tick, asking Buildbucket for the build status
[22:25:58.101] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:58.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:40:0) after 1m0s
[22:26:58.284] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:40:0)
[22:26:58.284] Timer tick, asking Buildbucket for the build status
[22:26:58.380] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:58.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:41:0) after 1m0s
[22:27:58.550] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:41:0)
[22:27:58.550] Timer tick, asking Buildbucket for the build status
[22:27:58.680] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:58.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:42:0) after 1m0s
[22:28:58.787] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:42:0)
[22:28:58.787] Timer tick, asking Buildbucket for the build status
[22:28:58.997] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:58.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:43:0) after 1m0s
[22:29:59.078] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:43:0)
[22:29:59.078] Timer tick, asking Buildbucket for the build status
[22:29:59.232] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:59.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:44:0) after 1m0s
[22:30:59.213] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:44:0)
[22:30:59.213] Timer tick, asking Buildbucket for the build status
[22:30:59.310] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:59.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:45:0) after 1m0s
[22:31:59.356] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:45:0)
[22:31:59.356] Timer tick, asking Buildbucket for the build status
[22:31:59.586] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:59.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:46:0) after 1m0s
[22:32:59.722] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:46:0)
[22:32:59.722] Timer tick, asking Buildbucket for the build status
[22:32:59.881] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:59.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:47:0) after 1m0s
[22:33:59.953] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:47:0)
[22:33:59.953] Timer tick, asking Buildbucket for the build status
[22:34:00.208] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:00.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:48:0) after 1m0s
[22:35:00.499] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:48:0)
[22:35:00.499] Timer tick, asking Buildbucket for the build status
[22:35:00.831] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:00.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:49:0) after 1m0s
[22:36:00.836] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:49:0)
[22:36:00.836] Timer tick, asking Buildbucket for the build status
[22:36:00.921] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:00.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:50:0) after 1m0s
[22:37:01.378] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:50:0)
[22:37:01.378] Timer tick, asking Buildbucket for the build status
[22:37:01.446] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:01.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:51:0) after 1m0s
[22:38:01.890] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:51:0)
[22:38:01.890] Timer tick, asking Buildbucket for the build status
[22:38:01.960] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:01.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:52:0) after 1m0s
[22:39:01.940] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:52:0)
[22:39:01.940] Timer tick, asking Buildbucket for the build status
[22:39:02.095] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:02.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:53:0) after 1m0s
[22:40:02.117] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:53:0)
[22:40:02.117] Timer tick, asking Buildbucket for the build status
[22:40:02.714] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:02.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:54:0) after 1m0s
[22:41:02.756] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:54:0)
[22:41:02.756] Timer tick, asking Buildbucket for the build status
[22:41:02.850] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:02.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:55:0) after 1m0s
[22:42:03.022] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:55:0)
[22:42:03.022] Timer tick, asking Buildbucket for the build status
[22:42:03.111] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:03.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:56:0) after 1m0s
[22:43:03.420] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:56:0)
[22:43:03.420] Timer tick, asking Buildbucket for the build status
[22:43:03.507] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:03.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:57:0) after 1m0s
[22:44:03.593] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:57:0)
[22:44:03.593] Timer tick, asking Buildbucket for the build status
[22:44:03.983] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:03.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:58:0) after 1m0s
[22:45:04.262] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:58:0)
[22:45:04.262] Timer tick, asking Buildbucket for the build status
[22:45:04.317] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:04.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:59:0) after 1m0s
[22:46:04.571] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:59:0)
[22:46:04.571] Timer tick, asking Buildbucket for the build status
[22:46:04.678] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:04.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:60:0) after 1m0s
[22:47:04.947] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:60:0)
[22:47:04.947] Timer tick, asking Buildbucket for the build status
[22:47:05.113] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:05.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:61:0) after 1m0s
[22:48:05.464] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:61:0)
[22:48:05.464] Timer tick, asking Buildbucket for the build status
[22:48:05.566] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:05.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:62:0) after 1m0s
[22:49:05.619] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:62:0)
[22:49:05.619] Timer tick, asking Buildbucket for the build status
[22:49:05.793] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:05.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:63:0) after 1m0s
[22:50:05.968] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:63:0)
[22:50:05.968] Timer tick, asking Buildbucket for the build status
[22:50:06.158] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:06.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:64:0) after 1m0s
[22:51:06.191] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:64:0)
[22:51:06.191] Timer tick, asking Buildbucket for the build status
[22:51:06.307] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:06.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:65:0) after 1m0s
[22:52:06.332] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:65:0)
[22:52:06.332] Timer tick, asking Buildbucket for the build status
[22:52:06.440] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:06.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:66:0) after 1m0s
[22:53:06.556] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:66:0)
[22:53:06.556] Timer tick, asking Buildbucket for the build status
[22:53:06.634] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:06.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:67:0) after 1m0s
[22:54:06.776] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:67:0)
[22:54:06.776] Timer tick, asking Buildbucket for the build status
[22:54:06.979] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:06.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:68:0) after 1m0s
[22:55:07.008] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:68:0)
[22:55:07.008] Timer tick, asking Buildbucket for the build status
[22:55:07.382] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:07.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:69:0) after 1m0s
[22:56:07.464] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:69:0)
[22:56:07.464] Timer tick, asking Buildbucket for the build status
[22:56:07.553] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:07.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:70:0) after 1m0s
[22:57:07.582] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:70:0)
[22:57:07.582] Timer tick, asking Buildbucket for the build status
[22:57:11.092] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:11.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:71:0) after 1m0s
[22:58:11.126] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:71:0)
[22:58:11.126] Timer tick, asking Buildbucket for the build status
[22:58:11.620] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:11.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:72:0) after 1m0s
[22:59:11.750] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:72:0)
[22:59:11.750] Timer tick, asking Buildbucket for the build status
[22:59:11.891] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:11.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:73:0) after 1m0s
[23:00:11.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:73:0)
[23:00:11.933] Timer tick, asking Buildbucket for the build status
[23:00:12.275] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:12.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:74:0) after 1m0s
[23:01:12.349] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:74:0)
[23:01:12.349] Timer tick, asking Buildbucket for the build status
[23:01:12.465] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:12.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:75:0) after 1m0s
[23:02:12.500] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:75:0)
[23:02:12.500] Timer tick, asking Buildbucket for the build status
[23:02:12.571] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:12.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:76:0) after 1m0s
[23:03:12.731] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:76:0)
[23:03:12.731] Timer tick, asking Buildbucket for the build status
[23:03:12.798] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:12.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:77:0) after 1m0s
[23:04:12.909] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:77:0)
[23:04:12.909] Timer tick, asking Buildbucket for the build status
[23:04:13.064] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:13.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:78:0) after 1m0s
[23:05:13.112] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:78:0)
[23:05:13.112] Timer tick, asking Buildbucket for the build status
[23:05:13.325] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:13.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:79:0) after 1m0s
[23:06:13.491] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:79:0)
[23:06:13.491] Timer tick, asking Buildbucket for the build status
[23:06:13.613] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:13.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:80:0) after 1m0s
[23:07:13.596] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:80:0)
[23:07:13.596] Timer tick, asking Buildbucket for the build status
[23:07:13.668] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:13.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:81:0) after 1m0s
[23:08:13.892] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:81:0)
[23:08:13.892] Timer tick, asking Buildbucket for the build status
[23:08:13.965] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:13.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:82:0) after 1m0s
[23:09:14.157] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:82:0)
[23:09:14.157] Timer tick, asking Buildbucket for the build status
[23:09:14.649] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:14.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:83:0) after 1m0s
[23:10:15.141] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:83:0)
[23:10:15.141] Timer tick, asking Buildbucket for the build status
[23:10:15.269] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:15.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:84:0) after 1m0s
[23:11:15.289] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:84:0)
[23:11:15.289] Timer tick, asking Buildbucket for the build status
[23:11:15.900] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:15.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:85:0) after 1m0s
[23:12:16.099] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:85:0)
[23:12:16.099] Timer tick, asking Buildbucket for the build status
[23:12:16.209] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:16.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:86:0) after 1m0s
[23:13:16.274] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:86:0)
[23:13:16.274] Timer tick, asking Buildbucket for the build status
[23:13:16.347] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:16.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:87:0) after 1m0s
[23:14:16.401] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:87:0)
[23:14:16.401] Timer tick, asking Buildbucket for the build status
[23:14:16.659] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:16.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:88:0) after 1m0s
[23:15:16.787] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:88:0)
[23:15:16.787] Timer tick, asking Buildbucket for the build status
[23:15:17.347] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:17.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:89:0) after 1m0s
[23:16:17.481] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:89:0)
[23:16:17.482] Timer tick, asking Buildbucket for the build status
[23:16:19.126] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:19.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:90:0) after 1m0s
[23:17:19.188] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:90:0)
[23:17:19.188] Timer tick, asking Buildbucket for the build status
[23:17:19.288] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:19.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:91:0) after 1m0s
[23:18:19.384] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:91:0)
[23:18:19.384] Timer tick, asking Buildbucket for the build status
[23:18:19.478] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:19.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:92:0) after 1m0s
[23:19:19.587] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:92:0)
[23:19:19.587] Timer tick, asking Buildbucket for the build status
[23:19:19.790] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:19.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:93:0) after 1m0s
[23:20:19.858] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:93:0)
[23:20:19.858] Timer tick, asking Buildbucket for the build status
[23:20:20.030] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:20.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:94:0) after 1m0s
[23:21:20.042] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:94:0)
[23:21:20.042] Timer tick, asking Buildbucket for the build status
[23:21:20.142] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:20.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:95:0) after 1m0s
[23:22:20.180] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:95:0)
[23:22:20.180] Timer tick, asking Buildbucket for the build status
[23:22:20.294] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:20.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:96:0) after 1m0s
[23:23:20.325] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:96:0)
[23:23:20.325] Timer tick, asking Buildbucket for the build status
[23:23:20.384] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:20.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:97:0) after 1m0s
[23:24:20.445] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:97:0)
[23:24:20.445] Timer tick, asking Buildbucket for the build status
[23:24:20.804] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:20.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:98:0) after 1m0s
[23:25:20.884] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:98:0)
[23:25:20.885] Timer tick, asking Buildbucket for the build status
[23:25:20.955] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:20.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:99:0) after 1m0s
[23:26:20.984] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:99:0)
[23:26:20.984] Timer tick, asking Buildbucket for the build status
[23:26:21.377] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:21.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:100:0) after 1m0s
[23:27:21.433] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:100:0)
[23:27:21.433] Timer tick, asking Buildbucket for the build status
[23:27:22.737] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:22.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:101:0) after 1m0s
[23:28:22.842] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:101:0)
[23:28:22.842] Timer tick, asking Buildbucket for the build status
[23:28:22.927] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:22.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:102:0) after 1m0s
[23:29:22.989] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:102:0)
[23:29:22.989] Timer tick, asking Buildbucket for the build status
[23:29:23.209] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:23.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:103:0) after 1m0s
[23:30:24.267] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:103:0)
[23:30:24.267] Timer tick, asking Buildbucket for the build status
[23:30:24.355] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:24.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:104:0) after 1m0s
[23:31:24.698] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:104:0)
[23:31:24.698] Timer tick, asking Buildbucket for the build status
[23:31:24.775] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:24.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:105:0) after 1m0s
[23:32:25.099] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:105:0)
[23:32:25.099] Timer tick, asking Buildbucket for the build status
[23:32:25.189] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:25.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:106:0) after 1m0s
[23:33:25.483] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:106:0)
[23:33:25.483] Timer tick, asking Buildbucket for the build status
[23:33:25.554] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:25.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:107:0) after 1m0s
[23:34:25.766] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:107:0)
[23:34:25.766] Timer tick, asking Buildbucket for the build status
[23:34:26.458] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:26.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:108:0) after 1m0s
[23:35:26.512] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:108:0)
[23:35:26.512] Timer tick, asking Buildbucket for the build status
[23:35:27.068] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:27.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:109:0) after 1m0s
[23:36:27.162] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:109:0)
[23:36:27.162] Timer tick, asking Buildbucket for the build status
[23:36:27.288] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:27.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:110:0) after 1m0s
[23:37:27.400] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:110:0)
[23:37:27.400] Timer tick, asking Buildbucket for the build status
[23:37:27.625] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:27.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:111:0) after 1m0s
[23:38:27.760] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:111:0)
[23:38:27.760] Timer tick, asking Buildbucket for the build status
[23:38:28.037] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:28.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:112:0) after 1m0s
[23:39:28.049] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:112:0)
[23:39:28.049] Timer tick, asking Buildbucket for the build status
[23:39:28.161] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:28.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:113:0) after 1m0s
[23:40:28.217] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:113:0)
[23:40:28.217] Timer tick, asking Buildbucket for the build status
[23:40:28.306] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:28.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:114:0) after 1m0s
[23:41:28.331] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:114:0)
[23:41:28.331] Timer tick, asking Buildbucket for the build status
[23:41:28.415] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:28.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:115:0) after 1m0s
[23:42:28.505] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:115:0)
[23:42:28.505] Timer tick, asking Buildbucket for the build status
[23:42:28.808] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:28.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:116:0) after 1m0s
[23:43:28.927] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:116:0)
[23:43:28.927] Timer tick, asking Buildbucket for the build status
[23:43:28.988] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:28.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:117:0) after 1m0s
[23:44:29.053] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:117:0)
[23:44:29.053] Timer tick, asking Buildbucket for the build status
[23:44:29.178] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:29.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:118:0) after 1m0s
[23:45:29.262] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:118:0)
[23:45:29.262] Timer tick, asking Buildbucket for the build status
[23:45:29.510] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:29.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:119:0) after 1m0s
[23:46:29.622] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:119:0)
[23:46:29.622] Timer tick, asking Buildbucket for the build status
[23:46:29.973] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:29.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:120:0) after 1m0s
[23:47:30.096] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:120:0)
[23:47:30.096] Timer tick, asking Buildbucket for the build status
[23:47:30.500] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:30.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:121:0) after 1m0s
[23:48:30.615] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:121:0)
[23:48:30.615] Timer tick, asking Buildbucket for the build status
[23:48:30.914] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:30.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:122:0) after 1m0s
[23:49:30.918] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:122:0)
[23:49:30.918] Timer tick, asking Buildbucket for the build status
[23:49:31.026] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:31.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:123:0) after 1m0s
[23:50:31.106] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:123:0)
[23:50:31.106] Timer tick, asking Buildbucket for the build status
[23:50:31.299] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:31.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:124:0) after 1m0s
[23:51:31.383] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:124:0)
[23:51:31.383] Timer tick, asking Buildbucket for the build status
[23:51:31.449] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:31.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:125:0) after 1m0s
[23:52:31.505] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:125:0)
[23:52:31.505] Timer tick, asking Buildbucket for the build status
[23:52:32.025] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:32.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:126:0) after 1m0s
[23:53:32.078] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:126:0)
[23:53:32.078] Timer tick, asking Buildbucket for the build status
[23:53:32.181] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:32.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:127:0) after 1m0s
[23:54:32.234] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:127:0)
[23:54:32.234] Timer tick, asking Buildbucket for the build status
[23:54:32.427] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:32.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:128:0) after 1m0s
[23:55:32.455] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:128:0)
[23:55:32.455] Timer tick, asking Buildbucket for the build status
[23:55:32.598] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:32.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:129:0) after 1m0s
[23:56:32.654] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:129:0)
[23:56:32.654] Timer tick, asking Buildbucket for the build status
[23:56:32.754] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:32.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:130:0) after 1m0s
[23:57:32.809] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:130:0)
[23:57:32.809] Timer tick, asking Buildbucket for the build status
[23:57:32.928] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:32.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:131:0) after 1m0s
[23:58:33.018] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:131:0)
[23:58:33.018] Timer tick, asking Buildbucket for the build status
[23:58:33.069] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:33.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:132:0) after 1m0s
[23:59:33.181] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:132:0)
[23:59:33.181] Timer tick, asking Buildbucket for the build status
[23:59:33.333] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:33.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:133:0) after 1m0s
[00:00:33.520] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:133:0)
[00:00:33.520] Timer tick, asking Buildbucket for the build status
[00:00:33.841] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:33.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:134:0) after 1m0s
[00:01:33.920] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:134:0)
[00:01:33.920] Timer tick, asking Buildbucket for the build status
[00:01:34.230] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:34.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:135:0) after 1m0s
[00:02:34.279] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:135:0)
[00:02:34.279] Timer tick, asking Buildbucket for the build status
[00:02:34.437] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:34.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:136:0) after 1m0s
[00:03:34.444] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:136:0)
[00:03:34.444] Timer tick, asking Buildbucket for the build status
[00:03:34.801] Build 8856824968172984832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:34.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022255030762363664:137:0) after 1m0s
[00:04:23.105] Received PubSub notification, asking Buildbucket for the build status
[00:04:23.325] Build 8856824968172984832: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:04:23.325] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611878662185457",
"created_by": "project:chromium",
"created_ts": "1611870525155104",
"id": "8856824968172984832",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b24d1a3871cf07bd0ea002ba6bc79890558bb0c2\"}}",
"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\": \"b24d1a3871cf07bd0ea002ba6bc79890558bb0c2\", \"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-0-89yk\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1486, \"got_angle_revision\": \"a408ce8349289c8fe457a5ebccb8c4cbac7b9c3f\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"b24d1a3871cf07bd0ea002ba6bc79890558bb0c2\", \"got_revision_cp\": \"refs/heads/master@{#848271}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"609f77888b667dbb70edc38715437b69f15a4dc4\", \"got_v8_revision_cp\": \"refs/heads/9.0.99@{#1}\", \"got_webrtc_revision\": \"54b925cfc2cd7c2f8f83e8e484f281aa87b92dfc\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33090}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b24d1a3871cf07bd0ea002ba6bc79890558bb0c2\"}, \"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-0-89yk\"], \"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\": [\"5673-2413400\"], \"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": "1611870567613045",
"status": "COMPLETED",
"status_changed_ts": "1611878662639934",
"tags": [
"build_address:luci.chromium.ci/win-official/1486",
"builder:win-official",
"buildset:commit/git/b24d1a3871cf07bd0ea002ba6bc79890558bb0c2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b24d1a3871cf07bd0ea002ba6bc79890558bb0c2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022255030762363664",
"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/8856824968172984832/+/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:5163c9e28e16e210",
"user_agent:luci-scheduler"
],
"updated_ts": "1611878662646290",
"url": "https://ci.chromium.org/b/8856824968172984832",
"utcnow_ts": "1611878663306460"
}
}
[00:04:23.326] Invocation finished in 2h15m40.023247872s with status SUCCEEDED