[00:48:23.379] New invocation is queued and will start shortly
[00:48:24.548] Starting the invocation (attempt 1)
[00:48:24.589] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:48:24.589] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:48:24.589] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024508651209903184",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4e761f875ad0a94bb9866f5cc13a17bc1fa552ff\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9024508651209903184",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/4e761f875ad0a94bb9866f5cc13a17bc1fa552ff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4e761f875ad0a94bb9866f5cc13a17bc1fa552ff",
"gitiles_ref:refs/heads/master"
]
}
[00:48:25.285] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1609721304670546",
"id": "8859078589192161344",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4e761f875ad0a94bb9866f5cc13a17bc1fa552ff\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609721305033303",
"tags": [
"build_address:luci.chromium.ci/win-official/1302",
"builder:win-official",
"buildset:commit/git/4e761f875ad0a94bb9866f5cc13a17bc1fa552ff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4e761f875ad0a94bb9866f5cc13a17bc1fa552ff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024508651209903184",
"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/8859078589192161344/+/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": "1609721305033403",
"url": "https://ci.chromium.org/b/8859078589192161344",
"utcnow_ts": "1609721305278928"
}
}
[00:48:25.285] Task URL: https://ci.chromium.org/b/8859078589192161344
[00:48:25.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:2:0) after 1m0s
[00:49:05.363] Received PubSub notification, asking Buildbucket for the build status
[00:49:05.764] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:25.305] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:2:0)
[00:49:25.305] Timer tick, asking Buildbucket for the build status
[00:49:25.386] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:25.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:4:0) after 1m0s
[00:50:25.454] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:4:0)
[00:50:25.454] Timer tick, asking Buildbucket for the build status
[00:50:25.568] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:25.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:5:0) after 1m0s
[00:51:25.636] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:5:0)
[00:51:25.636] Timer tick, asking Buildbucket for the build status
[00:51:25.756] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:25.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:6:0) after 1m0s
[00:52:25.784] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:6:0)
[00:52:25.784] Timer tick, asking Buildbucket for the build status
[00:52:25.848] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:25.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:7:0) after 1m0s
[00:53:25.867] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:7:0)
[00:53:25.867] Timer tick, asking Buildbucket for the build status
[00:53:25.938] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:25.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:8:0) after 1m0s
[00:54:25.954] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:8:0)
[00:54:25.954] Timer tick, asking Buildbucket for the build status
[00:54:26.063] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:26.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:9:0) after 1m0s
[00:55:27.245] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:9:0)
[00:55:27.245] Timer tick, asking Buildbucket for the build status
[00:55:27.334] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:27.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:10:0) after 1m0s
[00:56:30.014] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:10:0)
[00:56:30.014] Timer tick, asking Buildbucket for the build status
[00:56:30.180] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:30.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:11:0) after 1m0s
[00:57:30.249] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:11:0)
[00:57:30.249] Timer tick, asking Buildbucket for the build status
[00:57:30.328] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:30.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:12:0) after 1m0s
[00:58:30.350] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:12:0)
[00:58:30.350] Timer tick, asking Buildbucket for the build status
[00:58:30.454] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:30.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:13:0) after 1m0s
[00:59:30.491] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:13:0)
[00:59:30.491] Timer tick, asking Buildbucket for the build status
[00:59:30.590] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:30.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:14:0) after 1m0s
[01:00:30.701] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:14:0)
[01:00:30.701] Timer tick, asking Buildbucket for the build status
[01:00:30.937] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:30.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:15:0) after 1m0s
[01:01:30.987] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:15:0)
[01:01:30.987] Timer tick, asking Buildbucket for the build status
[01:01:31.229] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:31.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:16:0) after 1m0s
[01:02:31.333] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:16:0)
[01:02:31.333] Timer tick, asking Buildbucket for the build status
[01:02:31.407] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:31.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:17:0) after 1m0s
[01:03:31.425] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:17:0)
[01:03:31.425] Timer tick, asking Buildbucket for the build status
[01:03:31.730] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:31.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:18:0) after 1m0s
[01:04:31.745] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:18:0)
[01:04:31.745] Timer tick, asking Buildbucket for the build status
[01:04:31.806] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:31.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:19:0) after 1m0s
[01:05:31.825] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:19:0)
[01:05:31.825] Timer tick, asking Buildbucket for the build status
[01:05:32.041] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:32.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:20:0) after 1m0s
[01:06:32.118] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:20:0)
[01:06:32.118] Timer tick, asking Buildbucket for the build status
[01:06:32.536] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:32.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:21:0) after 1m0s
[01:07:32.575] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:21:0)
[01:07:32.575] Timer tick, asking Buildbucket for the build status
[01:07:32.792] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:32.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:22:0) after 1m0s
[01:08:32.809] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:22:0)
[01:08:32.809] Timer tick, asking Buildbucket for the build status
[01:08:33.029] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:33.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:23:0) after 1m0s
[01:09:33.037] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:23:0)
[01:09:33.037] Timer tick, asking Buildbucket for the build status
[01:09:33.096] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:33.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:24:0) after 1m0s
[01:10:33.116] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:24:0)
[01:10:33.116] Timer tick, asking Buildbucket for the build status
[01:10:33.304] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:33.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:25:0) after 1m0s
[01:11:33.371] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:25:0)
[01:11:33.371] Timer tick, asking Buildbucket for the build status
[01:11:33.715] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:33.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:26:0) after 1m0s
[01:12:33.724] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:26:0)
[01:12:33.724] Timer tick, asking Buildbucket for the build status
[01:12:33.814] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:33.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:27:0) after 1m0s
[01:13:33.859] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:27:0)
[01:13:33.859] Timer tick, asking Buildbucket for the build status
[01:13:33.952] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:33.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:28:0) after 1m0s
[01:14:33.974] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:28:0)
[01:14:33.974] Timer tick, asking Buildbucket for the build status
[01:14:34.237] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:34.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:29:0) after 1m0s
[01:15:34.267] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:29:0)
[01:15:34.267] Timer tick, asking Buildbucket for the build status
[01:15:34.334] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:34.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:30:0) after 1m0s
[01:16:34.364] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:30:0)
[01:16:34.364] Timer tick, asking Buildbucket for the build status
[01:16:34.416] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:34.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:31:0) after 1m0s
[01:17:34.505] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:31:0)
[01:17:34.505] Timer tick, asking Buildbucket for the build status
[01:17:34.564] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:34.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:32:0) after 1m0s
[01:18:34.585] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:32:0)
[01:18:34.585] Timer tick, asking Buildbucket for the build status
[01:18:34.672] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:34.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:33:0) after 1m0s
[01:19:34.699] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:33:0)
[01:19:34.699] Timer tick, asking Buildbucket for the build status
[01:19:34.757] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:34.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:34:0) after 1m0s
[01:20:34.775] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:34:0)
[01:20:34.775] Timer tick, asking Buildbucket for the build status
[01:20:34.838] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:34.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:35:0) after 1m0s
[01:21:34.856] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:35:0)
[01:21:34.856] Timer tick, asking Buildbucket for the build status
[01:21:34.939] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:34.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:36:0) after 1m0s
[01:22:34.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:36:0)
[01:22:34.957] Timer tick, asking Buildbucket for the build status
[01:22:35.018] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:35.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:37:0) after 1m0s
[01:23:35.052] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:37:0)
[01:23:35.052] Timer tick, asking Buildbucket for the build status
[01:23:35.138] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:35.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:38:0) after 1m0s
[01:24:35.158] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:38:0)
[01:24:35.158] Timer tick, asking Buildbucket for the build status
[01:24:35.206] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:35.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:39:0) after 1m0s
[01:25:35.224] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:39:0)
[01:25:35.224] Timer tick, asking Buildbucket for the build status
[01:25:35.321] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:35.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:40:0) after 1m0s
[01:26:35.339] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:40:0)
[01:26:35.339] Timer tick, asking Buildbucket for the build status
[01:26:35.429] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:35.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:41:0) after 1m0s
[01:27:35.448] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:41:0)
[01:27:35.448] Timer tick, asking Buildbucket for the build status
[01:27:35.539] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:35.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:42:0) after 1m0s
[01:28:35.608] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:42:0)
[01:28:35.608] Timer tick, asking Buildbucket for the build status
[01:28:35.711] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:35.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:43:0) after 1m0s
[01:29:35.732] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:43:0)
[01:29:35.732] Timer tick, asking Buildbucket for the build status
[01:29:35.792] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:35.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:44:0) after 1m0s
[01:30:35.823] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:44:0)
[01:30:35.823] Timer tick, asking Buildbucket for the build status
[01:30:35.879] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:35.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:45:0) after 1m0s
[01:31:35.912] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:45:0)
[01:31:35.912] Timer tick, asking Buildbucket for the build status
[01:31:35.978] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:35.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:46:0) after 1m0s
[01:32:36.006] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:46:0)
[01:32:36.006] Timer tick, asking Buildbucket for the build status
[01:32:36.105] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:36.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:47:0) after 1m0s
[01:33:36.150] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:47:0)
[01:33:36.150] Timer tick, asking Buildbucket for the build status
[01:33:36.253] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:36.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:48:0) after 1m0s
[01:34:36.339] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:48:0)
[01:34:36.340] Timer tick, asking Buildbucket for the build status
[01:34:36.486] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:36.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:49:0) after 1m0s
[01:35:36.566] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:49:0)
[01:35:36.566] Timer tick, asking Buildbucket for the build status
[01:35:36.653] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:36.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:50:0) after 1m0s
[01:36:36.692] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:50:0)
[01:36:36.692] Timer tick, asking Buildbucket for the build status
[01:36:36.803] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:36.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:51:0) after 1m0s
[01:37:36.865] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:51:0)
[01:37:36.865] Timer tick, asking Buildbucket for the build status
[01:37:37.117] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:37.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:52:0) after 1m0s
[01:38:37.138] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:52:0)
[01:38:37.138] Timer tick, asking Buildbucket for the build status
[01:38:37.230] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:37.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:53:0) after 1m0s
[01:39:37.263] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:53:0)
[01:39:37.263] Timer tick, asking Buildbucket for the build status
[01:39:37.505] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:37.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:54:0) after 1m0s
[01:40:37.737] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:54:0)
[01:40:37.737] Timer tick, asking Buildbucket for the build status
[01:40:37.815] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:37.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:55:0) after 1m0s
[01:41:37.868] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:55:0)
[01:41:37.868] Timer tick, asking Buildbucket for the build status
[01:41:37.945] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:37.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:56:0) after 1m0s
[01:42:37.917] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:56:0)
[01:42:37.917] Timer tick, asking Buildbucket for the build status
[01:42:38.015] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:38.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:57:0) after 1m0s
[01:43:38.023] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:57:0)
[01:43:38.023] Timer tick, asking Buildbucket for the build status
[01:43:38.441] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:38.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:58:0) after 1m0s
[01:44:38.539] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:58:0)
[01:44:38.539] Timer tick, asking Buildbucket for the build status
[01:44:38.599] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:38.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:59:0) after 1m0s
[01:45:38.616] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:59:0)
[01:45:38.616] Timer tick, asking Buildbucket for the build status
[01:45:38.728] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:38.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:60:0) after 1m0s
[01:46:38.727] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:60:0)
[01:46:38.727] Timer tick, asking Buildbucket for the build status
[01:46:38.843] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:38.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:61:0) after 1m0s
[01:47:38.981] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:61:0)
[01:47:38.981] Timer tick, asking Buildbucket for the build status
[01:47:39.048] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:39.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:62:0) after 1m0s
[01:48:39.056] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:62:0)
[01:48:39.056] Timer tick, asking Buildbucket for the build status
[01:48:39.329] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:39.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:63:0) after 1m0s
[01:49:39.369] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:63:0)
[01:49:39.369] Timer tick, asking Buildbucket for the build status
[01:49:39.427] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:39.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:64:0) after 1m0s
[01:50:39.411] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:64:0)
[01:50:39.411] Timer tick, asking Buildbucket for the build status
[01:50:39.888] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:39.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:65:0) after 1m0s
[01:51:39.906] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:65:0)
[01:51:39.906] Timer tick, asking Buildbucket for the build status
[01:51:39.964] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:39.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:66:0) after 1m0s
[01:52:39.990] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:66:0)
[01:52:39.990] Timer tick, asking Buildbucket for the build status
[01:52:40.057] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:40.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:67:0) after 1m0s
[01:53:40.127] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:67:0)
[01:53:40.127] Timer tick, asking Buildbucket for the build status
[01:53:40.312] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:40.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:68:0) after 1m0s
[01:54:40.336] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:68:0)
[01:54:40.336] Timer tick, asking Buildbucket for the build status
[01:54:43.484] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:43.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:69:0) after 1m0s
[01:55:43.585] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:69:0)
[01:55:43.585] Timer tick, asking Buildbucket for the build status
[01:55:43.642] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:43.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:70:0) after 1m0s
[01:56:45.951] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:70:0)
[01:56:45.951] Timer tick, asking Buildbucket for the build status
[01:56:46.012] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:46.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:71:0) after 1m0s
[01:57:46.207] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:71:0)
[01:57:46.207] Timer tick, asking Buildbucket for the build status
[01:57:46.386] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:46.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:72:0) after 1m0s
[01:58:46.671] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:72:0)
[01:58:46.671] Timer tick, asking Buildbucket for the build status
[01:58:46.770] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:46.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:73:0) after 1m0s
[01:59:47.032] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:73:0)
[01:59:47.032] Timer tick, asking Buildbucket for the build status
[01:59:47.089] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:47.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:74:0) after 1m0s
[02:00:47.413] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:74:0)
[02:00:47.413] Timer tick, asking Buildbucket for the build status
[02:00:47.545] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:47.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:75:0) after 1m0s
[02:01:47.678] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:75:0)
[02:01:47.678] Timer tick, asking Buildbucket for the build status
[02:01:47.759] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:47.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:76:0) after 1m0s
[02:02:47.945] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:76:0)
[02:02:47.945] Timer tick, asking Buildbucket for the build status
[02:02:48.116] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:48.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:77:0) after 1m0s
[02:03:48.386] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:77:0)
[02:03:48.386] Timer tick, asking Buildbucket for the build status
[02:03:48.481] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:48.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:78:0) after 1m0s
[02:04:48.643] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:78:0)
[02:04:48.643] Timer tick, asking Buildbucket for the build status
[02:04:48.724] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:48.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:79:0) after 1m0s
[02:05:48.993] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:79:0)
[02:05:48.993] Timer tick, asking Buildbucket for the build status
[02:05:49.067] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:49.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:80:0) after 1m0s
[02:06:49.444] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:80:0)
[02:06:49.444] Timer tick, asking Buildbucket for the build status
[02:06:49.503] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:49.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:81:0) after 1m0s
[02:07:49.560] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:81:0)
[02:07:49.560] Timer tick, asking Buildbucket for the build status
[02:07:49.695] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:49.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:82:0) after 1m0s
[02:08:49.827] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:82:0)
[02:08:49.827] Timer tick, asking Buildbucket for the build status
[02:08:50.293] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:50.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:83:0) after 1m0s
[02:09:50.398] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:83:0)
[02:09:50.398] Timer tick, asking Buildbucket for the build status
[02:09:50.492] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:50.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:84:0) after 1m0s
[02:10:50.527] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:84:0)
[02:10:50.527] Timer tick, asking Buildbucket for the build status
[02:10:50.580] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:50.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:85:0) after 1m0s
[02:11:50.797] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:85:0)
[02:11:50.797] Timer tick, asking Buildbucket for the build status
[02:11:51.296] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:51.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:86:0) after 1m0s
[02:12:51.369] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:86:0)
[02:12:51.369] Timer tick, asking Buildbucket for the build status
[02:12:51.428] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:51.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:87:0) after 1m0s
[02:13:51.536] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:87:0)
[02:13:51.536] Timer tick, asking Buildbucket for the build status
[02:13:51.627] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:51.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:88:0) after 1m0s
[02:14:51.828] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:88:0)
[02:14:51.828] Timer tick, asking Buildbucket for the build status
[02:14:51.894] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:51.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:89:0) after 1m0s
[02:15:52.076] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:89:0)
[02:15:52.076] Timer tick, asking Buildbucket for the build status
[02:15:52.214] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:52.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:90:0) after 1m0s
[02:16:52.301] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:90:0)
[02:16:52.301] Timer tick, asking Buildbucket for the build status
[02:16:52.367] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:52.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:91:0) after 1m0s
[02:17:52.350] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:91:0)
[02:17:52.350] Timer tick, asking Buildbucket for the build status
[02:17:52.594] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:52.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:92:0) after 1m0s
[02:18:52.631] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:92:0)
[02:18:52.631] Timer tick, asking Buildbucket for the build status
[02:18:53.104] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:53.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:93:0) after 1m0s
[02:19:53.212] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:93:0)
[02:19:53.212] Timer tick, asking Buildbucket for the build status
[02:19:53.291] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:53.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:94:0) after 1m0s
[02:20:53.359] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:94:0)
[02:20:53.359] Timer tick, asking Buildbucket for the build status
[02:20:53.420] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:53.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:95:0) after 1m0s
[02:21:53.444] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:95:0)
[02:21:53.444] Timer tick, asking Buildbucket for the build status
[02:21:53.882] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:53.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:96:0) after 1m0s
[02:22:54.029] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:96:0)
[02:22:54.029] Timer tick, asking Buildbucket for the build status
[02:22:54.108] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:54.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:97:0) after 1m0s
[02:23:54.283] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:97:0)
[02:23:54.283] Timer tick, asking Buildbucket for the build status
[02:23:54.407] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:54.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:98:0) after 1m0s
[02:24:54.427] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:98:0)
[02:24:54.427] Timer tick, asking Buildbucket for the build status
[02:24:54.482] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:54.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:99:0) after 1m0s
[02:25:54.699] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:99:0)
[02:25:54.699] Timer tick, asking Buildbucket for the build status
[02:25:54.766] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:54.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:100:0) after 1m0s
[02:26:54.948] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:100:0)
[02:26:54.948] Timer tick, asking Buildbucket for the build status
[02:26:55.015] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:55.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:101:0) after 1m0s
[02:27:55.097] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:101:0)
[02:27:55.097] Timer tick, asking Buildbucket for the build status
[02:27:55.307] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:55.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:102:0) after 1m0s
[02:28:55.362] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:102:0)
[02:28:55.362] Timer tick, asking Buildbucket for the build status
[02:28:55.696] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:55.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:103:0) after 1m0s
[02:29:55.867] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:103:0)
[02:29:55.867] Timer tick, asking Buildbucket for the build status
[02:29:55.920] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:55.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:104:0) after 1m0s
[02:30:55.996] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:104:0)
[02:30:55.996] Timer tick, asking Buildbucket for the build status
[02:30:56.052] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:56.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:105:0) after 1m0s
[02:31:56.078] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:105:0)
[02:31:56.078] Timer tick, asking Buildbucket for the build status
[02:31:56.136] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:56.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:106:0) after 1m0s
[02:32:56.203] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:106:0)
[02:32:56.203] Timer tick, asking Buildbucket for the build status
[02:32:56.257] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:56.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:107:0) after 1m0s
[02:33:56.241] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:107:0)
[02:33:56.241] Timer tick, asking Buildbucket for the build status
[02:33:56.332] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:56.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:108:0) after 1m0s
[02:34:56.401] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:108:0)
[02:34:56.401] Timer tick, asking Buildbucket for the build status
[02:34:56.697] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:56.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:109:0) after 1m0s
[02:35:56.672] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:109:0)
[02:35:56.672] Timer tick, asking Buildbucket for the build status
[02:35:56.736] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:56.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:110:0) after 1m0s
[02:36:56.774] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:110:0)
[02:36:56.774] Timer tick, asking Buildbucket for the build status
[02:36:56.833] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:56.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:111:0) after 1m0s
[02:37:56.915] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:111:0)
[02:37:56.915] Timer tick, asking Buildbucket for the build status
[02:37:56.989] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:56.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:112:0) after 1m0s
[02:38:57.020] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:112:0)
[02:38:57.020] Timer tick, asking Buildbucket for the build status
[02:38:57.192] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:57.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:113:0) after 1m0s
[02:39:57.241] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:113:0)
[02:39:57.241] Timer tick, asking Buildbucket for the build status
[02:39:57.709] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:57.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:114:0) after 1m0s
[02:40:57.797] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:114:0)
[02:40:57.797] Timer tick, asking Buildbucket for the build status
[02:40:57.928] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:57.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:115:0) after 1m0s
[02:41:58.033] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:115:0)
[02:41:58.033] Timer tick, asking Buildbucket for the build status
[02:41:58.104] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:58.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:116:0) after 1m0s
[02:42:58.174] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:116:0)
[02:42:58.174] Timer tick, asking Buildbucket for the build status
[02:42:58.229] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:58.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:117:0) after 1m0s
[02:43:58.298] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:117:0)
[02:43:58.298] Timer tick, asking Buildbucket for the build status
[02:43:58.408] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:58.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:118:0) after 1m0s
[02:44:58.503] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:118:0)
[02:44:58.505] Timer tick, asking Buildbucket for the build status
[02:44:58.985] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:58.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:119:0) after 1m0s
[02:45:59.015] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:119:0)
[02:45:59.015] Timer tick, asking Buildbucket for the build status
[02:45:59.070] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:59.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:120:0) after 1m0s
[02:46:59.159] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:120:0)
[02:46:59.159] Timer tick, asking Buildbucket for the build status
[02:46:59.234] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:59.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:121:0) after 1m0s
[02:47:59.258] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:121:0)
[02:47:59.258] Timer tick, asking Buildbucket for the build status
[02:47:59.363] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:59.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:122:0) after 1m0s
[02:48:59.390] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:122:0)
[02:48:59.390] Timer tick, asking Buildbucket for the build status
[02:48:59.494] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:59.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:123:0) after 1m0s
[02:49:59.519] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:123:0)
[02:49:59.519] Timer tick, asking Buildbucket for the build status
[02:49:59.934] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:59.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:124:0) after 1m0s
[02:50:59.989] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:124:0)
[02:50:59.989] Timer tick, asking Buildbucket for the build status
[02:51:00.299] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:00.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:125:0) after 1m0s
[02:52:00.328] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:125:0)
[02:52:00.328] Timer tick, asking Buildbucket for the build status
[02:52:00.439] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:00.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:126:0) after 1m0s
[02:53:00.475] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:126:0)
[02:53:00.475] Timer tick, asking Buildbucket for the build status
[02:53:00.571] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:00.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:127:0) after 1m0s
[02:54:01.251] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:127:0)
[02:54:01.251] Timer tick, asking Buildbucket for the build status
[02:54:01.363] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:01.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:128:0) after 1m0s
[02:55:01.394] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:128:0)
[02:55:01.394] Timer tick, asking Buildbucket for the build status
[02:55:01.456] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:01.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:129:0) after 1m0s
[02:56:01.543] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:129:0)
[02:56:01.543] Timer tick, asking Buildbucket for the build status
[02:56:01.789] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:01.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:130:0) after 1m0s
[02:57:01.841] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:130:0)
[02:57:01.841] Timer tick, asking Buildbucket for the build status
[02:57:01.916] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:01.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:131:0) after 1m0s
[02:58:01.937] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:131:0)
[02:58:01.937] Timer tick, asking Buildbucket for the build status
[02:58:01.987] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:01.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:132:0) after 1m0s
[02:59:02.013] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:132:0)
[02:59:02.013] Timer tick, asking Buildbucket for the build status
[02:59:02.111] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:02.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:133:0) after 1m0s
[03:00:02.158] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:133:0)
[03:00:02.158] Timer tick, asking Buildbucket for the build status
[03:00:02.228] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:02.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:134:0) after 1m0s
[03:01:02.394] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:134:0)
[03:01:02.394] Timer tick, asking Buildbucket for the build status
[03:01:02.512] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:02.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:135:0) after 1m0s
[03:02:02.620] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:135:0)
[03:02:02.620] Timer tick, asking Buildbucket for the build status
[03:02:02.692] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:02.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:136:0) after 1m0s
[03:03:02.742] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:136:0)
[03:03:02.742] Timer tick, asking Buildbucket for the build status
[03:03:02.828] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:02.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:137:0) after 1m0s
[03:04:02.980] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:137:0)
[03:04:02.980] Timer tick, asking Buildbucket for the build status
[03:04:03.973] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:03.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:138:0) after 1m0s
[03:05:04.050] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:138:0)
[03:05:04.050] Timer tick, asking Buildbucket for the build status
[03:05:04.118] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:04.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:139:0) after 1m0s
[03:06:04.143] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:139:0)
[03:06:04.143] Timer tick, asking Buildbucket for the build status
[03:06:04.202] Build 8859078589192161344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:04.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024508651209903184:140:0) after 1m0s
[03:07:02.631] Received PubSub notification, asking Buildbucket for the build status
[03:07:02.726] Build 8859078589192161344: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:07:02.726] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1609729622107783",
"created_by": "project:chromium",
"created_ts": "1609721304670546",
"id": "8859078589192161344",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4e761f875ad0a94bb9866f5cc13a17bc1fa552ff\"}}",
"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\": \"4e761f875ad0a94bb9866f5cc13a17bc1fa552ff\", \"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-1-qsxx\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1302, \"got_angle_revision\": \"bf02c31bec07a549cc8eea26ad0e63143954643d\", \"got_dawn_revision\": \"86980018b3f2786aeb5ded1220ec94bc927fa6b2\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"4e761f875ad0a94bb9866f5cc13a17bc1fa552ff\", \"got_revision_cp\": \"refs/heads/master@{#839991}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"ff29e249d3179766a40c9486183559fe3e5c1456\", \"got_v8_revision\": \"a5786bb381dd6ae4d1b936320a5fc00481566700\", \"got_v8_revision_cp\": \"refs/heads/8.9.202@{#1}\", \"got_webrtc_revision\": \"8673643bc2e357ca7b6dc01c0018ed05d20ffd72\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32900}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4e761f875ad0a94bb9866f5cc13a17bc1fa552ff\"}, \"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-1-qsxx\"], \"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": "1609721344222599",
"status": "COMPLETED",
"status_changed_ts": "1609729622385062",
"tags": [
"build_address:luci.chromium.ci/win-official/1302",
"builder:win-official",
"buildset:commit/git/4e761f875ad0a94bb9866f5cc13a17bc1fa552ff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4e761f875ad0a94bb9866f5cc13a17bc1fa552ff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024508651209903184",
"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/8859078589192161344/+/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:50e3af6162e16510",
"user_agent:luci-scheduler"
],
"updated_ts": "1609729622389772",
"url": "https://ci.chromium.org/b/8859078589192161344",
"utcnow_ts": "1609729622712493"
}
}
[03:07:02.726] Invocation finished in 2h18m39.362655536s with status SUCCEEDED