[06:58:50.985] New invocation is queued and will start shortly
[06:58:52.059] Starting the invocation (attempt 1)
[06:58:52.093] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:58:52.096] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:58:52.096] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025210119608015856",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0765e0b7aa452eac2a1ac4ff6097f075217b1112\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9025210119608015856",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/0765e0b7aa452eac2a1ac4ff6097f075217b1112",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0765e0b7aa452eac2a1ac4ff6097f075217b1112",
"gitiles_ref:refs/heads/master"
]
}
[06:58:52.707] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1609052332207231",
"id": "8859780057662482448",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0765e0b7aa452eac2a1ac4ff6097f075217b1112\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609052332605968",
"tags": [
"build_address:luci.chromium.ci/win32-official/1207",
"builder:win32-official",
"buildset:commit/git/0765e0b7aa452eac2a1ac4ff6097f075217b1112",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0765e0b7aa452eac2a1ac4ff6097f075217b1112",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025210119608015856",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8859780057662482448/+/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": "1609052332606095",
"url": "https://ci.chromium.org/b/8859780057662482448",
"utcnow_ts": "1609052332698348"
}
}
[06:58:52.707] Task URL: https://ci.chromium.org/b/8859780057662482448
[06:58:52.707] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:2:0) after 1m0s
[06:59:01.858] Received PubSub notification, asking Buildbucket for the build status
[06:59:01.942] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:52.971] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:2:0)
[06:59:52.971] Timer tick, asking Buildbucket for the build status
[06:59:53.037] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:53.037] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:4:0) after 1m0s
[07:00:53.086] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:4:0)
[07:00:53.086] Timer tick, asking Buildbucket for the build status
[07:00:53.168] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:53.168] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:5:0) after 1m0s
[07:01:53.325] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:5:0)
[07:01:53.325] Timer tick, asking Buildbucket for the build status
[07:01:53.400] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:53.400] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:6:0) after 1m0s
[07:02:53.424] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:6:0)
[07:02:53.424] Timer tick, asking Buildbucket for the build status
[07:02:53.483] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:53.483] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:7:0) after 1m0s
[07:03:53.550] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:7:0)
[07:03:53.550] Timer tick, asking Buildbucket for the build status
[07:03:53.620] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:53.620] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:8:0) after 1m0s
[07:04:53.712] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:8:0)
[07:04:53.712] Timer tick, asking Buildbucket for the build status
[07:04:53.793] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:53.793] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:9:0) after 1m0s
[07:05:53.836] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:9:0)
[07:05:53.836] Timer tick, asking Buildbucket for the build status
[07:05:54.006] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:54.006] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:10:0) after 1m0s
[07:06:54.175] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:10:0)
[07:06:54.175] Timer tick, asking Buildbucket for the build status
[07:06:54.237] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:54.237] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:11:0) after 1m0s
[07:07:54.345] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:11:0)
[07:07:54.345] Timer tick, asking Buildbucket for the build status
[07:07:54.593] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:54.593] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:12:0) after 1m0s
[07:08:54.723] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:12:0)
[07:08:54.723] Timer tick, asking Buildbucket for the build status
[07:08:54.804] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:54.804] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:13:0) after 1m0s
[07:09:54.867] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:13:0)
[07:09:54.867] Timer tick, asking Buildbucket for the build status
[07:09:55.190] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:55.190] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:14:0) after 1m0s
[07:10:55.266] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:14:0)
[07:10:55.266] Timer tick, asking Buildbucket for the build status
[07:10:55.315] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:55.315] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:15:0) after 1m0s
[07:11:55.505] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:15:0)
[07:11:55.505] Timer tick, asking Buildbucket for the build status
[07:11:55.553] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:55.553] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:16:0) after 1m0s
[07:12:55.683] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:16:0)
[07:12:55.683] Timer tick, asking Buildbucket for the build status
[07:12:55.742] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:55.742] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:17:0) after 1m0s
[07:13:55.813] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:17:0)
[07:13:55.813] Timer tick, asking Buildbucket for the build status
[07:13:55.922] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:55.922] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:18:0) after 1m0s
[07:14:55.991] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:18:0)
[07:14:55.991] Timer tick, asking Buildbucket for the build status
[07:14:56.057] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:56.057] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:19:0) after 1m0s
[07:15:56.220] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:19:0)
[07:15:56.220] Timer tick, asking Buildbucket for the build status
[07:15:56.274] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:56.274] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:20:0) after 1m0s
[07:16:56.291] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:20:0)
[07:16:56.291] Timer tick, asking Buildbucket for the build status
[07:16:56.367] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:56.367] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:21:0) after 1m0s
[07:17:56.481] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:21:0)
[07:17:56.481] Timer tick, asking Buildbucket for the build status
[07:17:56.534] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:56.534] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:22:0) after 1m0s
[07:18:56.614] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:22:0)
[07:18:56.614] Timer tick, asking Buildbucket for the build status
[07:18:56.820] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:56.820] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:23:0) after 1m0s
[07:19:56.856] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:23:0)
[07:19:56.856] Timer tick, asking Buildbucket for the build status
[07:19:56.975] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:56.975] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:24:0) after 1m0s
[07:20:56.993] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:24:0)
[07:20:56.993] Timer tick, asking Buildbucket for the build status
[07:20:57.112] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:57.112] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:25:0) after 1m0s
[07:21:57.132] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:25:0)
[07:21:57.132] Timer tick, asking Buildbucket for the build status
[07:21:57.286] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:57.286] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:26:0) after 1m0s
[07:22:57.308] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:26:0)
[07:22:57.308] Timer tick, asking Buildbucket for the build status
[07:22:57.413] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:57.413] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:27:0) after 1m0s
[07:23:57.435] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:27:0)
[07:23:57.435] Timer tick, asking Buildbucket for the build status
[07:23:57.517] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:57.517] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:28:0) after 1m0s
[07:24:57.536] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:28:0)
[07:24:57.536] Timer tick, asking Buildbucket for the build status
[07:24:57.594] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:57.594] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:29:0) after 1m0s
[07:25:57.612] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:29:0)
[07:25:57.612] Timer tick, asking Buildbucket for the build status
[07:25:57.668] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:57.668] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:30:0) after 1m0s
[07:26:57.689] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:30:0)
[07:26:57.689] Timer tick, asking Buildbucket for the build status
[07:26:57.898] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:57.898] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:31:0) after 1m0s
[07:27:57.970] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:31:0)
[07:27:57.970] Timer tick, asking Buildbucket for the build status
[07:27:58.022] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:58.022] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:32:0) after 1m0s
[07:28:58.087] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:32:0)
[07:28:58.087] Timer tick, asking Buildbucket for the build status
[07:28:58.212] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:58.212] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:33:0) after 1m0s
[07:29:58.207] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:33:0)
[07:29:58.207] Timer tick, asking Buildbucket for the build status
[07:29:58.269] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:58.269] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:34:0) after 1m0s
[07:30:58.293] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:34:0)
[07:30:58.293] Timer tick, asking Buildbucket for the build status
[07:30:58.344] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:58.344] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:35:0) after 1m0s
[07:31:58.378] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:35:0)
[07:31:58.378] Timer tick, asking Buildbucket for the build status
[07:31:58.608] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:58.608] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:36:0) after 1m0s
[07:32:58.690] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:36:0)
[07:32:58.690] Timer tick, asking Buildbucket for the build status
[07:32:58.796] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:58.796] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:37:0) after 1m0s
[07:33:58.918] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:37:0)
[07:33:58.918] Timer tick, asking Buildbucket for the build status
[07:33:59.461] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:59.461] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:38:0) after 1m0s
[07:34:59.486] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:38:0)
[07:34:59.486] Timer tick, asking Buildbucket for the build status
[07:34:59.585] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:59.585] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:39:0) after 1m0s
[07:35:59.606] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:39:0)
[07:35:59.606] Timer tick, asking Buildbucket for the build status
[07:35:59.660] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:59.661] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:40:0) after 1m0s
[07:36:59.734] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:40:0)
[07:36:59.735] Timer tick, asking Buildbucket for the build status
[07:36:59.987] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:59.987] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:41:0) after 1m0s
[07:38:00.023] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:41:0)
[07:38:00.023] Timer tick, asking Buildbucket for the build status
[07:38:00.285] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:00.285] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:42:0) after 1m0s
[07:39:01.077] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:42:0)
[07:39:01.077] Timer tick, asking Buildbucket for the build status
[07:39:01.171] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:01.171] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:43:0) after 1m0s
[07:40:01.492] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:43:0)
[07:40:01.492] Timer tick, asking Buildbucket for the build status
[07:40:01.546] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:01.546] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:44:0) after 1m0s
[07:41:02.095] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:44:0)
[07:41:02.095] Timer tick, asking Buildbucket for the build status
[07:41:02.203] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:02.203] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:45:0) after 1m0s
[07:42:02.298] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:45:0)
[07:42:02.298] Timer tick, asking Buildbucket for the build status
[07:42:02.473] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:02.473] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:46:0) after 1m0s
[07:43:02.537] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:46:0)
[07:43:02.537] Timer tick, asking Buildbucket for the build status
[07:43:02.597] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:02.597] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:47:0) after 1m0s
[07:44:02.684] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:47:0)
[07:44:02.684] Timer tick, asking Buildbucket for the build status
[07:44:02.779] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:02.779] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:48:0) after 1m0s
[07:45:03.176] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:48:0)
[07:45:03.176] Timer tick, asking Buildbucket for the build status
[07:45:03.497] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:03.497] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:49:0) after 1m0s
[07:46:03.610] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:49:0)
[07:46:03.610] Timer tick, asking Buildbucket for the build status
[07:46:03.684] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:03.684] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:50:0) after 1m0s
[07:47:03.705] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:50:0)
[07:47:03.705] Timer tick, asking Buildbucket for the build status
[07:47:03.928] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:03.928] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:51:0) after 1m0s
[07:48:03.969] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:51:0)
[07:48:03.969] Timer tick, asking Buildbucket for the build status
[07:48:04.093] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:04.093] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:52:0) after 1m0s
[07:49:04.163] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:52:0)
[07:49:04.163] Timer tick, asking Buildbucket for the build status
[07:49:04.291] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:04.291] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:53:0) after 1m0s
[07:50:04.361] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:53:0)
[07:50:04.361] Timer tick, asking Buildbucket for the build status
[07:50:04.582] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:04.582] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:54:0) after 1m0s
[07:51:04.854] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:54:0)
[07:51:04.854] Timer tick, asking Buildbucket for the build status
[07:51:04.916] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:04.916] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:55:0) after 1m0s
[07:52:05.045] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:55:0)
[07:52:05.045] Timer tick, asking Buildbucket for the build status
[07:52:05.190] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:05.190] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:56:0) after 1m0s
[07:53:05.264] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:56:0)
[07:53:05.264] Timer tick, asking Buildbucket for the build status
[07:53:05.322] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:05.322] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:57:0) after 1m0s
[07:54:05.371] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:57:0)
[07:54:05.371] Timer tick, asking Buildbucket for the build status
[07:54:05.468] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:05.468] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:58:0) after 1m0s
[07:55:05.581] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:58:0)
[07:55:05.581] Timer tick, asking Buildbucket for the build status
[07:55:05.660] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:05.660] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:59:0) after 1m0s
[07:56:05.761] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:59:0)
[07:56:05.761] Timer tick, asking Buildbucket for the build status
[07:56:05.896] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:05.896] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:60:0) after 1m0s
[07:57:05.961] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:60:0)
[07:57:05.961] Timer tick, asking Buildbucket for the build status
[07:57:06.019] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:06.019] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:61:0) after 1m0s
[07:58:06.024] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:61:0)
[07:58:06.024] Timer tick, asking Buildbucket for the build status
[07:58:06.091] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:06.091] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:62:0) after 1m0s
[07:59:06.119] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:62:0)
[07:59:06.119] Timer tick, asking Buildbucket for the build status
[07:59:06.219] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:06.219] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:63:0) after 1m0s
[08:00:06.254] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:63:0)
[08:00:06.254] Timer tick, asking Buildbucket for the build status
[08:00:06.322] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:06.322] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:64:0) after 1m0s
[08:01:06.402] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:64:0)
[08:01:06.402] Timer tick, asking Buildbucket for the build status
[08:01:06.458] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:06.458] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:65:0) after 1m0s
[08:02:06.524] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:65:0)
[08:02:06.524] Timer tick, asking Buildbucket for the build status
[08:02:06.574] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:06.574] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:66:0) after 1m0s
[08:03:06.637] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:66:0)
[08:03:06.637] Timer tick, asking Buildbucket for the build status
[08:03:06.723] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:06.723] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:67:0) after 1m0s
[08:04:06.839] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:67:0)
[08:04:06.839] Timer tick, asking Buildbucket for the build status
[08:04:07.000] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:07.000] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:68:0) after 1m0s
[08:05:07.030] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:68:0)
[08:05:07.030] Timer tick, asking Buildbucket for the build status
[08:05:07.223] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:07.223] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:69:0) after 1m0s
[08:06:07.289] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:69:0)
[08:06:07.289] Timer tick, asking Buildbucket for the build status
[08:06:07.367] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:07.367] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:70:0) after 1m0s
[08:07:07.449] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:70:0)
[08:07:07.449] Timer tick, asking Buildbucket for the build status
[08:07:07.507] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:07.507] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:71:0) after 1m0s
[08:08:07.592] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:71:0)
[08:08:07.592] Timer tick, asking Buildbucket for the build status
[08:08:07.659] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:07.659] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:72:0) after 1m0s
[08:09:07.738] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:72:0)
[08:09:07.738] Timer tick, asking Buildbucket for the build status
[08:09:07.839] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:07.840] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:73:0) after 1m0s
[08:10:07.890] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:73:0)
[08:10:07.890] Timer tick, asking Buildbucket for the build status
[08:10:08.098] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:08.098] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:74:0) after 1m0s
[08:11:08.168] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:74:0)
[08:11:08.168] Timer tick, asking Buildbucket for the build status
[08:11:08.263] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:08.263] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:75:0) after 1m0s
[08:12:08.278] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:75:0)
[08:12:08.278] Timer tick, asking Buildbucket for the build status
[08:12:08.380] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:08.380] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:76:0) after 1m0s
[08:13:08.447] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:76:0)
[08:13:08.447] Timer tick, asking Buildbucket for the build status
[08:13:08.507] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:08.507] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:77:0) after 1m0s
[08:14:08.520] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:77:0)
[08:14:08.520] Timer tick, asking Buildbucket for the build status
[08:14:08.711] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:08.711] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:78:0) after 1m0s
[08:15:08.758] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:78:0)
[08:15:08.758] Timer tick, asking Buildbucket for the build status
[08:15:08.884] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:08.884] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:79:0) after 1m0s
[08:16:08.952] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:79:0)
[08:16:08.952] Timer tick, asking Buildbucket for the build status
[08:16:09.007] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:09.007] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:80:0) after 1m0s
[08:17:09.020] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:80:0)
[08:17:09.020] Timer tick, asking Buildbucket for the build status
[08:17:09.135] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:09.135] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:81:0) after 1m0s
[08:18:09.256] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:81:0)
[08:18:09.256] Timer tick, asking Buildbucket for the build status
[08:18:09.330] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:09.330] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:82:0) after 1m0s
[08:19:09.387] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:82:0)
[08:19:09.388] Timer tick, asking Buildbucket for the build status
[08:19:09.473] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:09.473] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:83:0) after 1m0s
[08:20:09.561] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:83:0)
[08:20:09.561] Timer tick, asking Buildbucket for the build status
[08:20:09.662] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:09.662] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:84:0) after 1m0s
[08:21:09.724] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:84:0)
[08:21:09.724] Timer tick, asking Buildbucket for the build status
[08:21:10.023] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:10.023] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:85:0) after 1m0s
[08:22:10.047] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:85:0)
[08:22:10.047] Timer tick, asking Buildbucket for the build status
[08:22:10.332] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:10.332] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:86:0) after 1m0s
[08:23:10.370] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:86:0)
[08:23:10.370] Timer tick, asking Buildbucket for the build status
[08:23:10.458] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:10.458] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:87:0) after 1m0s
[08:24:10.515] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:87:0)
[08:24:10.515] Timer tick, asking Buildbucket for the build status
[08:24:10.665] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:10.665] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:88:0) after 1m0s
[08:25:10.778] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:88:0)
[08:25:10.778] Timer tick, asking Buildbucket for the build status
[08:25:10.878] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:10.878] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:89:0) after 1m0s
[08:26:10.983] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:89:0)
[08:26:10.983] Timer tick, asking Buildbucket for the build status
[08:26:11.029] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:11.029] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:90:0) after 1m0s
[08:27:11.029] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:90:0)
[08:27:11.029] Timer tick, asking Buildbucket for the build status
[08:27:11.078] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:11.078] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:91:0) after 1m0s
[08:28:11.129] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:91:0)
[08:28:11.129] Timer tick, asking Buildbucket for the build status
[08:28:11.187] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:11.187] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:92:0) after 1m0s
[08:29:11.246] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:92:0)
[08:29:11.246] Timer tick, asking Buildbucket for the build status
[08:29:11.426] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:11.426] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:93:0) after 1m0s
[08:30:11.431] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:93:0)
[08:30:11.431] Timer tick, asking Buildbucket for the build status
[08:30:11.490] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:11.490] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:94:0) after 1m0s
[08:31:11.545] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:94:0)
[08:31:11.545] Timer tick, asking Buildbucket for the build status
[08:31:11.679] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:11.679] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:95:0) after 1m0s
[08:32:11.728] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:95:0)
[08:32:11.728] Timer tick, asking Buildbucket for the build status
[08:32:12.090] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:12.090] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:96:0) after 1m0s
[08:33:12.202] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:96:0)
[08:33:12.202] Timer tick, asking Buildbucket for the build status
[08:33:12.295] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:12.295] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:97:0) after 1m0s
[08:34:12.441] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:97:0)
[08:34:12.441] Timer tick, asking Buildbucket for the build status
[08:34:12.971] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:12.975] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:98:0) after 1m0s
[08:35:13.038] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:98:0)
[08:35:13.038] Timer tick, asking Buildbucket for the build status
[08:35:13.095] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:13.095] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:99:0) after 1m0s
[08:36:13.119] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:99:0)
[08:36:13.119] Timer tick, asking Buildbucket for the build status
[08:36:13.281] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:13.282] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:100:0) after 1m0s
[08:37:13.385] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:100:0)
[08:37:13.385] Timer tick, asking Buildbucket for the build status
[08:37:13.438] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:13.438] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:101:0) after 1m0s
[08:38:13.523] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:101:0)
[08:38:13.523] Timer tick, asking Buildbucket for the build status
[08:38:13.595] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:13.595] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:102:0) after 1m0s
[08:39:13.616] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:102:0)
[08:39:13.616] Timer tick, asking Buildbucket for the build status
[08:39:13.764] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:13.764] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:103:0) after 1m0s
[08:40:13.816] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:103:0)
[08:40:13.816] Timer tick, asking Buildbucket for the build status
[08:40:13.878] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:13.878] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:104:0) after 1m0s
[08:41:13.913] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:104:0)
[08:41:13.913] Timer tick, asking Buildbucket for the build status
[08:41:13.972] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:13.972] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:105:0) after 1m0s
[08:42:14.076] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:105:0)
[08:42:14.076] Timer tick, asking Buildbucket for the build status
[08:42:14.128] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:14.128] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:106:0) after 1m0s
[08:43:14.263] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:106:0)
[08:43:14.263] Timer tick, asking Buildbucket for the build status
[08:43:14.318] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:14.318] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:107:0) after 1m0s
[08:44:14.526] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:107:0)
[08:44:14.526] Timer tick, asking Buildbucket for the build status
[08:44:15.323] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:15.323] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:108:0) after 1m0s
[08:45:15.356] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:108:0)
[08:45:15.356] Timer tick, asking Buildbucket for the build status
[08:45:15.423] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:15.423] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:109:0) after 1m0s
[08:46:16.123] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:109:0)
[08:46:16.123] Timer tick, asking Buildbucket for the build status
[08:46:16.169] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:16.170] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:110:0) after 1m0s
[08:47:16.405] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:110:0)
[08:47:16.405] Timer tick, asking Buildbucket for the build status
[08:47:16.462] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:16.462] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:111:0) after 1m0s
[08:48:16.558] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:111:0)
[08:48:16.558] Timer tick, asking Buildbucket for the build status
[08:48:16.627] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:16.627] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:112:0) after 1m0s
[08:49:16.710] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:112:0)
[08:49:16.710] Timer tick, asking Buildbucket for the build status
[08:49:16.886] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:16.886] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:113:0) after 1m0s
[08:50:16.934] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:113:0)
[08:50:16.934] Timer tick, asking Buildbucket for the build status
[08:50:16.996] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:16.996] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:114:0) after 1m0s
[08:51:17.146] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:114:0)
[08:51:17.146] Timer tick, asking Buildbucket for the build status
[08:51:17.201] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:17.201] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:115:0) after 1m0s
[08:52:17.287] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:115:0)
[08:52:17.287] Timer tick, asking Buildbucket for the build status
[08:52:17.402] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:17.402] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:116:0) after 1m0s
[08:53:17.575] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:116:0)
[08:53:17.575] Timer tick, asking Buildbucket for the build status
[08:53:17.735] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:17.735] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:117:0) after 1m0s
[08:54:17.815] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:117:0)
[08:54:17.815] Timer tick, asking Buildbucket for the build status
[08:54:17.908] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:17.908] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:118:0) after 1m0s
[08:55:17.994] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:118:0)
[08:55:17.994] Timer tick, asking Buildbucket for the build status
[08:55:18.113] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:18.113] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:119:0) after 1m0s
[08:56:18.158] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:119:0)
[08:56:18.158] Timer tick, asking Buildbucket for the build status
[08:56:18.212] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:18.212] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:120:0) after 1m0s
[08:57:18.341] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:120:0)
[08:57:18.341] Timer tick, asking Buildbucket for the build status
[08:57:18.405] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:18.405] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:121:0) after 1m0s
[08:58:18.542] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:121:0)
[08:58:18.542] Timer tick, asking Buildbucket for the build status
[08:58:18.592] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:18.592] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:122:0) after 1m0s
[08:59:18.732] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:122:0)
[08:59:18.732] Timer tick, asking Buildbucket for the build status
[08:59:18.934] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:18.934] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:123:0) after 1m0s
[09:00:19.264] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:123:0)
[09:00:19.264] Timer tick, asking Buildbucket for the build status
[09:00:19.344] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:19.344] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:124:0) after 1m0s
[09:01:19.540] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:124:0)
[09:01:19.540] Timer tick, asking Buildbucket for the build status
[09:01:19.636] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:19.636] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:125:0) after 1m0s
[09:02:19.785] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:125:0)
[09:02:19.785] Timer tick, asking Buildbucket for the build status
[09:02:20.108] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:20.108] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:126:0) after 1m0s
[09:03:20.278] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:126:0)
[09:03:20.278] Timer tick, asking Buildbucket for the build status
[09:03:20.584] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:20.584] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:127:0) after 1m0s
[09:04:20.703] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:127:0)
[09:04:20.703] Timer tick, asking Buildbucket for the build status
[09:04:21.062] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:21.062] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:128:0) after 1m0s
[09:05:21.153] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:128:0)
[09:05:21.153] Timer tick, asking Buildbucket for the build status
[09:05:21.207] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:21.207] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:129:0) after 1m0s
[09:06:21.382] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:129:0)
[09:06:21.382] Timer tick, asking Buildbucket for the build status
[09:06:21.430] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:21.430] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:130:0) after 1m0s
[09:07:21.509] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:130:0)
[09:07:21.509] Timer tick, asking Buildbucket for the build status
[09:07:21.725] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:21.725] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:131:0) after 1m0s
[09:08:21.876] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:131:0)
[09:08:21.876] Timer tick, asking Buildbucket for the build status
[09:08:21.994] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:21.994] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:132:0) after 1m0s
[09:09:22.084] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:132:0)
[09:09:22.084] Timer tick, asking Buildbucket for the build status
[09:09:22.270] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:22.270] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:133:0) after 1m0s
[09:10:22.295] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:133:0)
[09:10:22.295] Timer tick, asking Buildbucket for the build status
[09:10:22.703] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:22.703] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:134:0) after 1m0s
[09:11:22.752] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:134:0)
[09:11:22.752] Timer tick, asking Buildbucket for the build status
[09:11:22.969] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:22.969] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:135:0) after 1m0s
[09:12:23.062] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:135:0)
[09:12:23.062] Timer tick, asking Buildbucket for the build status
[09:12:23.203] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:23.203] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:136:0) after 1m0s
[09:13:23.296] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:136:0)
[09:13:23.296] Timer tick, asking Buildbucket for the build status
[09:13:23.359] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:23.359] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:137:0) after 1m0s
[09:14:23.467] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:137:0)
[09:14:23.467] Timer tick, asking Buildbucket for the build status
[09:14:23.562] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:23.562] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:138:0) after 1m0s
[09:15:23.622] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:138:0)
[09:15:23.622] Timer tick, asking Buildbucket for the build status
[09:15:23.809] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:23.809] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:139:0) after 1m0s
[09:16:23.862] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:139:0)
[09:16:23.862] Timer tick, asking Buildbucket for the build status
[09:16:23.932] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:23.932] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:140:0) after 1m0s
[09:17:23.910] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:140:0)
[09:17:23.910] Timer tick, asking Buildbucket for the build status
[09:17:23.966] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:23.966] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:141:0) after 1m0s
[09:18:24.124] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:141:0)
[09:18:24.124] Timer tick, asking Buildbucket for the build status
[09:18:24.173] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:24.173] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:142:0) after 1m0s
[09:19:24.201] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:142:0)
[09:19:24.201] Timer tick, asking Buildbucket for the build status
[09:19:24.337] Build 8859780057662482448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:24.337] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025210119608015856:143:0) after 1m0s
[09:20:13.502] Received PubSub notification, asking Buildbucket for the build status
[09:20:13.571] Build 8859780057662482448: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:20:13.571] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1609060812672446",
"created_by": "project:chromium",
"created_ts": "1609052332207231",
"id": "8859780057662482448",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0765e0b7aa452eac2a1ac4ff6097f075217b1112\"}}",
"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\": \"0765e0b7aa452eac2a1ac4ff6097f075217b1112\", \"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-mirm\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 1207, \"got_angle_revision\": \"5e606e5bfce2b23c139c5289cbf1299844fa4b43\", \"got_dawn_revision\": \"c8d5277e86ee8c51b1dd7892b41e83eb26ac2ede\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"0765e0b7aa452eac2a1ac4ff6097f075217b1112\", \"got_revision_cp\": \"refs/heads/master@{#839381}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"4d7ae9713defe93b6f3de031454727a30c70a734\", \"got_v8_revision_cp\": \"refs/heads/8.9.192@{#1}\", \"got_webrtc_revision\": \"b90546580ec3e8236225f39f9e5e5e7b7128c24b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32881}\", \"mirrored_builders\": [\"tryserver.chromium:win32-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0765e0b7aa452eac2a1ac4ff6097f075217b1112\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_f375b1f56d48f8eb1b78ff74f62ac76927591c747ddd46d6194a7cde2679a0e3_v2\", \"git\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-0-mirm\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"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": "1609052341046091",
"status": "COMPLETED",
"status_changed_ts": "1609060813184759",
"tags": [
"build_address:luci.chromium.ci/win32-official/1207",
"builder:win32-official",
"buildset:commit/git/0765e0b7aa452eac2a1ac4ff6097f075217b1112",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0765e0b7aa452eac2a1ac4ff6097f075217b1112",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025210119608015856",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8859780057662482448/+/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:50bbcfad034f4410",
"user_agent:luci-scheduler"
],
"updated_ts": "1609060813190245",
"url": "https://ci.chromium.org/b/8859780057662482448",
"utcnow_ts": "1609060813565794"
}
}
[09:20:13.571] Invocation finished in 2h21m22.603855602s with status SUCCEEDED