[01:48:55.420] New invocation is queued and will start shortly
[01:48:56.667] Starting the invocation (attempt 1)
[01:48:56.711] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:48:56.711] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:48:56.711] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9034832896921205152",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"103d4b4940bcdebcb967e5b15b0fe57f28b23054\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9034832896921205152",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/103d4b4940bcdebcb967e5b15b0fe57f28b23054",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/103d4b4940bcdebcb967e5b15b0fe57f28b23054",
"gitiles_ref:refs/heads/master"
]
}
[01:48:58.047] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1599875336801031",
"id": "8869402834796508656",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"103d4b4940bcdebcb967e5b15b0fe57f28b23054\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1599875337690731",
"tags": [
"build_address:luci.chromium.ci/win32-official/513",
"builder:win32-official",
"buildset:commit/git/103d4b4940bcdebcb967e5b15b0fe57f28b23054",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/103d4b4940bcdebcb967e5b15b0fe57f28b23054",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034832896921205152",
"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/8869402834796508656/+/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": "1599875337690855",
"url": "https://ci.chromium.org/b/8869402834796508656",
"utcnow_ts": "1599875337996123"
}
}
[01:48:58.047] Task URL: https://ci.chromium.org/b/8869402834796508656
[01:48:58.047] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:2:0) after 1m0s
[01:49:58.133] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:2:0)
[01:49:58.133] Timer tick, asking Buildbucket for the build status
[01:49:58.221] Build 8869402834796508656: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:49:58.221] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:3:0) after 1m0s
[01:50:20.255] Received PubSub notification, asking Buildbucket for the build status
[01:50:20.484] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:58.254] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:3:0)
[01:50:58.254] Timer tick, asking Buildbucket for the build status
[01:50:58.335] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:58.335] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:5:0) after 1m0s
[01:51:58.450] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:5:0)
[01:51:58.450] Timer tick, asking Buildbucket for the build status
[01:51:58.547] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:58.547] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:6:0) after 1m0s
[01:52:58.726] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:6:0)
[01:52:58.726] Timer tick, asking Buildbucket for the build status
[01:52:58.803] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:58.803] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:7:0) after 1m0s
[01:53:59.025] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:7:0)
[01:53:59.025] Timer tick, asking Buildbucket for the build status
[01:53:59.131] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:59.131] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:8:0) after 1m0s
[01:54:59.292] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:8:0)
[01:54:59.292] Timer tick, asking Buildbucket for the build status
[01:54:59.382] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:59.382] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:9:0) after 1m0s
[01:55:59.427] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:9:0)
[01:55:59.427] Timer tick, asking Buildbucket for the build status
[01:55:59.515] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:59.515] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:10:0) after 1m0s
[01:56:59.690] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:10:0)
[01:56:59.690] Timer tick, asking Buildbucket for the build status
[01:56:59.979] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:59.979] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:11:0) after 1m0s
[01:58:00.038] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:11:0)
[01:58:00.038] Timer tick, asking Buildbucket for the build status
[01:58:00.327] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:00.327] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:12:0) after 1m0s
[01:59:00.492] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:12:0)
[01:59:00.492] Timer tick, asking Buildbucket for the build status
[01:59:00.597] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:00.597] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:13:0) after 1m0s
[02:00:00.854] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:13:0)
[02:00:00.854] Timer tick, asking Buildbucket for the build status
[02:00:00.933] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:00.933] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:14:0) after 1m0s
[02:01:01.798] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:14:0)
[02:01:01.798] Timer tick, asking Buildbucket for the build status
[02:01:02.011] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:02.011] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:15:0) after 1m0s
[02:02:02.323] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:15:0)
[02:02:02.323] Timer tick, asking Buildbucket for the build status
[02:02:02.494] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:02.494] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:16:0) after 1m0s
[02:03:02.625] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:16:0)
[02:03:02.625] Timer tick, asking Buildbucket for the build status
[02:03:02.793] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:02.793] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:17:0) after 1m0s
[02:04:02.899] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:17:0)
[02:04:02.899] Timer tick, asking Buildbucket for the build status
[02:04:03.078] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:03.078] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:18:0) after 1m0s
[02:05:03.217] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:18:0)
[02:05:03.217] Timer tick, asking Buildbucket for the build status
[02:05:03.702] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:03.702] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:19:0) after 1m0s
[02:06:03.704] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:19:0)
[02:06:03.704] Timer tick, asking Buildbucket for the build status
[02:06:03.826] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:03.826] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:20:0) after 1m0s
[02:07:03.892] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:20:0)
[02:07:03.892] Timer tick, asking Buildbucket for the build status
[02:07:04.185] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:04.185] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:21:0) after 1m0s
[02:08:04.219] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:21:0)
[02:08:04.219] Timer tick, asking Buildbucket for the build status
[02:08:04.275] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:04.275] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:22:0) after 1m0s
[02:09:04.567] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:22:0)
[02:09:04.567] Timer tick, asking Buildbucket for the build status
[02:09:04.676] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:04.676] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:23:0) after 1m0s
[02:10:04.787] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:23:0)
[02:10:04.787] Timer tick, asking Buildbucket for the build status
[02:10:04.888] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:04.888] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:24:0) after 1m0s
[02:11:05.286] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:24:0)
[02:11:05.286] Timer tick, asking Buildbucket for the build status
[02:11:05.345] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:05.345] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:25:0) after 1m0s
[02:12:05.507] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:25:0)
[02:12:05.507] Timer tick, asking Buildbucket for the build status
[02:12:05.596] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:05.596] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:26:0) after 1m0s
[02:13:05.746] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:26:0)
[02:13:05.746] Timer tick, asking Buildbucket for the build status
[02:13:06.099] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:06.099] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:27:0) after 1m0s
[02:14:06.094] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:27:0)
[02:14:06.094] Timer tick, asking Buildbucket for the build status
[02:14:06.314] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:06.314] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:28:0) after 1m0s
[02:15:06.401] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:28:0)
[02:15:06.401] Timer tick, asking Buildbucket for the build status
[02:15:06.530] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:06.530] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:29:0) after 1m0s
[02:16:06.702] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:29:0)
[02:16:06.702] Timer tick, asking Buildbucket for the build status
[02:16:07.138] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:07.138] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:30:0) after 1m0s
[02:17:07.375] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:30:0)
[02:17:07.375] Timer tick, asking Buildbucket for the build status
[02:17:07.576] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:07.577] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:31:0) after 1m0s
[02:18:07.606] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:31:0)
[02:18:07.606] Timer tick, asking Buildbucket for the build status
[02:18:07.884] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:07.884] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:32:0) after 1m0s
[02:19:08.012] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:32:0)
[02:19:08.012] Timer tick, asking Buildbucket for the build status
[02:19:08.127] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:08.127] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:33:0) after 1m0s
[02:20:08.201] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:33:0)
[02:20:08.201] Timer tick, asking Buildbucket for the build status
[02:20:08.302] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:08.302] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:34:0) after 1m0s
[02:21:08.515] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:34:0)
[02:21:08.515] Timer tick, asking Buildbucket for the build status
[02:21:08.598] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:08.598] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:35:0) after 1m0s
[02:22:08.723] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:35:0)
[02:22:08.723] Timer tick, asking Buildbucket for the build status
[02:22:08.904] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:08.904] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:36:0) after 1m0s
[02:23:08.961] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:36:0)
[02:23:08.961] Timer tick, asking Buildbucket for the build status
[02:23:09.082] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:09.082] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:37:0) after 1m0s
[02:24:09.144] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:37:0)
[02:24:09.144] Timer tick, asking Buildbucket for the build status
[02:24:09.408] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:09.408] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:38:0) after 1m0s
[02:25:09.494] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:38:0)
[02:25:09.494] Timer tick, asking Buildbucket for the build status
[02:25:09.579] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:09.579] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:39:0) after 1m0s
[02:26:09.613] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:39:0)
[02:26:09.613] Timer tick, asking Buildbucket for the build status
[02:26:10.504] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:10.504] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:40:0) after 1m0s
[02:27:10.625] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:40:0)
[02:27:10.625] Timer tick, asking Buildbucket for the build status
[02:27:11.010] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:11.010] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:41:0) after 1m0s
[02:28:11.076] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:41:0)
[02:28:11.076] Timer tick, asking Buildbucket for the build status
[02:28:11.235] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:11.235] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:42:0) after 1m0s
[02:29:11.260] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:42:0)
[02:29:11.260] Timer tick, asking Buildbucket for the build status
[02:29:11.398] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:11.398] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:43:0) after 1m0s
[02:30:11.452] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:43:0)
[02:30:11.452] Timer tick, asking Buildbucket for the build status
[02:30:11.521] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:11.521] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:44:0) after 1m0s
[02:31:11.628] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:44:0)
[02:31:11.628] Timer tick, asking Buildbucket for the build status
[02:31:11.858] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:11.858] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:45:0) after 1m0s
[02:32:11.956] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:45:0)
[02:32:11.956] Timer tick, asking Buildbucket for the build status
[02:32:12.080] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:12.081] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:46:0) after 1m0s
[02:33:12.075] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:46:0)
[02:33:12.075] Timer tick, asking Buildbucket for the build status
[02:33:12.185] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:12.185] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:47:0) after 1m0s
[02:34:12.290] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:47:0)
[02:34:12.290] Timer tick, asking Buildbucket for the build status
[02:34:12.407] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:12.407] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:48:0) after 1m0s
[02:35:12.472] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:48:0)
[02:35:12.472] Timer tick, asking Buildbucket for the build status
[02:35:13.204] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:13.204] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:49:0) after 1m0s
[02:36:13.237] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:49:0)
[02:36:13.237] Timer tick, asking Buildbucket for the build status
[02:36:13.294] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:13.294] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:50:0) after 1m0s
[02:37:13.371] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:50:0)
[02:37:13.371] Timer tick, asking Buildbucket for the build status
[02:37:13.428] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:13.428] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:51:0) after 1m0s
[02:38:13.453] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:51:0)
[02:38:13.453] Timer tick, asking Buildbucket for the build status
[02:38:13.501] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:13.501] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:52:0) after 1m0s
[02:39:13.533] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:52:0)
[02:39:13.533] Timer tick, asking Buildbucket for the build status
[02:39:13.640] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:13.640] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:53:0) after 1m0s
[02:40:13.672] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:53:0)
[02:40:13.672] Timer tick, asking Buildbucket for the build status
[02:40:13.752] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:13.752] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:54:0) after 1m0s
[02:41:13.763] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:54:0)
[02:41:13.763] Timer tick, asking Buildbucket for the build status
[02:41:14.014] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:14.014] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:55:0) after 1m0s
[02:42:14.106] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:55:0)
[02:42:14.106] Timer tick, asking Buildbucket for the build status
[02:42:14.328] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:14.328] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:56:0) after 1m0s
[02:43:14.329] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:56:0)
[02:43:14.329] Timer tick, asking Buildbucket for the build status
[02:43:14.415] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:14.415] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:57:0) after 1m0s
[02:44:14.453] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:57:0)
[02:44:14.453] Timer tick, asking Buildbucket for the build status
[02:44:14.598] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:14.598] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:58:0) after 1m0s
[02:45:14.659] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:58:0)
[02:45:14.659] Timer tick, asking Buildbucket for the build status
[02:45:14.723] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:14.723] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:59:0) after 1m0s
[02:46:14.792] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:59:0)
[02:46:14.792] Timer tick, asking Buildbucket for the build status
[02:46:14.925] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:14.925] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:60:0) after 1m0s
[02:47:14.999] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:60:0)
[02:47:14.999] Timer tick, asking Buildbucket for the build status
[02:47:15.067] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:15.067] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:61:0) after 1m0s
[02:48:15.148] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:61:0)
[02:48:15.149] Timer tick, asking Buildbucket for the build status
[02:48:15.511] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:15.511] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:62:0) after 1m0s
[02:49:15.531] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:62:0)
[02:49:15.531] Timer tick, asking Buildbucket for the build status
[02:49:15.800] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:15.800] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:63:0) after 1m0s
[02:50:15.872] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:63:0)
[02:50:15.872] Timer tick, asking Buildbucket for the build status
[02:50:15.946] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:15.946] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:64:0) after 1m0s
[02:51:16.009] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:64:0)
[02:51:16.009] Timer tick, asking Buildbucket for the build status
[02:51:16.215] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:16.215] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:65:0) after 1m0s
[02:52:16.252] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:65:0)
[02:52:16.252] Timer tick, asking Buildbucket for the build status
[02:52:16.412] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:16.412] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:66:0) after 1m0s
[02:53:16.428] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:66:0)
[02:53:16.428] Timer tick, asking Buildbucket for the build status
[02:53:16.495] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:16.495] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:67:0) after 1m0s
[02:54:16.627] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:67:0)
[02:54:16.627] Timer tick, asking Buildbucket for the build status
[02:54:16.834] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:16.834] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:68:0) after 1m0s
[02:55:16.885] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:68:0)
[02:55:16.885] Timer tick, asking Buildbucket for the build status
[02:55:17.004] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:17.004] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:69:0) after 1m0s
[02:56:17.071] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:69:0)
[02:56:17.071] Timer tick, asking Buildbucket for the build status
[02:56:17.143] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:17.143] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:70:0) after 1m0s
[02:57:17.246] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:70:0)
[02:57:17.246] Timer tick, asking Buildbucket for the build status
[02:57:17.578] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:17.578] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:71:0) after 1m0s
[02:58:17.674] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:71:0)
[02:58:17.674] Timer tick, asking Buildbucket for the build status
[02:58:17.806] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:17.806] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:72:0) after 1m0s
[02:59:17.874] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:72:0)
[02:59:17.874] Timer tick, asking Buildbucket for the build status
[02:59:18.024] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:18.024] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:73:0) after 1m0s
[03:00:18.042] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:73:0)
[03:00:18.042] Timer tick, asking Buildbucket for the build status
[03:00:18.127] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:18.127] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:74:0) after 1m0s
[03:01:18.280] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:74:0)
[03:01:18.280] Timer tick, asking Buildbucket for the build status
[03:01:18.363] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:18.363] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:75:0) after 1m0s
[03:02:18.655] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:75:0)
[03:02:18.655] Timer tick, asking Buildbucket for the build status
[03:02:18.936] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:18.936] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:76:0) after 1m0s
[03:03:19.015] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:76:0)
[03:03:19.015] Timer tick, asking Buildbucket for the build status
[03:03:19.072] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:19.072] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:77:0) after 1m0s
[03:04:19.124] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:77:0)
[03:04:19.124] Timer tick, asking Buildbucket for the build status
[03:04:19.329] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:19.329] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:78:0) after 1m0s
[03:05:19.455] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:78:0)
[03:05:19.455] Timer tick, asking Buildbucket for the build status
[03:05:19.588] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:19.588] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:79:0) after 1m0s
[03:06:19.729] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:79:0)
[03:06:19.729] Timer tick, asking Buildbucket for the build status
[03:06:19.808] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:19.808] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:80:0) after 1m0s
[03:07:19.981] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:80:0)
[03:07:19.981] Timer tick, asking Buildbucket for the build status
[03:07:20.068] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:20.068] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:81:0) after 1m0s
[03:08:20.130] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:81:0)
[03:08:20.130] Timer tick, asking Buildbucket for the build status
[03:08:20.205] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:20.205] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:82:0) after 1m0s
[03:09:20.268] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:82:0)
[03:09:20.268] Timer tick, asking Buildbucket for the build status
[03:09:20.437] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:20.437] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:83:0) after 1m0s
[03:10:20.555] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:83:0)
[03:10:20.555] Timer tick, asking Buildbucket for the build status
[03:10:20.808] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:20.808] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:84:0) after 1m0s
[03:11:20.890] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:84:0)
[03:11:20.890] Timer tick, asking Buildbucket for the build status
[03:11:20.951] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:20.951] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:85:0) after 1m0s
[03:12:21.030] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:85:0)
[03:12:21.030] Timer tick, asking Buildbucket for the build status
[03:12:21.213] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:21.213] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:86:0) after 1m0s
[03:13:21.251] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:86:0)
[03:13:21.251] Timer tick, asking Buildbucket for the build status
[03:13:21.310] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:21.310] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:87:0) after 1m0s
[03:14:21.361] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:87:0)
[03:14:21.361] Timer tick, asking Buildbucket for the build status
[03:14:21.758] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:21.758] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:88:0) after 1m0s
[03:15:21.847] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:88:0)
[03:15:21.847] Timer tick, asking Buildbucket for the build status
[03:15:22.219] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:22.219] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:89:0) after 1m0s
[03:16:22.211] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:89:0)
[03:16:22.211] Timer tick, asking Buildbucket for the build status
[03:16:22.298] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:22.298] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:90:0) after 1m0s
[03:17:22.334] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:90:0)
[03:17:22.334] Timer tick, asking Buildbucket for the build status
[03:17:22.426] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:22.426] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:91:0) after 1m0s
[03:18:22.459] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:91:0)
[03:18:22.459] Timer tick, asking Buildbucket for the build status
[03:18:22.875] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:22.875] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:92:0) after 1m0s
[03:19:22.937] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:92:0)
[03:19:22.937] Timer tick, asking Buildbucket for the build status
[03:19:23.056] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:23.056] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:93:0) after 1m0s
[03:20:23.089] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:93:0)
[03:20:23.089] Timer tick, asking Buildbucket for the build status
[03:20:23.167] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:23.167] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:94:0) after 1m0s
[03:21:23.198] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:94:0)
[03:21:23.198] Timer tick, asking Buildbucket for the build status
[03:21:23.421] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:23.421] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:95:0) after 1m0s
[03:22:23.430] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:95:0)
[03:22:23.430] Timer tick, asking Buildbucket for the build status
[03:22:23.513] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:23.513] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:96:0) after 1m0s
[03:23:23.555] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:96:0)
[03:23:23.555] Timer tick, asking Buildbucket for the build status
[03:23:23.632] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:23.632] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:97:0) after 1m0s
[03:24:23.737] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:97:0)
[03:24:23.737] Timer tick, asking Buildbucket for the build status
[03:24:23.862] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:23.862] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:98:0) after 1m0s
[03:25:23.904] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:98:0)
[03:25:23.904] Timer tick, asking Buildbucket for the build status
[03:25:24.012] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:24.012] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:99:0) after 1m0s
[03:26:24.042] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:99:0)
[03:26:24.042] Timer tick, asking Buildbucket for the build status
[03:26:24.239] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:24.239] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:100:0) after 1m0s
[03:27:24.287] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:100:0)
[03:27:24.287] Timer tick, asking Buildbucket for the build status
[03:27:24.507] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:24.507] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:101:0) after 1m0s
[03:28:24.516] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:101:0)
[03:28:24.516] Timer tick, asking Buildbucket for the build status
[03:28:24.579] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:24.579] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:102:0) after 1m0s
[03:29:24.652] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:102:0)
[03:29:24.652] Timer tick, asking Buildbucket for the build status
[03:29:24.959] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:24.959] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:103:0) after 1m0s
[03:30:25.026] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:103:0)
[03:30:25.026] Timer tick, asking Buildbucket for the build status
[03:30:25.087] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:25.087] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:104:0) after 1m0s
[03:31:25.142] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:104:0)
[03:31:25.142] Timer tick, asking Buildbucket for the build status
[03:31:25.221] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:25.221] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:105:0) after 1m0s
[03:32:25.468] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:105:0)
[03:32:25.468] Timer tick, asking Buildbucket for the build status
[03:32:25.541] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:25.541] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:106:0) after 1m0s
[03:33:25.679] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:106:0)
[03:33:25.679] Timer tick, asking Buildbucket for the build status
[03:33:25.805] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:25.805] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:107:0) after 1m0s
[03:34:26.004] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:107:0)
[03:34:26.004] Timer tick, asking Buildbucket for the build status
[03:34:26.179] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:26.179] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:108:0) after 1m0s
[03:35:26.274] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:108:0)
[03:35:26.275] Timer tick, asking Buildbucket for the build status
[03:35:26.487] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:26.487] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:109:0) after 1m0s
[03:36:26.526] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:109:0)
[03:36:26.526] Timer tick, asking Buildbucket for the build status
[03:36:26.583] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:26.583] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:110:0) after 1m0s
[03:37:26.650] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:110:0)
[03:37:26.650] Timer tick, asking Buildbucket for the build status
[03:37:26.725] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:26.725] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:111:0) after 1m0s
[03:38:26.810] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:111:0)
[03:38:26.810] Timer tick, asking Buildbucket for the build status
[03:38:27.092] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:27.092] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:112:0) after 1m0s
[03:39:27.217] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:112:0)
[03:39:27.217] Timer tick, asking Buildbucket for the build status
[03:39:27.406] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:27.406] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:113:0) after 1m0s
[03:40:27.445] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:113:0)
[03:40:27.445] Timer tick, asking Buildbucket for the build status
[03:40:27.507] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:27.507] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:114:0) after 1m0s
[03:41:27.571] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:114:0)
[03:41:27.571] Timer tick, asking Buildbucket for the build status
[03:41:27.778] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:27.778] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:115:0) after 1m0s
[03:42:27.876] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:115:0)
[03:42:27.876] Timer tick, asking Buildbucket for the build status
[03:42:27.946] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:27.946] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:116:0) after 1m0s
[03:43:27.985] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:116:0)
[03:43:27.985] Timer tick, asking Buildbucket for the build status
[03:43:28.048] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:28.048] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:117:0) after 1m0s
[03:44:28.066] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:117:0)
[03:44:28.066] Timer tick, asking Buildbucket for the build status
[03:44:28.169] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:28.170] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:118:0) after 1m0s
[03:45:28.231] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:118:0)
[03:45:28.231] Timer tick, asking Buildbucket for the build status
[03:45:28.308] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:28.308] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:119:0) after 1m0s
[03:46:28.386] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:119:0)
[03:46:28.386] Timer tick, asking Buildbucket for the build status
[03:46:28.504] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:28.504] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:120:0) after 1m0s
[03:47:28.550] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:120:0)
[03:47:28.550] Timer tick, asking Buildbucket for the build status
[03:47:28.687] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:28.687] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:121:0) after 1m0s
[03:48:28.822] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:121:0)
[03:48:28.822] Timer tick, asking Buildbucket for the build status
[03:48:28.889] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:28.889] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:122:0) after 1m0s
[03:49:28.949] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:122:0)
[03:49:28.949] Timer tick, asking Buildbucket for the build status
[03:49:29.089] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:29.089] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:123:0) after 1m0s
[03:50:29.150] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:123:0)
[03:50:29.150] Timer tick, asking Buildbucket for the build status
[03:50:29.378] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:29.378] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:124:0) after 1m0s
[03:51:29.423] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:124:0)
[03:51:29.423] Timer tick, asking Buildbucket for the build status
[03:51:29.481] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:29.481] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:125:0) after 1m0s
[03:52:29.548] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:125:0)
[03:52:29.548] Timer tick, asking Buildbucket for the build status
[03:52:29.604] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:29.604] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:126:0) after 1m0s
[03:53:29.641] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:126:0)
[03:53:29.641] Timer tick, asking Buildbucket for the build status
[03:53:29.764] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:29.764] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:127:0) after 1m0s
[03:54:29.897] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:127:0)
[03:54:29.897] Timer tick, asking Buildbucket for the build status
[03:54:30.005] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:30.005] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:128:0) after 1m0s
[03:55:30.048] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:128:0)
[03:55:30.048] Timer tick, asking Buildbucket for the build status
[03:55:30.373] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:30.373] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:129:0) after 1m0s
[03:56:30.356] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:129:0)
[03:56:30.356] Timer tick, asking Buildbucket for the build status
[03:56:30.412] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:30.412] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:130:0) after 1m0s
[03:57:30.447] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:130:0)
[03:57:30.447] Timer tick, asking Buildbucket for the build status
[03:57:30.507] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:30.507] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:131:0) after 1m0s
[03:58:30.538] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:131:0)
[03:58:30.538] Timer tick, asking Buildbucket for the build status
[03:58:30.978] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:30.978] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:132:0) after 1m0s
[03:59:31.097] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:132:0)
[03:59:31.097] Timer tick, asking Buildbucket for the build status
[03:59:31.490] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:31.490] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:133:0) after 1m0s
[04:00:31.552] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:133:0)
[04:00:31.552] Timer tick, asking Buildbucket for the build status
[04:00:31.978] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:31.978] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:134:0) after 1m0s
[04:01:32.040] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:134:0)
[04:01:32.040] Timer tick, asking Buildbucket for the build status
[04:01:32.394] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:32.394] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:135:0) after 1m0s
[04:02:32.429] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:135:0)
[04:02:32.429] Timer tick, asking Buildbucket for the build status
[04:02:32.614] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:32.614] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:136:0) after 1m0s
[04:03:32.728] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:136:0)
[04:03:32.728] Timer tick, asking Buildbucket for the build status
[04:03:33.028] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:33.028] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:137:0) after 1m0s
[04:04:33.069] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:137:0)
[04:04:33.069] Timer tick, asking Buildbucket for the build status
[04:04:33.211] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:33.211] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:138:0) after 1m0s
[04:05:33.240] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:138:0)
[04:05:33.240] Timer tick, asking Buildbucket for the build status
[04:05:33.325] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:33.325] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:139:0) after 1m0s
[04:06:33.422] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:139:0)
[04:06:33.422] Timer tick, asking Buildbucket for the build status
[04:06:33.604] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:33.604] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:140:0) after 1m0s
[04:07:33.631] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:140:0)
[04:07:33.631] Timer tick, asking Buildbucket for the build status
[04:07:33.923] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:33.923] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:141:0) after 1m0s
[04:08:34.047] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:141:0)
[04:08:34.047] Timer tick, asking Buildbucket for the build status
[04:08:34.144] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:34.144] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:142:0) after 1m0s
[04:09:34.225] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:142:0)
[04:09:34.225] Timer tick, asking Buildbucket for the build status
[04:09:34.549] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:34.549] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:143:0) after 1m0s
[04:10:34.578] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:143:0)
[04:10:34.578] Timer tick, asking Buildbucket for the build status
[04:10:34.634] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:34.634] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:144:0) after 1m0s
[04:11:34.678] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:144:0)
[04:11:34.678] Timer tick, asking Buildbucket for the build status
[04:11:34.736] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:34.736] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:145:0) after 1m0s
[04:12:34.765] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:145:0)
[04:12:34.765] Timer tick, asking Buildbucket for the build status
[04:12:35.036] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:35.036] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:146:0) after 1m0s
[04:13:35.240] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:146:0)
[04:13:35.240] Timer tick, asking Buildbucket for the build status
[04:13:35.329] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:35.329] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:147:0) after 1m0s
[04:14:35.586] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:147:0)
[04:14:35.586] Timer tick, asking Buildbucket for the build status
[04:14:35.714] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:35.714] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:148:0) after 1m0s
[04:15:35.802] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:148:0)
[04:15:35.802] Timer tick, asking Buildbucket for the build status
[04:15:35.922] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:35.922] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:149:0) after 1m0s
[04:16:35.957] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:149:0)
[04:16:35.957] Timer tick, asking Buildbucket for the build status
[04:16:36.020] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:36.020] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:150:0) after 1m0s
[04:17:36.109] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:150:0)
[04:17:36.109] Timer tick, asking Buildbucket for the build status
[04:17:36.175] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:36.175] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:151:0) after 1m0s
[04:18:36.298] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:151:0)
[04:18:36.298] Timer tick, asking Buildbucket for the build status
[04:18:36.517] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:36.517] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:152:0) after 1m0s
[04:19:36.678] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:152:0)
[04:19:36.678] Timer tick, asking Buildbucket for the build status
[04:19:37.109] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:37.109] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:153:0) after 1m0s
[04:20:37.178] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:153:0)
[04:20:37.178] Timer tick, asking Buildbucket for the build status
[04:20:37.252] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:37.252] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:154:0) after 1m0s
[04:21:37.374] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:154:0)
[04:21:37.374] Timer tick, asking Buildbucket for the build status
[04:21:37.504] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:37.504] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:155:0) after 1m0s
[04:22:37.572] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:155:0)
[04:22:37.572] Timer tick, asking Buildbucket for the build status
[04:22:37.681] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:37.681] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:156:0) after 1m0s
[04:23:37.838] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:156:0)
[04:23:37.838] Timer tick, asking Buildbucket for the build status
[04:23:37.899] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:37.899] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:157:0) after 1m0s
[04:24:38.034] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:157:0)
[04:24:38.034] Timer tick, asking Buildbucket for the build status
[04:24:38.157] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:38.157] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:158:0) after 1m0s
[04:25:38.350] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:158:0)
[04:25:38.350] Timer tick, asking Buildbucket for the build status
[04:25:38.441] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:38.442] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:159:0) after 1m0s
[04:26:38.530] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:159:0)
[04:26:38.530] Timer tick, asking Buildbucket for the build status
[04:26:38.598] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:38.598] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:160:0) after 1m0s
[04:27:38.612] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:160:0)
[04:27:38.612] Timer tick, asking Buildbucket for the build status
[04:27:38.682] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:38.682] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:161:0) after 1m0s
[04:28:38.840] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:161:0)
[04:28:38.840] Timer tick, asking Buildbucket for the build status
[04:28:39.099] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:39.099] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:162:0) after 1m0s
[04:29:39.154] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:162:0)
[04:29:39.154] Timer tick, asking Buildbucket for the build status
[04:29:39.292] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:39.292] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:163:0) after 1m0s
[04:30:39.456] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:163:0)
[04:30:39.456] Timer tick, asking Buildbucket for the build status
[04:30:39.603] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:39.603] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:164:0) after 1m0s
[04:31:39.636] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:164:0)
[04:31:39.636] Timer tick, asking Buildbucket for the build status
[04:31:39.907] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:39.907] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:165:0) after 1m0s
[04:32:39.960] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:165:0)
[04:32:39.960] Timer tick, asking Buildbucket for the build status
[04:32:40.033] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:40.033] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:166:0) after 1m0s
[04:33:40.060] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:166:0)
[04:33:40.060] Timer tick, asking Buildbucket for the build status
[04:33:40.404] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:40.404] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:167:0) after 1m0s
[04:34:40.593] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:167:0)
[04:34:40.593] Timer tick, asking Buildbucket for the build status
[04:34:40.694] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:40.694] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:168:0) after 1m0s
[04:35:41.081] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:168:0)
[04:35:41.081] Timer tick, asking Buildbucket for the build status
[04:35:41.183] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:41.183] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:169:0) after 1m0s
[04:36:41.223] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:169:0)
[04:36:41.223] Timer tick, asking Buildbucket for the build status
[04:36:41.298] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:41.298] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:170:0) after 1m0s
[04:37:41.365] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:170:0)
[04:37:41.365] Timer tick, asking Buildbucket for the build status
[04:37:41.458] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:41.458] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:171:0) after 1m0s
[04:38:41.615] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:171:0)
[04:38:41.615] Timer tick, asking Buildbucket for the build status
[04:38:41.728] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:41.728] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:172:0) after 1m0s
[04:39:42.086] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:172:0)
[04:39:42.086] Timer tick, asking Buildbucket for the build status
[04:39:43.399] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:43.399] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:173:0) after 1m0s
[04:40:43.482] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:173:0)
[04:40:43.482] Timer tick, asking Buildbucket for the build status
[04:40:43.736] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:43.736] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:174:0) after 1m0s
[04:41:43.972] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:174:0)
[04:41:43.972] Timer tick, asking Buildbucket for the build status
[04:41:44.144] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:44.144] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:175:0) after 1m0s
[04:42:44.307] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:175:0)
[04:42:44.307] Timer tick, asking Buildbucket for the build status
[04:42:44.377] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:44.377] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:176:0) after 1m0s
[04:43:44.550] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:176:0)
[04:43:44.550] Timer tick, asking Buildbucket for the build status
[04:43:44.639] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:44.639] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:177:0) after 1m0s
[04:44:44.745] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:177:0)
[04:44:44.745] Timer tick, asking Buildbucket for the build status
[04:44:44.859] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:44.859] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:178:0) after 1m0s
[04:45:44.972] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:178:0)
[04:45:44.972] Timer tick, asking Buildbucket for the build status
[04:45:46.016] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:46.016] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:179:0) after 1m0s
[04:46:46.152] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:179:0)
[04:46:46.152] Timer tick, asking Buildbucket for the build status
[04:46:46.235] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:46.235] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:180:0) after 1m0s
[04:47:46.280] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:180:0)
[04:47:46.280] Timer tick, asking Buildbucket for the build status
[04:47:46.361] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:46.361] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:181:0) after 1m0s
[04:48:46.428] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:181:0)
[04:48:46.428] Timer tick, asking Buildbucket for the build status
[04:48:46.747] Build 8869402834796508656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:46.747] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034832896921205152:182:0) after 1m0s
[04:49:30.145] Received PubSub notification, asking Buildbucket for the build status
[04:49:30.220] Build 8869402834796508656: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:49:30.220] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1599886169264258",
"created_by": "project:chromium",
"created_ts": "1599875336801031",
"id": "8869402834796508656",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"103d4b4940bcdebcb967e5b15b0fe57f28b23054\"}}",
"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\": \"103d4b4940bcdebcb967e5b15b0fe57f28b23054\", \"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-3-ucjj\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 513, \"got_angle_revision\": \"bca88739adb7e7bd82864ce620bc9c15c087c666\", \"got_dawn_revision\": \"dbd34449af20f2d026b1e7f959073e254066ec83\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"103d4b4940bcdebcb967e5b15b0fe57f28b23054\", \"got_revision_cp\": \"refs/heads/master@{#806420}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"41974f57973ebcdab4cfde6cc60354f6b79a5901\", \"got_v8_revision\": \"bb4817544d7ce327fc432a8229efa213952ca979\", \"got_v8_revision_cp\": \"refs/heads/8.7.76@{#1}\", \"got_webrtc_revision\": \"32eb03a1fbe2007a055a227e317ffea4a6afaa55\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32087}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"103d4b4940bcdebcb967e5b15b0fe57f28b23054\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_f6b49936a2965b3cdad8f63ce13313d0c4b82119a9acfffbfe199e91c26d0c88_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-3-ucjj\"], \"image\": [\"chrome-win10-1703-20083100-22dcea0839e\"], \"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\": [\"5518-246c570\"], \"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": "1599875419400035",
"status": "COMPLETED",
"status_changed_ts": "1599886169832443",
"tags": [
"build_address:luci.chromium.ci/win32-official/513",
"builder:win32-official",
"buildset:commit/git/103d4b4940bcdebcb967e5b15b0fe57f28b23054",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/103d4b4940bcdebcb967e5b15b0fe57f28b23054",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034832896921205152",
"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/8869402834796508656/+/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:4e98d1d8a5e02e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1599886169852996",
"url": "https://ci.chromium.org/b/8869402834796508656",
"utcnow_ts": "1599886170213541"
}
}
[04:49:30.221] Invocation finished in 3h0m34.829673442s with status SUCCEEDED